[12:35:17.896] <TB3> INFO: *** Welcome to pxar ***
[12:35:17.896] <TB3> INFO: *** Today: 2015/12/08
[12:35:17.986] <TB3> INFO: *** Version: 3197
[12:35:17.986] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C15.dat
[12:35:17.987] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//tbmParameters_C0b.dat
[12:35:17.988] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//defaultMaskFile.dat
[12:35:17.988] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters_C15.dat
[12:35:18.062] <TB3> INFO: clk: 4
[12:35:18.062] <TB3> INFO: ctr: 4
[12:35:18.062] <TB3> INFO: sda: 19
[12:35:18.062] <TB3> INFO: tin: 9
[12:35:18.062] <TB3> INFO: level: 15
[12:35:18.062] <TB3> INFO: triggerdelay: 0
[12:35:18.062] <TB3> QUIET: Instanciating API for pxar prod-12
[12:35:18.062] <TB3> INFO: Log level: INFO
[12:35:18.069] <TB3> INFO: Found DTB DTB_WZ4I6J
[12:35:18.078] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[12:35:18.081] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[12:35:18.083] <TB3> INFO: RPC call hashes of host and DTB match: 398089610
[12:35:19.608] <TB3> INFO: DUT info:
[12:35:19.608] <TB3> INFO: The DUT currently contains the following objects:
[12:35:19.608] <TB3> INFO: 2 TBM Cores tbm08c (2 ON)
[12:35:19.608] <TB3> INFO: TBM Core alpha (0): 7 registers set
[12:35:19.608] <TB3> INFO: TBM Core beta (1): 7 registers set
[12:35:19.608] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:35:19.609] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:19.609] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:19.609] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:19.609] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:19.609] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:19.609] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:19.609] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:19.609] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:19.609] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:19.609] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:19.609] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:19.609] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:19.609] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:19.609] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:19.609] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:19.609] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:35:20.010] <TB3> INFO: enter 'restricted' command line mode
[12:35:20.011] <TB3> INFO: enter test to run
[12:35:20.011] <TB3> INFO: test: pretest no parameter change
[12:35:20.011] <TB3> INFO: running: pretest
[12:35:20.018] <TB3> INFO: ######################################################################
[12:35:20.018] <TB3> INFO: PixTestPretest::doTest()
[12:35:20.018] <TB3> INFO: ######################################################################
[12:35:20.019] <TB3> INFO: ----------------------------------------------------------------------
[12:35:20.019] <TB3> INFO: PixTestPretest::programROC()
[12:35:20.019] <TB3> INFO: ----------------------------------------------------------------------
[12:35:38.038] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:35:38.038] <TB3> INFO: IA differences per ROC: 16.1 20.9 17.7 17.7 20.1 20.1 19.3 17.7 20.1 19.3 18.5 17.7 17.7 18.5 19.3 17.7
[12:35:38.121] <TB3> INFO: ----------------------------------------------------------------------
[12:35:38.121] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:35:38.121] <TB3> INFO: ----------------------------------------------------------------------
[12:35:45.634] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 381.9 mA = 23.8688 mA/ROC
[12:35:45.634] <TB3> INFO: i(loss) [mA/ROC]: 18.5 18.5 18.5 18.5 18.5 18.5 18.5 17.7 17.7 18.5 18.5 18.5 18.5 17.7 17.7 18.5
[12:35:45.670] <TB3> INFO: ----------------------------------------------------------------------
[12:35:45.670] <TB3> INFO: PixTestPretest::findTiming()
[12:35:45.670] <TB3> INFO: ----------------------------------------------------------------------
[12:35:45.670] <TB3> INFO: PixTestCmd::init()
[12:35:46.265] <TB3> WARNING: Not unmasking DUT, not setting Calibrate bits!
[12:37:19.716] <TB3> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[12:37:19.716] <TB3> INFO: (success/tries = 100/100), width = 4
[12:37:19.718] <TB3> INFO: ----------------------------------------------------------------------
[12:37:19.718] <TB3> INFO: PixTestPretest::findWorkingPixel()
[12:37:19.718] <TB3> INFO: ----------------------------------------------------------------------
[12:37:19.855] <TB3> INFO: Expecting 231680 events.
[12:37:24.466] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (1) != Token Chain Length (8)
[12:37:24.469] <TB3> ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[12:37:27.297] <TB3> INFO: 231680 events read in total (6727ms).
[12:37:27.302] <TB3> INFO: Test took 7582ms.
[12:37:27.694] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[12:37:27.726] <TB3> INFO: ----------------------------------------------------------------------
[12:37:27.726] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[12:37:27.726] <TB3> INFO: ----------------------------------------------------------------------
[12:37:27.864] <TB3> INFO: Expecting 231680 events.
[12:37:36.245] <TB3> INFO: 231680 events read in total (7666ms).
[12:37:36.250] <TB3> INFO: Test took 8518ms.
[12:37:36.648] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[12:37:36.648] <TB3> INFO: CalDel: 149 143 140 142 162 148 153 147 138 140 144 134 131 168 128 132
[12:37:36.648] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[12:37:36.652] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C0.dat
[12:37:36.652] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C1.dat
[12:37:36.652] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C2.dat
[12:37:36.652] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C3.dat
[12:37:36.653] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C4.dat
[12:37:36.653] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C5.dat
[12:37:36.653] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C6.dat
[12:37:36.653] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C7.dat
[12:37:36.653] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C8.dat
[12:37:36.654] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C9.dat
[12:37:36.654] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C10.dat
[12:37:36.654] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C11.dat
[12:37:36.654] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C12.dat
[12:37:36.654] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C13.dat
[12:37:36.655] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C14.dat
[12:37:36.655] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters_C15.dat
[12:37:36.655] <TB3> INFO: write tbm parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//tbmParameters_C0a.dat
[12:37:36.655] <TB3> INFO: write tbm parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//tbmParameters_C0b.dat
[12:37:36.655] <TB3> INFO: PixTestPretest::doTest() done, duration: 136 seconds
[12:37:36.721] <TB3> INFO: enter test to run
[12:37:36.721] <TB3> INFO: test: fulltest no parameter change
[12:37:36.721] <TB3> INFO: running: fulltest
[12:37:36.721] <TB3> INFO: ######################################################################
[12:37:36.721] <TB3> INFO: PixTestFullTest::doTest()
[12:37:36.721] <TB3> INFO: ######################################################################
[12:37:36.722] <TB3> INFO: ######################################################################
[12:37:36.722] <TB3> INFO: PixTestAlive::doTest()
[12:37:36.722] <TB3> INFO: ######################################################################
[12:37:36.724] <TB3> INFO: ----------------------------------------------------------------------
[12:37:36.724] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:37:36.724] <TB3> INFO: ----------------------------------------------------------------------
[12:37:37.034] <TB3> INFO: Expecting 41600 events.
[12:37:41.386] <TB3> INFO: 41600 events read in total (3637ms).
[12:37:41.386] <TB3> INFO: Test took 4661ms.
[12:37:41.393] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:37:41.773] <TB3> INFO: PixTestAlive::aliveTest() done
[12:37:41.773] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 1 0 0 0 0 0 0 0 0
[12:37:41.775] <TB3> INFO: ----------------------------------------------------------------------
[12:37:41.775] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:37:41.775] <TB3> INFO: ----------------------------------------------------------------------
[12:37:42.102] <TB3> INFO: Expecting 41600 events.
[12:37:45.065] <TB3> INFO: 41600 events read in total (2248ms).
[12:37:45.065] <TB3> INFO: Test took 3287ms.
[12:37:45.065] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:37:45.066] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:37:45.467] <TB3> INFO: PixTestAlive::maskTest() done
[12:37:45.467] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:37:45.469] <TB3> INFO: ----------------------------------------------------------------------
[12:37:45.469] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:37:45.469] <TB3> INFO: ----------------------------------------------------------------------
[12:37:45.813] <TB3> INFO: Expecting 41600 events.
[12:37:50.091] <TB3> INFO: 41600 events read in total (3563ms).
[12:37:50.092] <TB3> INFO: Test took 4622ms.
[12:37:50.098] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:37:50.481] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[12:37:50.481] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:37:50.481] <TB3> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[12:37:50.481] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:37:50.481] <TB3> INFO: Decoding statistics:
[12:37:50.481] <TB3> INFO: General information:
[12:37:50.481] <TB3> INFO: 16bit words read: 0
[12:37:50.481] <TB3> INFO: valid events total: 0
[12:37:50.481] <TB3> INFO: empty events: 0
[12:37:50.481] <TB3> INFO: valid events with pixels: 0
[12:37:50.481] <TB3> INFO: valid pixel hits: 0
[12:37:50.481] <TB3> INFO: Event errors: 0
[12:37:50.481] <TB3> INFO: start marker: 0
[12:37:50.481] <TB3> INFO: stop marker: 0
[12:37:50.481] <TB3> INFO: overflow: 0
[12:37:50.481] <TB3> INFO: invalid 5bit words: 0
[12:37:50.481] <TB3> INFO: invalid XOR eye diagram: 0
[12:37:50.481] <TB3> INFO: TBM errors: 0
[12:37:50.481] <TB3> INFO: flawed TBM headers: 0
[12:37:50.482] <TB3> INFO: flawed TBM trailers: 0
[12:37:50.482] <TB3> INFO: event ID mismatches: 0
[12:37:50.482] <TB3> INFO: ROC errors: 0
[12:37:50.482] <TB3> INFO: missing ROC header(s): 0
[12:37:50.482] <TB3> INFO: misplaced readback start: 0
[12:37:50.482] <TB3> INFO: Pixel decoding errors: 0
[12:37:50.482] <TB3> INFO: pixel data incomplete: 0
[12:37:50.482] <TB3> INFO: pixel address: 0
[12:37:50.482] <TB3> INFO: pulse height fill bit: 0
[12:37:50.482] <TB3> INFO: buffer corruption: 0
[12:37:50.495] <TB3> INFO: ######################################################################
[12:37:50.495] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[12:37:50.495] <TB3> INFO: ######################################################################
[12:37:50.500] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[12:37:50.510] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[12:37:50.510] <TB3> INFO: run 1 of 1
[12:37:50.824] <TB3> INFO: Expecting 3120000 events.
[12:38:38.802] <TB3> INFO: 1199535 events read in total (47263ms).
[12:39:29.873] <TB3> INFO: 2392400 events read in total (98334ms).
[12:39:58.396] <TB3> INFO: 3120000 events read in total (126858ms).
[12:39:58.456] <TB3> INFO: Test took 127947ms.
[12:39:58.551] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:40:22.545] <TB3> INFO: PixTestBBMap::doTest() done, duration: 152 seconds
[12:40:22.545] <TB3> INFO: number of dead bumps (per ROC): 0 1 0 0 1 1 0 0 0 0 1 0 2 0 0 0
[12:40:22.545] <TB3> INFO: separation cut (per ROC): 102 131 132 117 112 115 110 119 112 125 106 105 104 105 118 105
[12:40:22.545] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:40:22.545] <TB3> INFO: Decoding statistics:
[12:40:22.545] <TB3> INFO: General information:
[12:40:22.545] <TB3> INFO: 16bit words read: 0
[12:40:22.545] <TB3> INFO: valid events total: 0
[12:40:22.545] <TB3> INFO: empty events: 0
[12:40:22.545] <TB3> INFO: valid events with pixels: 0
[12:40:22.545] <TB3> INFO: valid pixel hits: 0
[12:40:22.545] <TB3> INFO: Event errors: 0
[12:40:22.545] <TB3> INFO: start marker: 0
[12:40:22.545] <TB3> INFO: stop marker: 0
[12:40:22.545] <TB3> INFO: overflow: 0
[12:40:22.545] <TB3> INFO: invalid 5bit words: 0
[12:40:22.545] <TB3> INFO: invalid XOR eye diagram: 0
[12:40:22.545] <TB3> INFO: TBM errors: 0
[12:40:22.545] <TB3> INFO: flawed TBM headers: 0
[12:40:22.545] <TB3> INFO: flawed TBM trailers: 0
[12:40:22.545] <TB3> INFO: event ID mismatches: 0
[12:40:22.545] <TB3> INFO: ROC errors: 0
[12:40:22.545] <TB3> INFO: missing ROC header(s): 0
[12:40:22.545] <TB3> INFO: misplaced readback start: 0
[12:40:22.545] <TB3> INFO: Pixel decoding errors: 0
[12:40:22.545] <TB3> INFO: pixel data incomplete: 0
[12:40:22.545] <TB3> INFO: pixel address: 0
[12:40:22.545] <TB3> INFO: pulse height fill bit: 0
[12:40:22.545] <TB3> INFO: buffer corruption: 0
[12:40:22.618] <TB3> INFO: ######################################################################
[12:40:22.618] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:40:22.618] <TB3> INFO: ######################################################################
[12:40:22.618] <TB3> INFO: ----------------------------------------------------------------------
[12:40:22.618] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:40:22.618] <TB3> INFO: ----------------------------------------------------------------------
[12:40:22.618] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:40:22.627] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[12:40:22.627] <TB3> INFO: run 1 of 1
[12:40:22.954] <TB3> INFO: Expecting 26208000 events.
[12:40:55.124] <TB3> INFO: 1103700 events read in total (31455ms).
[12:41:26.648] <TB3> INFO: 2186050 events read in total (62979ms).
[12:41:58.097] <TB3> INFO: 3267050 events read in total (94428ms).
[12:42:29.430] <TB3> INFO: 4343900 events read in total (125761ms).
[12:43:00.333] <TB3> INFO: 5416550 events read in total (156664ms).
[12:43:30.675] <TB3> INFO: 6488300 events read in total (187006ms).
[12:44:00.654] <TB3> INFO: 7558700 events read in total (216985ms).
[12:44:30.941] <TB3> INFO: 8624850 events read in total (247272ms).
[12:45:01.394] <TB3> INFO: 9692350 events read in total (277725ms).
[12:45:32.657] <TB3> INFO: 10753600 events read in total (308988ms).
[12:46:03.537] <TB3> INFO: 11815750 events read in total (339868ms).
[12:46:34.610] <TB3> INFO: 12873850 events read in total (370941ms).
[12:47:05.099] <TB3> INFO: 13921150 events read in total (401430ms).
[12:47:35.598] <TB3> INFO: 14965250 events read in total (431929ms).
[12:48:05.198] <TB3> INFO: 16005250 events read in total (461529ms).
[12:48:35.355] <TB3> INFO: 17041950 events read in total (491686ms).
[12:49:05.648] <TB3> INFO: 18079650 events read in total (521979ms).
[12:49:35.751] <TB3> INFO: 19113700 events read in total (552082ms).
[12:50:06.316] <TB3> INFO: 20146600 events read in total (582647ms).
[12:50:36.680] <TB3> INFO: 21179150 events read in total (613011ms).
[12:51:08.465] <TB3> INFO: 22208900 events read in total (644796ms).
[12:51:38.944] <TB3> INFO: 23239450 events read in total (675275ms).
[12:52:09.000] <TB3> INFO: 24271500 events read in total (705331ms).
[12:52:39.100] <TB3> INFO: 25302650 events read in total (735431ms).
[12:53:06.196] <TB3> INFO: 26208000 events read in total (762527ms).
[12:53:06.238] <TB3> INFO: Test took 763611ms.
[12:53:06.330] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:53:06.535] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:53:08.358] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:53:09.916] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:53:11.429] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:53:13.019] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:53:14.685] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:53:16.272] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:53:17.961] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:53:19.718] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:53:21.322] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:53:22.996] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:53:24.501] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:53:25.942] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:53:27.561] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:53:29.258] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:53:30.935] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:53:32.624] <TB3> INFO: PixTestScurves::scurves() done
[12:53:32.624] <TB3> INFO: Vcal mean: 72.27 92.96 113.18 101.63 84.13 87.75 84.09 87.22 86.16 100.80 82.51 83.63 73.52 78.17 92.60 88.76
[12:53:32.624] <TB3> INFO: Vcal RMS: 4.63 5.09 5.36 6.06 4.70 5.32 4.91 6.11 4.97 6.22 4.77 5.54 5.11 4.52 8.58 5.66
[12:53:32.624] <TB3> INFO: PixTestScurves::fullTest() done, duration: 790 seconds
[12:53:32.624] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:53:32.624] <TB3> INFO: Decoding statistics:
[12:53:32.624] <TB3> INFO: General information:
[12:53:32.624] <TB3> INFO: 16bit words read: 0
[12:53:32.624] <TB3> INFO: valid events total: 0
[12:53:32.624] <TB3> INFO: empty events: 0
[12:53:32.624] <TB3> INFO: valid events with pixels: 0
[12:53:32.624] <TB3> INFO: valid pixel hits: 0
[12:53:32.624] <TB3> INFO: Event errors: 0
[12:53:32.624] <TB3> INFO: start marker: 0
[12:53:32.624] <TB3> INFO: stop marker: 0
[12:53:32.624] <TB3> INFO: overflow: 0
[12:53:32.624] <TB3> INFO: invalid 5bit words: 0
[12:53:32.624] <TB3> INFO: invalid XOR eye diagram: 0
[12:53:32.624] <TB3> INFO: TBM errors: 0
[12:53:32.625] <TB3> INFO: flawed TBM headers: 0
[12:53:32.625] <TB3> INFO: flawed TBM trailers: 0
[12:53:32.625] <TB3> INFO: event ID mismatches: 0
[12:53:32.625] <TB3> INFO: ROC errors: 0
[12:53:32.625] <TB3> INFO: missing ROC header(s): 0
[12:53:32.625] <TB3> INFO: misplaced readback start: 0
[12:53:32.625] <TB3> INFO: Pixel decoding errors: 0
[12:53:32.625] <TB3> INFO: pixel data incomplete: 0
[12:53:32.625] <TB3> INFO: pixel address: 0
[12:53:32.625] <TB3> INFO: pulse height fill bit: 0
[12:53:32.625] <TB3> INFO: buffer corruption: 0
[12:53:32.695] <TB3> INFO: ######################################################################
[12:53:32.695] <TB3> INFO: PixTestTrim::doTest()
[12:53:32.695] <TB3> INFO: ######################################################################
[12:53:32.697] <TB3> INFO: ----------------------------------------------------------------------
[12:53:32.697] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[12:53:32.697] <TB3> INFO: ----------------------------------------------------------------------
[12:53:32.776] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[12:53:32.776] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:53:32.784] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[12:53:32.784] <TB3> INFO: run 1 of 1
[12:53:33.107] <TB3> INFO: Expecting 6281600 events.
[12:54:13.970] <TB3> INFO: 1427640 events read in total (40148ms).
[12:54:56.178] <TB3> INFO: 2840640 events read in total (82356ms).
[12:55:39.793] <TB3> INFO: 4257530 events read in total (125971ms).
[12:56:23.381] <TB3> INFO: 5682970 events read in total (169560ms).
[12:56:42.107] <TB3> INFO: 6281600 events read in total (188285ms).
[12:56:42.146] <TB3> INFO: Test took 189362ms.
[12:56:42.196] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:57:01.227] <TB3> INFO: ROC 0 VthrComp = 75
[12:57:01.227] <TB3> INFO: ROC 1 VthrComp = 99
[12:57:01.229] <TB3> INFO: ROC 2 VthrComp = 108
[12:57:01.229] <TB3> INFO: ROC 3 VthrComp = 98
[12:57:01.229] <TB3> INFO: ROC 4 VthrComp = 87
[12:57:01.229] <TB3> INFO: ROC 5 VthrComp = 89
[12:57:01.229] <TB3> INFO: ROC 6 VthrComp = 86
[12:57:01.229] <TB3> INFO: ROC 7 VthrComp = 90
[12:57:01.229] <TB3> INFO: ROC 8 VthrComp = 90
[12:57:01.229] <TB3> INFO: ROC 9 VthrComp = 100
[12:57:01.230] <TB3> INFO: ROC 10 VthrComp = 85
[12:57:01.230] <TB3> INFO: ROC 11 VthrComp = 86
[12:57:01.230] <TB3> INFO: ROC 12 VthrComp = 79
[12:57:01.230] <TB3> INFO: ROC 13 VthrComp = 80
[12:57:01.230] <TB3> INFO: ROC 14 VthrComp = 89
[12:57:01.230] <TB3> INFO: ROC 15 VthrComp = 88
[12:57:01.230] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[12:57:01.230] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:57:01.240] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[12:57:01.240] <TB3> INFO: run 1 of 1
[12:57:01.548] <TB3> INFO: Expecting 6281600 events.
[12:57:34.045] <TB3> INFO: 890060 events read in total (31782ms).
[12:58:05.930] <TB3> INFO: 1777060 events read in total (63667ms).
[12:58:42.418] <TB3> INFO: 2665870 events read in total (100155ms).
[12:59:19.230] <TB3> INFO: 3551470 events read in total (136967ms).
[12:59:55.612] <TB3> INFO: 4429110 events read in total (173350ms).
[13:00:31.799] <TB3> INFO: 5302690 events read in total (209536ms).
[13:01:07.566] <TB3> INFO: 6175430 events read in total (245303ms).
[13:01:12.593] <TB3> INFO: 6281600 events read in total (250330ms).
[13:01:12.647] <TB3> INFO: Test took 251407ms.
[13:01:12.800] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:01:40.561] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 57.8304 for pixel 7/17 mean/min/max = 46.0266/34.1543/57.899
[13:01:40.561] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 56.0256 for pixel 51/56 mean/min/max = 43.7756/31.0786/56.4725
[13:01:40.561] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 64.934 for pixel 0/9 mean/min/max = 49.7787/34.4909/65.0664
[13:01:40.562] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 61.5041 for pixel 13/1 mean/min/max = 46.8941/32.0755/61.7126
[13:01:40.562] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 57.3717 for pixel 15/74 mean/min/max = 44.8071/32.187/57.4272
[13:01:40.562] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 59.0443 for pixel 15/1 mean/min/max = 46.1176/33.0594/59.1759
[13:01:40.562] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 58.228 for pixel 16/79 mean/min/max = 45.5596/32.8011/58.3181
[13:01:40.563] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 61.8524 for pixel 16/79 mean/min/max = 46.6468/31.1864/62.1072
[13:01:40.563] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 57.6742 for pixel 7/79 mean/min/max = 45.4351/33.1557/57.7146
[13:01:40.563] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 62.3348 for pixel 11/79 mean/min/max = 47.0795/31.4874/62.6715
[13:01:40.563] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 57.5002 for pixel 0/2 mean/min/max = 45.0943/32.6471/57.5415
[13:01:40.564] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 59.5361 for pixel 1/24 mean/min/max = 45.4291/31.2551/59.6032
[13:01:40.564] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 59.6773 for pixel 25/78 mean/min/max = 46.837/33.979/59.695
[13:01:40.564] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 57.9504 for pixel 0/74 mean/min/max = 45.5921/32.8858/58.2983
[13:01:40.565] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 68.3343 for pixel 8/0 mean/min/max = 49.0222/29.4006/68.6438
[13:01:40.565] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 61.5861 for pixel 14/3 mean/min/max = 47.1413/32.5589/61.7238
[13:01:40.565] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:01:40.696] <TB3> INFO: Expecting 514560 events.
[13:01:49.748] <TB3> INFO: 514560 events read in total (8337ms).
[13:01:49.753] <TB3> INFO: Expecting 514560 events.
[13:01:58.654] <TB3> INFO: 514560 events read in total (8228ms).
[13:01:58.663] <TB3> INFO: Expecting 514560 events.
[13:02:07.974] <TB3> INFO: 514560 events read in total (8642ms).
[13:02:07.984] <TB3> INFO: Expecting 514560 events.
[13:02:17.641] <TB3> INFO: 514560 events read in total (8996ms).
[13:02:17.655] <TB3> INFO: Expecting 514560 events.
[13:02:26.760] <TB3> INFO: 514560 events read in total (8452ms).
[13:02:26.774] <TB3> INFO: Expecting 514560 events.
[13:02:35.759] <TB3> INFO: 514560 events read in total (8323ms).
[13:02:35.774] <TB3> INFO: Expecting 514560 events.
[13:02:44.625] <TB3> INFO: 514560 events read in total (8192ms).
[13:02:44.644] <TB3> INFO: Expecting 514560 events.
[13:02:53.527] <TB3> INFO: 514560 events read in total (8226ms).
[13:02:53.549] <TB3> INFO: Expecting 514560 events.
[13:03:02.362] <TB3> INFO: 514560 events read in total (8167ms).
[13:03:02.383] <TB3> INFO: Expecting 514560 events.
[13:03:11.685] <TB3> INFO: 514560 events read in total (8643ms).
[13:03:11.708] <TB3> INFO: Expecting 514560 events.
[13:03:21.435] <TB3> INFO: 514560 events read in total (9075ms).
[13:03:21.459] <TB3> INFO: Expecting 514560 events.
[13:03:31.099] <TB3> INFO: 514560 events read in total (8989ms).
[13:03:31.128] <TB3> INFO: Expecting 514560 events.
[13:03:40.736] <TB3> INFO: 514560 events read in total (8970ms).
[13:03:40.764] <TB3> INFO: Expecting 514560 events.
[13:03:50.378] <TB3> INFO: 514560 events read in total (8964ms).
[13:03:50.407] <TB3> INFO: Expecting 514560 events.
[13:04:00.181] <TB3> INFO: 514560 events read in total (9120ms).
[13:04:00.213] <TB3> INFO: Expecting 514560 events.
[13:04:09.936] <TB3> INFO: 514560 events read in total (9081ms).
[13:04:09.972] <TB3> INFO: Test took 149407ms.
[13:04:11.043] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[13:04:11.051] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:04:11.051] <TB3> INFO: run 1 of 1
[13:04:11.365] <TB3> INFO: Expecting 6281600 events.
[13:04:24.342] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 5 ROCs were found
[13:04:24.342] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[13:04:24.342] <TB3> ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (189) != TBM ID (129)
[13:04:24.342] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (1) != Token Chain Length (8)
[13:04:24.342] <TB3> ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (130) != TBM ID (189)
[13:04:24.342] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:04:24.342] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bf 8040 43ca 22 28ce 43ca 22 28ae 43ca 22 2901 43ca 22 2907 43ca 22 2920 43ca 22 28cf 43ca 22 28c0 43ca 22 28c5 e022 c000
[13:04:24.342] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1b9 80c0 4389 22 28e1 4389 22 28ad 4389 22 2902 4389 22 2908 4389 22 290e 4389 22 28e0 4389 22 28ad 4389 22 28c5 e022 c000
[13:04:24.342] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ba 8000 4389 22 28e0 4389 22 28c0 4389 22 2902 4389 22 2907 4389 22 2920 4389 22 28e0 4389 22 28ae 4389 22 28c7 e022 c000
[13:04:24.342] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bb 8040 4389 22 28cd 4389 22 28af 4389 22 2903 4389 22 2907 4389 22 2920 4389 22 28e1 4389 22 28af 4389 22 28c5 e022 c000
[13:04:24.342] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bc 80b1 4389 22 28e0 4389 22 28ae 4389 22 2901 4389 22 2907 4389 22 2920 43c9 22 2a78 72 2045 2040 45 e022 c000
[13:04:24.342] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1bd 80c0 43c8 22 28cd 43c8 22 28ae 43c8 22 2904 43c8 22 2905 43c8 22 2920 43c8 22 28e0 43c8 22 28ae 43c8 22 28c6 e022 c000
[13:04:24.342] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1be 8000 43c8 22 28e0 43c8 22 28af 43c8 22 2903 43c8 22 2907 43c8 22 290d 43c8 22 28cf 43c8 22 28ae 43c8 22 28c7 e022 c000
[13:04:24.342] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:04:24.342] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0c0 80b1 43c8 22 28c2 43c8 22 28c5 43c8 22 2881 43c8 22 28c4 43c8 22 28ca 43c8 22 2903 43c8 22 28c6 43c8 22 28ca e022 c000
[13:04:24.342] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bb 8040 4389 22 28c3 4389 22 28c5 4389 22 2881 4389 22 28c4 4389 22 28ca 4389 22 2902 4389 22 28c5 4389 22 28ca e022 c000
[13:04:24.342] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a0bc 80b1 4389 22 28c5 4389 22 28c7 4389 22 2881 4389 22 28c2 4389 22 28cb 43c9 22
[13:04:24.342] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a081 8014 43c9 2ae0 114 e022 c000
[13:04:24.342] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bd 80c0 43c8 22 28c2 43c8 22 28c6 43c8 22 286f 43c8 22 28c3 43c8 22 28c9 43c8 22 2902 43c8 22 28c7 43c8 22 28c9 e022 c000
[13:04:24.342] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0be 8000 43c8 22 28c4 43c8 22 28c6 43c8 22 286f 43c8 22 28c2 43c8 22 28cc 43c8 22 2902 43c8 22 28c5 43c8 22 28ca e022 c000
[13:04:24.342] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0bf 8040 43ca 22 28c3 43ca 22 28c7 43ca 22 2880 43ca 22 28c4 43ca 22 28ca 43ca 22 2902 43ca 22 28c6 43ca 22 28cc e022 c000
[13:04:50.215] <TB3> CRITICAL: <hal.cc/condenseTriggers:L1899> Data size does not correspond to 10 triggers! Aborting data processing!
[13:04:50.238] <TB3> INFO: 0 events read in total (38158ms).
[13:05:28.435] <TB3> INFO: 868220 events read in total (76355ms).
[13:06:06.870] <TB3> INFO: 1737050 events read in total (114790ms).
[13:06:43.255] <TB3> INFO: 2602970 events read in total (151175ms).
[13:07:19.365] <TB3> INFO: 3460940 events read in total (187285ms).
[13:07:55.964] <TB3> INFO: 4315460 events read in total (223884ms).
[13:08:31.499] <TB3> INFO: 5169880 events read in total (259419ms).
[13:08:35.068] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 3 ROCs were found
[13:08:35.068] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[13:08:35.068] <TB3> ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (1) != TBM ID (67)
[13:08:35.069] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (3) != Token Chain Length (8)
[13:08:35.069] <TB3> ERROR: <datapipe.cc/CheckEventID:L463> Channel 0 Event ID mismatch: local ID (68) != TBM ID (1)
[13:08:35.069] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:08:35.069] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a103 8040 43c8 862 28e8 43c9 862 28c6 43c8 862 28ec 43c9 862 290d 43c8 862 2909 43c9 862 28e0 43c8 862 28cd 43c9 862 28eb e022 c000
[13:08:35.069] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fd 80c0 4389 862 28e6 4389 862 28c4 4389 862 28ef 4389 862 290d 4389 862 2909 4389 862 28e0 4389 862 28cc 4389 862 28ea e022 c000
[13:08:35.069] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fe 8000 4388 862 28e7 4388 862 28c5 4388 862 28ed 4388 862 290d 4388 862 2908 4388 862 28e1 4388 862 28cd 4388 862 28eb e022 c000
[13:08:35.069] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ff 8040 438a 862 28e7 438a 862 28c6 438a 862 2900 438a 862 290d 438a 862 2909 438a 862 28cf 438a 862 28cd 438a 862 28e9 e022 c000
[13:08:35.069] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a100 80b1 4389 862 28e6 4389 862 28c5 4389 862 28ed 43c9 862 290e 73 20c5 20c1 c5 43c9 862 28cd 43c9 862 28eb e022 c000
[13:08:35.069] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a101 80c0 43c8 862 28e7 43c8 862 28c5 43c8 862 28ed 43c8 862 290c 43c9 862 2909 43c9 862 28cf 43c9 862 28cd 43c9 862 28ea e022 c000
[13:08:35.069] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a102 8000 43c8 862 28e8 43c8 862 28c4 43c9 862 28ee 43c9 862 290d 43c8 862 290c 43c8 862 28e1 43c9 862 28cd 43c9 862 28ec e022 c000
[13:08:35.069] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:08:35.069] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a004 80b1 43c8 862 28ca 43c8 862 28e3 43c8 862 2885 43c8 862 28c9 43c8 862 28cc 43c8 862 28ea 43c8 862 28c7 43c8 862 28e2 e022 c000
[13:08:35.069] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ff 8040 438a 862 28ca 438a 862 28e2 438a 862 2884 438a 862 28c9 438a 862 28cc 438a 862 28ea 438a 862 28c4 438a 862 28e4 e022 c000
[13:08:35.069] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a000 80b1 4388 862 28cc 4388 862 28e2 4388 862 2886 43c8 862
[13:08:35.069] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a043 8014 43c8 28c4 314 43c8 862 28c5 43c8 862 28e1 e022 c000
[13:08:35.069] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a001 80c0 43c8 862 28ca 43c8 862 28e2 43c8 862 2886 43c8 862 28c9 43c9 862 28cc 43c9 862 28ea 43c9 862 28c5 43c9 862 28e3 e022 c000
[13:08:35.069] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a002 8000 43c8 862 28cc 43c8 862 28e2 43c9 862 2884 43c9 862 28c9 43c8 862 28cc 43c8 862 28eb 43c9 862 28c4 43c9 862 28e2 e022 c000
[13:08:35.069] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a003 8040 43c8 862 28ca 43c9 862 28e2 43c8 862 2886 43c9 862 28c9 43c8 862 28cc 43c9 862 28ec 43c8 862 28c5 43c9 862 28e1 e022 c000
[13:08:42.812] <TB3> CRITICAL: <hal.cc/condenseTriggers:L1899> Data size does not correspond to 10 triggers! Aborting data processing!
[13:08:42.821] <TB3> INFO: 5169880 events read in total (270742ms).
[13:08:42.822] <TB3> CRITICAL: <hal.cc/MultiRocAllPixelsDacScan:L958> Incomplete DAQ data readout! Missing 111172 Events.
[13:08:43.185] <TB3> INFO: Expecting 6281600 events.
[13:09:20.358] <TB3> INFO: 870490 events read in total (36458ms).
[13:09:53.905] <TB3> INFO: 1738770 events read in total (70005ms).
[13:10:27.389] <TB3> INFO: 2607630 events read in total (103489ms).
[13:10:59.381] <TB3> INFO: 3473490 events read in total (135481ms).
[13:11:35.607] <TB3> INFO: 4331540 events read in total (171707ms).
[13:12:10.616] <TB3> INFO: 5186030 events read in total (206716ms).
[13:12:44.697] <TB3> INFO: 6040440 events read in total (240797ms).
[13:12:53.786] <TB3> INFO: 6281600 events read in total (249886ms).
[13:12:53.857] <TB3> INFO: Test took 251007ms.
[13:12:54.020] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:13:17.742] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.059113 .. 255.000000
[13:13:17.841] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[13:13:17.850] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:13:17.850] <TB3> INFO: run 1 of 1
[13:13:18.190] <TB3> INFO: Expecting 10649600 events.
[13:13:53.623] <TB3> INFO: 826350 events read in total (34718ms).
[13:14:25.776] <TB3> INFO: 1652990 events read in total (66871ms).
[13:15:00.459] <TB3> INFO: 2479510 events read in total (101554ms).
[13:15:33.866] <TB3> INFO: 3306230 events read in total (134961ms).
[13:16:05.786] <TB3> INFO: 4132970 events read in total (166881ms).
[13:16:38.847] <TB3> INFO: 4960380 events read in total (199942ms).
[13:17:12.387] <TB3> INFO: 5787380 events read in total (233482ms).
[13:17:43.141] <TB3> INFO: 6613870 events read in total (264236ms).
[13:18:17.639] <TB3> INFO: 7439720 events read in total (298734ms).
[13:18:49.304] <TB3> INFO: 8264450 events read in total (330399ms).
[13:19:24.802] <TB3> INFO: 9088820 events read in total (365897ms).
[13:19:36.424] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 0 has NoTokenPass but 8 ROCs were found
[13:19:36.425] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 8 ROCs were found
[13:19:36.425] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:19:36.425] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a081 80c0 43c8 714 20c0 43c8 714 20cd 43c8 714 2083 43c8 714 20c3 43c9 714 20c0 43c9 714 20e5 43c9 714 20c2 43c9 714 20cc e022 c000
[13:19:36.425] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07b 8040 4388 714 20c1 4388 714 20cd 4388 714 2083 4388 714 20c4 4388 714 20c1 4388 714 20e4 4388 714 20c0 4388 714 20cc e022 c000
[13:19:36.425] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07c 80b1 4388 714 20c0 4388 714 20cc 4388 714 2081 4388 714 20c2 4388 714 20c0 4388 714 20e5 4388 714 20c2 4388 714 20cc e022 c000
[13:19:36.425] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07d 80c0 4389 714 20c2 4389 714 20cc 4389 714 2083 4389 714 20c4 4389 714 20c0 4389 714 20e5 4389 714 20c1 4389 714 20cc e022 c000
[13:19:36.425] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a07e 8000 4388 714 20c0 4388 714 20cc 4388 714 2083 4388 714 20c3 4388 714 20c1 4388 714 20e5 4388 714 20c1 43c8 714 217e ffe 2220 ff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff
[13:19:36.425] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07f 8040 43ca 714 20c0 43ca 714 20cd 43ca 714 2083 43ca 714 20c4 43ca 714 20c1 43ca 714 20e5 43ca 714 20c1 43ca 714 20cc e022 c000
[13:19:36.425] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a080 80b1 43c8 714 20c1 43c8 714 20cd 43c8 714 2083 43c8 714 20c4 43c8 714 20c1 43c8 714 20e5 43c8 714 20c1 43c8 714 20cc e022 c000
[13:19:36.425] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:19:36.425] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a181 80c0 43c8 714 20e9 43c8 714 20c6 43c8 714 2100 43c8 714 2107 43c9 714 2102 43c9 714 20cb 43c9 714 20e3 43c9 714 20ca e022 c000
[13:19:36.425] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17b 8040 4388 714 20e7 4388 714 20c4 4388 714 2101 4388 714 2107 4388 714 2102 4388 714 20ca 4388 714 20e2 4388 714 20cb e022 c000
[13:19:36.425] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17c 80b1 4388 714 20e8 4388 714 20c5 4388 714 2100 4388 714 2108 4388 714 2102 4388 714 20cc 4388 714 20e5 4388 714 20cb e022 c000
[13:19:36.425] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17d 80c0 4389 714 20e8 4389 714 20c5 4389 714 2101 4389 714 2109 4389 714 2102 4389 714 20cd 4389 714 20e4 4389 714 20cd e022 c000
[13:19:36.425] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a17e 8000 4388 714 20e9 4388 714 20c6 4388 714 2101 4388 714 2109 4388 714 2101 4388 714 20ca 4388 714 20e2 43c8 714 202e 7c 2440 1ff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff 2fff fff
[13:19:36.425] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a17f 8040 43ca 714 20e9 43ca 714 20c5 43ca 714 2101 43ca 714 2107 43ca 714 2102 43ca 714 20cc 43ca 714 20e3 43ca 714 20ca e022 c000
[13:19:36.425] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a180 80b1 43c9 714 20e9 43c9 714 20c5 43c9 714 2100 43c9 714 2109 43c9 714 2102 43c9 714 20c9 43c9 714 20e3 43c9 714 20cc e022 c000
[13:20:00.800] <TB3> INFO: 9912930 events read in total (401895ms).
[13:20:28.342] <TB3> INFO: 10649600 events read in total (429437ms).
[13:20:28.459] <TB3> INFO: Test took 430609ms.
[13:20:28.746] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:58.309] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 12.308420 .. 49.068705
[13:20:58.393] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 2 .. 59 (-1/-1) hits flags = 528 (plus default)
[13:20:58.402] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:20:58.402] <TB3> INFO: run 1 of 1
[13:20:58.721] <TB3> INFO: Expecting 2412800 events.
[13:21:37.240] <TB3> INFO: 1143330 events read in total (37798ms).
[13:22:13.138] <TB3> INFO: 2283520 events read in total (73696ms).
[13:22:17.506] <TB3> INFO: 2412800 events read in total (78065ms).
[13:22:17.524] <TB3> INFO: Test took 79122ms.
[13:22:17.561] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:32.551] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 19.416979 .. 46.164793
[13:22:32.627] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 9 .. 56 (-1/-1) hits flags = 528 (plus default)
[13:22:32.636] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:22:32.636] <TB3> INFO: run 1 of 1
[13:22:32.942] <TB3> INFO: Expecting 1996800 events.
[13:23:10.017] <TB3> INFO: 1120600 events read in total (36360ms).
[13:23:40.043] <TB3> INFO: 1996800 events read in total (66386ms).
[13:23:40.067] <TB3> INFO: Test took 67431ms.
[13:23:40.104] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:53.497] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 21.343621 .. 45.083076
[13:23:53.576] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 11 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:23:53.585] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:23:53.585] <TB3> INFO: run 1 of 1
[13:23:53.893] <TB3> INFO: Expecting 1872000 events.
[13:24:31.526] <TB3> INFO: 1115820 events read in total (36918ms).
[13:24:59.523] <TB3> INFO: 1872000 events read in total (64916ms).
[13:24:59.544] <TB3> INFO: Test took 65960ms.
[13:24:59.583] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:25:15.367] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[13:25:15.367] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:25:15.376] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:25:15.376] <TB3> INFO: run 1 of 1
[13:25:15.680] <TB3> INFO: Expecting 1705600 events.
[13:25:56.932] <TB3> INFO: 1075520 events read in total (40537ms).
[13:26:06.182] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[13:26:06.182] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (0) != Token Chain Length (8)
[13:26:06.182] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:26:06.182] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a072 8000 43c8 43c8 43c9 43c9 43c8 43c8 43c9 43c9 e022 c000
[13:26:06.182] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06c 80b1 4388 4388 4388 4388 4388 4388 4388 4388 e022 c000
[13:26:06.182] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06d 80c0 4388 4388 4388 4388 4388 4388 4388 4388 e022 c000
[13:26:06.182] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06e 8000 4389 4389 4389 4389 4389 4389 4389 4389 e022 c000
[13:26:06.182] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06f 8040 7ff 43cb 43cb 43cb 43cb 43cb 43cb 43cb e022 c000
[13:26:06.182] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a070 80b1 43c8 43c8 43c8 43c8 43c8 43c8 43c8 43c8 e022 c000
[13:26:06.182] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a071 80c0 43c8 43c8 43c8 43c8 43c9 43c9 43c9 43c9 e022 c000
[13:26:06.182] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:26:06.182] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a172 8000 43c8 43c8 43c9 43c9 43c8 43c8 43c9 43c9 e022 c000
[13:26:06.182] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16c 80b1 4388 4388 4388 4388 4388 4388 4388 4388 e022 c000
[13:26:06.182] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16d 80c0 4388 4388 4388 4388 4388 4388 4388 4388 e022 c000
[13:26:06.182] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16e 8000 4389 4389 4389 4389 4389 4389 4389 4389 e022 c000
[13:26:06.182] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16f 8040 e001 c06f
[13:26:06.182] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a170 80b1 43c9 43c9 43c9 43c9 43c9 43c9 43c9 43c9 e022 c000
[13:26:06.182] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a171 80c0 43c8 43c8 43c8 43c8 43c9 43c9 43c9 43c9 e022 c000
[13:26:17.746] <TB3> INFO: 1705600 events read in total (61351ms).
[13:26:17.757] <TB3> INFO: Test took 62381ms.
[13:26:17.786] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:26:31.405] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C0.dat
[13:26:31.405] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C1.dat
[13:26:31.405] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C2.dat
[13:26:31.405] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C3.dat
[13:26:31.405] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C4.dat
[13:26:31.406] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C5.dat
[13:26:31.406] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C6.dat
[13:26:31.406] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C7.dat
[13:26:31.406] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C8.dat
[13:26:31.406] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C9.dat
[13:26:31.416] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C10.dat
[13:26:31.416] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C11.dat
[13:26:31.417] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C12.dat
[13:26:31.417] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C13.dat
[13:26:31.417] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C14.dat
[13:26:31.417] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C15.dat
[13:26:31.417] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C0.dat
[13:26:31.427] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C1.dat
[13:26:31.433] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C2.dat
[13:26:31.440] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C3.dat
[13:26:31.446] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C4.dat
[13:26:31.453] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C5.dat
[13:26:31.459] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C6.dat
[13:26:31.466] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C7.dat
[13:26:31.473] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C8.dat
[13:26:31.479] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C9.dat
[13:26:31.486] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C10.dat
[13:26:31.492] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C11.dat
[13:26:31.498] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C12.dat
[13:26:31.505] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C13.dat
[13:26:31.511] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C14.dat
[13:26:31.518] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//trimParameters35_C15.dat
[13:26:31.524] <TB3> INFO: PixTestTrim::trimTest() done
[13:26:31.524] <TB3> INFO: vtrim: 99 91 120 96 94 110 98 107 88 104 93 90 90 88 115 108
[13:26:31.524] <TB3> INFO: vthrcomp: 75 99 108 98 87 89 86 90 90 100 85 86 79 80 89 88
[13:26:31.524] <TB3> INFO: vcal mean: 35.01 34.94 34.99 34.99 34.96 35.01 34.97 34.92 34.96 34.99 34.98 34.98 35.03 35.02 34.95 34.99
[13:26:31.524] <TB3> INFO: vcal RMS: 0.84 0.86 0.95 1.20 0.83 0.90 0.87 1.06 0.78 0.88 0.88 0.87 0.79 0.86 1.16 0.93
[13:26:31.524] <TB3> INFO: bits mean: 9.06 9.73 8.61 9.38 9.88 9.14 9.31 9.21 9.08 8.89 9.64 9.61 8.60 9.13 9.45 9.52
[13:26:31.524] <TB3> INFO: bits RMS: 2.57 2.82 2.45 2.65 2.58 2.65 2.72 2.83 2.73 2.86 2.57 2.74 2.74 2.74 2.68 2.46
[13:26:31.531] <TB3> INFO: ----------------------------------------------------------------------
[13:26:31.531] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[13:26:31.531] <TB3> INFO: ----------------------------------------------------------------------
[13:26:31.533] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[13:26:31.542] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[13:26:31.542] <TB3> INFO: run 1 of 1
[13:26:31.848] <TB3> INFO: Expecting 4160000 events.
[13:27:21.888] <TB3> INFO: 1140555 events read in total (49325ms).
[13:28:06.579] <TB3> INFO: 2261990 events read in total (94016ms).
[13:28:30.473] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[13:28:30.473] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a030 80b1 43c8 498 24c1 43c8 498 24c5 43c8 43c8 498 24af 43c8 498 24e0 43c8 498 24e6 43c8 498 24c1 43c8 498 24c9 e022 c000
[13:28:30.473] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02a 8000 4389 498 24c0 4389 498 24c3 4389 4389 498 24ad 4389 498 24cf 4389 498 24e5 4389 498 24c1 4389 498 24c8 e022 c000
[13:28:30.473] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02b 8040 4389 498 24c1 4389 498 24c4 4389 4389 498 24ad 4389 498 24e0 4389 498 24e5 4389 498 24c1 4389 498 24c9 e022 c000
[13:28:30.473] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02c 80b1 4389 498 24c1 4389 498 24c5 4389 4389 498 24ae 4389 498 24cf 4389 498 24e5 4389 498 24c2 4389 498 24c9 e022 c000
[13:28:30.473] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02d 80c0 4389 498 24c1 4389 498 24c3 4389 4389 498 24af 4389 498 24e0 4389 498 24e6 4389 498 24c1 4389 498 24c9 f022 c000
[13:28:30.473] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02e 8000 43c9 498 24c1 43c9 498 24c5 43c9 43c9 498 24ae 43c9 498 24cf 43c9 498 24e5 43c9 498 24c1 43c9 498 24c9 e022 c000
[13:28:30.474] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02f 8040 43ca 498 24c1 43ca 498 24c2 43ca 43ca 498 24af 43ca 498 24e0 43ca 498 24e6 43ca 498 24c0 43ca 498 24c9 e022 c000
[13:28:54.291] <TB3> INFO: 3366245 events read in total (141729ms).
[13:29:26.869] <TB3> INFO: 4160000 events read in total (174306ms).
[13:29:26.933] <TB3> INFO: Test took 175391ms.
[13:29:27.052] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:57.156] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 181 (-1/-1) hits flags = 528 (plus default)
[13:29:57.165] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[13:29:57.165] <TB3> INFO: run 1 of 1
[13:29:57.478] <TB3> INFO: Expecting 3785600 events.
[13:30:45.623] <TB3> INFO: 1141100 events read in total (47430ms).
[13:31:36.379] <TB3> INFO: 2261695 events read in total (98186ms).
[13:32:26.372] <TB3> INFO: 3364340 events read in total (148179ms).
[13:32:43.838] <TB3> INFO: 3785600 events read in total (165645ms).
[13:32:43.892] <TB3> INFO: Test took 166726ms.
[13:32:43.998] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:33:12.548] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 166 (-1/-1) hits flags = 528 (plus default)
[13:33:12.559] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[13:33:12.559] <TB3> INFO: run 1 of 1
[13:33:12.867] <TB3> INFO: Expecting 3473600 events.
[13:33:59.814] <TB3> INFO: 1192760 events read in total (46232ms).
[13:34:46.632] <TB3> INFO: 2356600 events read in total (93050ms).
[13:35:34.937] <TB3> INFO: 3473600 events read in total (141355ms).
[13:35:34.995] <TB3> INFO: Test took 142437ms.
[13:35:35.092] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:59.791] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 166 (-1/-1) hits flags = 528 (plus default)
[13:35:59.800] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[13:35:59.800] <TB3> INFO: run 1 of 1
[13:36:00.117] <TB3> INFO: Expecting 3473600 events.
[13:36:47.875] <TB3> INFO: 1191200 events read in total (47043ms).
[13:37:37.145] <TB3> INFO: 2353615 events read in total (96313ms).
[13:38:28.482] <TB3> INFO: 3473600 events read in total (147650ms).
[13:38:28.551] <TB3> INFO: Test took 148751ms.
[13:38:28.649] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:38:57.729] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 166 (-1/-1) hits flags = 528 (plus default)
[13:38:57.740] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[13:38:57.740] <TB3> INFO: run 1 of 1
[13:38:58.067] <TB3> INFO: Expecting 3473600 events.
[13:39:46.143] <TB3> INFO: 1189705 events read in total (47361ms).
[13:40:37.561] <TB3> INFO: 2350795 events read in total (98779ms).
[13:41:27.491] <TB3> INFO: 3473600 events read in total (148709ms).
[13:41:27.544] <TB3> INFO: Test took 149803ms.
[13:41:27.629] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:54.028] <TB3> INFO: PixTestTrim::trimBitTest() done
[13:41:54.030] <TB3> INFO: PixTestTrim::doTest() done, duration: 2901 seconds
[13:41:54.030] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:41:54.030] <TB3> INFO: Decoding statistics:
[13:41:54.030] <TB3> INFO: General information:
[13:41:54.030] <TB3> INFO: 16bit words read: 0
[13:41:54.030] <TB3> INFO: valid events total: 0
[13:41:54.030] <TB3> INFO: empty events: 0
[13:41:54.030] <TB3> INFO: valid events with pixels: 0
[13:41:54.030] <TB3> INFO: valid pixel hits: 0
[13:41:54.030] <TB3> INFO: Event errors: 0
[13:41:54.030] <TB3> INFO: start marker: 0
[13:41:54.030] <TB3> INFO: stop marker: 0
[13:41:54.030] <TB3> INFO: overflow: 0
[13:41:54.030] <TB3> INFO: invalid 5bit words: 0
[13:41:54.030] <TB3> INFO: invalid XOR eye diagram: 0
[13:41:54.030] <TB3> INFO: TBM errors: 0
[13:41:54.030] <TB3> INFO: flawed TBM headers: 0
[13:41:54.030] <TB3> INFO: flawed TBM trailers: 0
[13:41:54.030] <TB3> INFO: event ID mismatches: 0
[13:41:54.030] <TB3> INFO: ROC errors: 0
[13:41:54.030] <TB3> INFO: missing ROC header(s): 0
[13:41:54.030] <TB3> INFO: misplaced readback start: 0
[13:41:54.030] <TB3> INFO: Pixel decoding errors: 0
[13:41:54.030] <TB3> INFO: pixel data incomplete: 0
[13:41:54.030] <TB3> INFO: pixel address: 0
[13:41:54.030] <TB3> INFO: pulse height fill bit: 0
[13:41:54.030] <TB3> INFO: buffer corruption: 0
[13:41:54.809] <TB3> INFO: ######################################################################
[13:41:54.809] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[13:41:54.809] <TB3> INFO: ######################################################################
[13:41:55.116] <TB3> INFO: Expecting 41600 events.
[13:41:59.574] <TB3> INFO: 41600 events read in total (3743ms).
[13:41:59.575] <TB3> INFO: Test took 4764ms.
[13:41:59.582] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:00.245] <TB3> INFO: Expecting 41600 events.
[13:42:04.640] <TB3> INFO: 41600 events read in total (3680ms).
[13:42:04.641] <TB3> INFO: Test took 4713ms.
[13:42:04.647] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:05.095] <TB3> INFO: Expecting 41600 events.
[13:42:09.503] <TB3> INFO: 41600 events read in total (3693ms).
[13:42:09.503] <TB3> INFO: Test took 4732ms.
[13:42:09.510] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:09.944] <TB3> INFO: Expecting 2560 events.
[13:42:10.907] <TB3> INFO: 2560 events read in total (248ms).
[13:42:10.907] <TB3> INFO: Test took 1390ms.
[13:42:11.415] <TB3> INFO: Expecting 2560 events.
[13:42:12.377] <TB3> INFO: 2560 events read in total (247ms).
[13:42:12.377] <TB3> INFO: Test took 1469ms.
[13:42:12.884] <TB3> INFO: Expecting 2560 events.
[13:42:13.846] <TB3> INFO: 2560 events read in total (246ms).
[13:42:13.846] <TB3> INFO: Test took 1469ms.
[13:42:14.354] <TB3> INFO: Expecting 2560 events.
[13:42:15.315] <TB3> INFO: 2560 events read in total (246ms).
[13:42:15.315] <TB3> INFO: Test took 1468ms.
[13:42:15.823] <TB3> INFO: Expecting 2560 events.
[13:42:16.785] <TB3> INFO: 2560 events read in total (247ms).
[13:42:16.786] <TB3> INFO: Test took 1470ms.
[13:42:17.293] <TB3> INFO: Expecting 2560 events.
[13:42:18.254] <TB3> INFO: 2560 events read in total (246ms).
[13:42:18.255] <TB3> INFO: Test took 1469ms.
[13:42:18.763] <TB3> INFO: Expecting 2560 events.
[13:42:19.725] <TB3> INFO: 2560 events read in total (247ms).
[13:42:19.725] <TB3> INFO: Test took 1469ms.
[13:42:20.233] <TB3> INFO: Expecting 2560 events.
[13:42:21.195] <TB3> INFO: 2560 events read in total (247ms).
[13:42:21.196] <TB3> INFO: Test took 1470ms.
[13:42:21.703] <TB3> INFO: Expecting 2560 events.
[13:42:22.667] <TB3> INFO: 2560 events read in total (249ms).
[13:42:22.667] <TB3> INFO: Test took 1471ms.
[13:42:23.175] <TB3> INFO: Expecting 2560 events.
[13:42:24.138] <TB3> INFO: 2560 events read in total (249ms).
[13:42:24.139] <TB3> INFO: Test took 1472ms.
[13:42:24.647] <TB3> INFO: Expecting 2560 events.
[13:42:25.604] <TB3> INFO: 2560 events read in total (243ms).
[13:42:25.604] <TB3> INFO: Test took 1464ms.
[13:42:26.112] <TB3> INFO: Expecting 2560 events.
[13:42:27.074] <TB3> INFO: 2560 events read in total (247ms).
[13:42:27.074] <TB3> INFO: Test took 1469ms.
[13:42:27.582] <TB3> INFO: Expecting 2560 events.
[13:42:28.544] <TB3> INFO: 2560 events read in total (247ms).
[13:42:28.544] <TB3> INFO: Test took 1464ms.
[13:42:29.052] <TB3> INFO: Expecting 2560 events.
[13:42:30.009] <TB3> INFO: 2560 events read in total (242ms).
[13:42:30.009] <TB3> INFO: Test took 1464ms.
[13:42:30.517] <TB3> INFO: Expecting 2560 events.
[13:42:31.480] <TB3> INFO: 2560 events read in total (248ms).
[13:42:31.481] <TB3> INFO: Test took 1472ms.
[13:42:31.989] <TB3> INFO: Expecting 2560 events.
[13:42:32.952] <TB3> INFO: 2560 events read in total (248ms).
[13:42:32.952] <TB3> INFO: Test took 1469ms.
[13:42:32.957] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:42:33.462] <TB3> INFO: Expecting 655360 events.
[13:42:45.837] <TB3> INFO: 655360 events read in total (11660ms).
[13:42:45.846] <TB3> INFO: Expecting 655360 events.
[13:42:57.991] <TB3> INFO: 655360 events read in total (11545ms).
[13:42:58.006] <TB3> INFO: Expecting 655360 events.
[13:43:10.242] <TB3> INFO: 655360 events read in total (11654ms).
[13:43:10.262] <TB3> INFO: Expecting 655360 events.
[13:43:22.518] <TB3> INFO: 655360 events read in total (11684ms).
[13:43:22.541] <TB3> INFO: Expecting 655360 events.
[13:43:34.804] <TB3> INFO: 655360 events read in total (11693ms).
[13:43:34.827] <TB3> INFO: Expecting 655360 events.
[13:43:47.019] <TB3> INFO: 655360 events read in total (11602ms).
[13:43:47.045] <TB3> INFO: Expecting 655360 events.
[13:43:59.304] <TB3> INFO: 655360 events read in total (11683ms).
[13:43:59.345] <TB3> INFO: Expecting 655360 events.
[13:44:11.433] <TB3> INFO: 655360 events read in total (11539ms).
[13:44:11.469] <TB3> INFO: Expecting 655360 events.
[13:44:23.652] <TB3> INFO: 655360 events read in total (11627ms).
[13:44:23.688] <TB3> INFO: Expecting 655360 events.
[13:44:35.797] <TB3> INFO: 655360 events read in total (11539ms).
[13:44:35.838] <TB3> INFO: Expecting 655360 events.
[13:44:48.195] <TB3> INFO: 655360 events read in total (11796ms).
[13:44:48.257] <TB3> INFO: Expecting 655360 events.
[13:44:59.684] <TB3> INFO: 655360 events read in total (10884ms).
[13:44:59.732] <TB3> INFO: Expecting 655360 events.
[13:45:10.962] <TB3> INFO: 655360 events read in total (10671ms).
[13:45:11.013] <TB3> INFO: Expecting 655360 events.
[13:45:22.261] <TB3> INFO: 655360 events read in total (10687ms).
[13:45:22.318] <TB3> INFO: Expecting 655360 events.
[13:45:33.783] <TB3> INFO: 655360 events read in total (10914ms).
[13:45:33.844] <TB3> INFO: Expecting 655360 events.
[13:45:45.990] <TB3> INFO: 655360 events read in total (11593ms).
[13:45:46.064] <TB3> INFO: Test took 193107ms.
[13:45:46.150] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:46.457] <TB3> INFO: Expecting 655360 events.
[13:45:58.826] <TB3> INFO: 655360 events read in total (11654ms).
[13:45:58.835] <TB3> INFO: Expecting 655360 events.
[13:46:10.971] <TB3> INFO: 655360 events read in total (11542ms).
[13:46:10.983] <TB3> INFO: Expecting 655360 events.
[13:46:23.228] <TB3> INFO: 655360 events read in total (11642ms).
[13:46:23.245] <TB3> INFO: Expecting 655360 events.
[13:46:35.432] <TB3> INFO: 655360 events read in total (11589ms).
[13:46:35.452] <TB3> INFO: Expecting 655360 events.
[13:46:47.692] <TB3> INFO: 655360 events read in total (11651ms).
[13:46:47.721] <TB3> INFO: Expecting 655360 events.
[13:46:59.944] <TB3> INFO: 655360 events read in total (11672ms).
[13:46:59.971] <TB3> INFO: Expecting 655360 events.
[13:47:11.976] <TB3> INFO: 655360 events read in total (11434ms).
[13:47:12.006] <TB3> INFO: Expecting 655360 events.
[13:47:24.222] <TB3> INFO: 655360 events read in total (11625ms).
[13:47:24.259] <TB3> INFO: Expecting 655360 events.
[13:47:36.567] <TB3> INFO: 655360 events read in total (11755ms).
[13:47:36.605] <TB3> INFO: Expecting 655360 events.
[13:47:48.939] <TB3> INFO: 655360 events read in total (11776ms).
[13:47:48.987] <TB3> INFO: Expecting 655360 events.
[13:47:54.269] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L480> Channel 1 has NoTokenPass but 8 ROCs were found
[13:48:00.284] <TB3> INFO: 655360 events read in total (10762ms).
[13:48:00.329] <TB3> INFO: Expecting 655360 events.
[13:48:11.581] <TB3> INFO: 655360 events read in total (10701ms).
[13:48:11.629] <TB3> INFO: Expecting 655360 events.
[13:48:22.849] <TB3> INFO: 655360 events read in total (10656ms).
[13:48:22.912] <TB3> INFO: Expecting 655360 events.
[13:48:28.250] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[13:48:28.250] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (4) != Token Chain Length (8)
[13:48:34.250] <TB3> INFO: 655360 events read in total (10798ms).
[13:48:34.323] <TB3> INFO: Expecting 655360 events.
[13:48:46.400] <TB3> INFO: 655360 events read in total (11550ms).
[13:48:46.516] <TB3> INFO: Expecting 655360 events.
[13:48:58.655] <TB3> INFO: 655360 events read in total (11612ms).
[13:48:58.743] <TB3> INFO: Test took 192594ms.
[13:48:58.974] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:48:58.983] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:48:58.993] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:48:59.002] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:48:59.011] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:48:59.021] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:48:59.028] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:48:59.038] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:48:59.047] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:48:59.057] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:48:59.067] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:48:59.077] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:48:59.086] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:48:59.095] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:48:59.103] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:48:59.110] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:48:59.154] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C0.dat
[13:48:59.155] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C1.dat
[13:48:59.155] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C2.dat
[13:48:59.155] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C3.dat
[13:48:59.155] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C4.dat
[13:48:59.155] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C5.dat
[13:48:59.156] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C6.dat
[13:48:59.156] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C7.dat
[13:48:59.156] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C8.dat
[13:48:59.156] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C9.dat
[13:48:59.157] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C10.dat
[13:48:59.157] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C11.dat
[13:48:59.157] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C12.dat
[13:48:59.157] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C13.dat
[13:48:59.157] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C14.dat
[13:48:59.158] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//dacParameters35_C15.dat
[13:48:59.517] <TB3> INFO: Expecting 41600 events.
[13:49:03.397] <TB3> INFO: 41600 events read in total (3165ms).
[13:49:03.398] <TB3> INFO: Test took 4237ms.
[13:49:04.069] <TB3> INFO: Expecting 41600 events.
[13:49:06.874] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[13:49:07.955] <TB3> INFO: 41600 events read in total (3171ms).
[13:49:07.956] <TB3> INFO: Test took 4223ms.
[13:49:08.603] <TB3> INFO: Expecting 41600 events.
[13:49:12.460] <TB3> INFO: 41600 events read in total (3142ms).
[13:49:12.461] <TB3> INFO: Test took 4201ms.
[13:49:12.784] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:12.916] <TB3> INFO: Expecting 2560 events.
[13:49:13.879] <TB3> INFO: 2560 events read in total (246ms).
[13:49:13.880] <TB3> INFO: Test took 1096ms.
[13:49:13.882] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:14.388] <TB3> INFO: Expecting 2560 events.
[13:49:15.352] <TB3> INFO: 2560 events read in total (249ms).
[13:49:15.353] <TB3> INFO: Test took 1471ms.
[13:49:15.355] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:15.861] <TB3> INFO: Expecting 2560 events.
[13:49:16.822] <TB3> INFO: 2560 events read in total (246ms).
[13:49:16.822] <TB3> INFO: Test took 1467ms.
[13:49:16.824] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:17.332] <TB3> INFO: Expecting 2560 events.
[13:49:18.296] <TB3> INFO: 2560 events read in total (249ms).
[13:49:18.296] <TB3> INFO: Test took 1472ms.
[13:49:18.300] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:18.805] <TB3> INFO: Expecting 2560 events.
[13:49:19.768] <TB3> INFO: 2560 events read in total (248ms).
[13:49:19.768] <TB3> INFO: Test took 1469ms.
[13:49:19.771] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:20.277] <TB3> INFO: Expecting 2560 events.
[13:49:21.238] <TB3> INFO: 2560 events read in total (246ms).
[13:49:21.239] <TB3> INFO: Test took 1468ms.
[13:49:21.242] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:21.747] <TB3> INFO: Expecting 2560 events.
[13:49:22.708] <TB3> INFO: 2560 events read in total (246ms).
[13:49:22.708] <TB3> INFO: Test took 1466ms.
[13:49:22.711] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:23.217] <TB3> INFO: Expecting 2560 events.
[13:49:24.181] <TB3> INFO: 2560 events read in total (249ms).
[13:49:24.181] <TB3> INFO: Test took 1470ms.
[13:49:24.184] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:24.690] <TB3> INFO: Expecting 2560 events.
[13:49:25.651] <TB3> INFO: 2560 events read in total (246ms).
[13:49:25.652] <TB3> INFO: Test took 1468ms.
[13:49:25.654] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:26.161] <TB3> INFO: Expecting 2560 events.
[13:49:27.122] <TB3> INFO: 2560 events read in total (247ms).
[13:49:27.123] <TB3> INFO: Test took 1469ms.
[13:49:27.125] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:27.632] <TB3> INFO: Expecting 2560 events.
[13:49:28.595] <TB3> INFO: 2560 events read in total (248ms).
[13:49:28.595] <TB3> INFO: Test took 1470ms.
[13:49:28.598] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:29.104] <TB3> INFO: Expecting 2560 events.
[13:49:30.067] <TB3> INFO: 2560 events read in total (248ms).
[13:49:30.069] <TB3> INFO: Test took 1471ms.
[13:49:30.071] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:30.577] <TB3> INFO: Expecting 2560 events.
[13:49:31.538] <TB3> INFO: 2560 events read in total (246ms).
[13:49:31.538] <TB3> INFO: Test took 1467ms.
[13:49:31.541] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:32.047] <TB3> INFO: Expecting 2560 events.
[13:49:33.009] <TB3> INFO: 2560 events read in total (247ms).
[13:49:33.010] <TB3> INFO: Test took 1470ms.
[13:49:33.012] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:33.518] <TB3> INFO: Expecting 2560 events.
[13:49:34.480] <TB3> INFO: 2560 events read in total (248ms).
[13:49:34.481] <TB3> INFO: Test took 1469ms.
[13:49:34.484] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:34.989] <TB3> INFO: Expecting 2560 events.
[13:49:35.952] <TB3> INFO: 2560 events read in total (248ms).
[13:49:35.953] <TB3> INFO: Test took 1469ms.
[13:49:35.955] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:36.462] <TB3> INFO: Expecting 2560 events.
[13:49:37.425] <TB3> INFO: 2560 events read in total (248ms).
[13:49:37.425] <TB3> INFO: Test took 1471ms.
[13:49:37.428] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:37.934] <TB3> INFO: Expecting 2560 events.
[13:49:38.897] <TB3> INFO: 2560 events read in total (248ms).
[13:49:38.899] <TB3> INFO: Test took 1471ms.
[13:49:38.902] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:39.406] <TB3> INFO: Expecting 2560 events.
[13:49:40.368] <TB3> INFO: 2560 events read in total (247ms).
[13:49:40.368] <TB3> INFO: Test took 1466ms.
[13:49:40.371] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:40.877] <TB3> INFO: Expecting 2560 events.
[13:49:41.839] <TB3> INFO: 2560 events read in total (247ms).
[13:49:41.840] <TB3> INFO: Test took 1469ms.
[13:49:41.843] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:42.349] <TB3> INFO: Expecting 2560 events.
[13:49:43.311] <TB3> INFO: 2560 events read in total (247ms).
[13:49:43.311] <TB3> INFO: Test took 1469ms.
[13:49:43.316] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:43.820] <TB3> INFO: Expecting 2560 events.
[13:49:44.783] <TB3> INFO: 2560 events read in total (248ms).
[13:49:44.783] <TB3> INFO: Test took 1467ms.
[13:49:44.787] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:45.292] <TB3> INFO: Expecting 2560 events.
[13:49:46.256] <TB3> INFO: 2560 events read in total (249ms).
[13:49:46.256] <TB3> INFO: Test took 1469ms.
[13:49:46.259] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:46.764] <TB3> INFO: Expecting 2560 events.
[13:49:47.726] <TB3> INFO: 2560 events read in total (247ms).
[13:49:47.727] <TB3> INFO: Test took 1468ms.
[13:49:47.730] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:48.236] <TB3> INFO: Expecting 2560 events.
[13:49:49.199] <TB3> INFO: 2560 events read in total (248ms).
[13:49:49.199] <TB3> INFO: Test took 1470ms.
[13:49:49.203] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:49.708] <TB3> INFO: Expecting 2560 events.
[13:49:50.673] <TB3> INFO: 2560 events read in total (250ms).
[13:49:50.674] <TB3> INFO: Test took 1471ms.
[13:49:50.677] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:51.182] <TB3> INFO: Expecting 2560 events.
[13:49:52.145] <TB3> INFO: 2560 events read in total (248ms).
[13:49:52.145] <TB3> INFO: Test took 1468ms.
[13:49:52.147] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:52.654] <TB3> INFO: Expecting 2560 events.
[13:49:53.618] <TB3> INFO: 2560 events read in total (249ms).
[13:49:53.618] <TB3> INFO: Test took 1471ms.
[13:49:53.621] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:54.126] <TB3> INFO: Expecting 2560 events.
[13:49:55.082] <TB3> INFO: 2560 events read in total (241ms).
[13:49:55.083] <TB3> INFO: Test took 1462ms.
[13:49:55.085] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:55.592] <TB3> INFO: Expecting 2560 events.
[13:49:56.555] <TB3> INFO: 2560 events read in total (248ms).
[13:49:56.555] <TB3> INFO: Test took 1471ms.
[13:49:56.558] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:57.064] <TB3> INFO: Expecting 2560 events.
[13:49:58.026] <TB3> INFO: 2560 events read in total (247ms).
[13:49:58.026] <TB3> INFO: Test took 1468ms.
[13:49:58.029] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:49:58.535] <TB3> INFO: Expecting 2560 events.
[13:49:59.499] <TB3> INFO: 2560 events read in total (249ms).
[13:49:59.500] <TB3> INFO: Test took 1471ms.
[13:50:00.232] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 485 seconds
[13:50:00.232] <TB3> INFO: PH scale (per ROC): 90 80 75 74 82 80 84 83 80 81 83 79 98 80 73 80
[13:50:00.232] <TB3> INFO: PH offset (per ROC): 157 154 184 166 156 144 159 154 147 161 141 138 131 150 158 150
[13:50:00.237] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:00.237] <TB3> INFO: Decoding statistics:
[13:50:00.237] <TB3> INFO: General information:
[13:50:00.237] <TB3> INFO: 16bit words read: 66442
[13:50:00.237] <TB3> INFO: valid events total: 5120
[13:50:00.237] <TB3> INFO: empty events: 2619
[13:50:00.237] <TB3> INFO: valid events with pixels: 2501
[13:50:00.237] <TB3> INFO: valid pixel hits: 2501
[13:50:00.237] <TB3> INFO: Event errors: 0
[13:50:00.237] <TB3> INFO: start marker: 0
[13:50:00.237] <TB3> INFO: stop marker: 0
[13:50:00.237] <TB3> INFO: overflow: 0
[13:50:00.237] <TB3> INFO: invalid 5bit words: 0
[13:50:00.237] <TB3> INFO: invalid XOR eye diagram: 0
[13:50:00.237] <TB3> INFO: TBM errors: 0
[13:50:00.237] <TB3> INFO: flawed TBM headers: 0
[13:50:00.237] <TB3> INFO: flawed TBM trailers: 0
[13:50:00.237] <TB3> INFO: event ID mismatches: 0
[13:50:00.237] <TB3> INFO: ROC errors: 0
[13:50:00.237] <TB3> INFO: missing ROC header(s): 0
[13:50:00.238] <TB3> INFO: misplaced readback start: 0
[13:50:00.238] <TB3> INFO: Pixel decoding errors: 0
[13:50:00.238] <TB3> INFO: pixel data incomplete: 0
[13:50:00.238] <TB3> INFO: pixel address: 0
[13:50:00.238] <TB3> INFO: pulse height fill bit: 0
[13:50:00.238] <TB3> INFO: buffer corruption: 0
[13:50:00.441] <TB3> INFO: ######################################################################
[13:50:00.441] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[13:50:00.441] <TB3> INFO: ######################################################################
[13:50:00.451] <TB3> INFO: scanning low vcal = 10
[13:50:00.758] <TB3> INFO: Expecting 41600 events.
[13:50:04.520] <TB3> INFO: 41600 events read in total (3047ms).
[13:50:04.520] <TB3> INFO: Test took 4069ms.
[13:50:04.522] <TB3> INFO: scanning low vcal = 20
[13:50:05.029] <TB3> INFO: Expecting 41600 events.
[13:50:08.787] <TB3> INFO: 41600 events read in total (3043ms).
[13:50:08.788] <TB3> INFO: Test took 4266ms.
[13:50:08.790] <TB3> INFO: scanning low vcal = 30
[13:50:09.295] <TB3> INFO: Expecting 41600 events.
[13:50:13.097] <TB3> INFO: 41600 events read in total (3087ms).
[13:50:13.098] <TB3> INFO: Test took 4308ms.
[13:50:13.100] <TB3> INFO: scanning low vcal = 40
[13:50:13.594] <TB3> INFO: Expecting 41600 events.
[13:50:17.870] <TB3> INFO: 41600 events read in total (3561ms).
[13:50:17.870] <TB3> INFO: Test took 4770ms.
[13:50:17.873] <TB3> INFO: scanning low vcal = 50
[13:50:18.323] <TB3> INFO: Expecting 41600 events.
[13:50:22.707] <TB3> INFO: 41600 events read in total (3669ms).
[13:50:22.708] <TB3> INFO: Test took 4835ms.
[13:50:22.711] <TB3> INFO: scanning low vcal = 60
[13:50:23.132] <TB3> INFO: Expecting 41600 events.
[13:50:27.511] <TB3> INFO: 41600 events read in total (3664ms).
[13:50:27.511] <TB3> INFO: Test took 4800ms.
[13:50:27.514] <TB3> INFO: scanning low vcal = 70
[13:50:27.951] <TB3> INFO: Expecting 41600 events.
[13:50:32.261] <TB3> INFO: 41600 events read in total (3595ms).
[13:50:32.262] <TB3> INFO: Test took 4748ms.
[13:50:32.264] <TB3> INFO: scanning low vcal = 80
[13:50:32.712] <TB3> INFO: Expecting 41600 events.
[13:50:37.052] <TB3> INFO: 41600 events read in total (3625ms).
[13:50:37.053] <TB3> INFO: Test took 4789ms.
[13:50:37.055] <TB3> INFO: scanning low vcal = 90
[13:50:37.500] <TB3> INFO: Expecting 41600 events.
[13:50:41.710] <TB3> INFO: 41600 events read in total (3495ms).
[13:50:41.712] <TB3> INFO: Test took 4656ms.
[13:50:41.717] <TB3> INFO: scanning low vcal = 100
[13:50:42.140] <TB3> INFO: Expecting 41600 events.
[13:50:46.478] <TB3> INFO: 41600 events read in total (3623ms).
[13:50:46.479] <TB3> INFO: Test took 4762ms.
[13:50:46.482] <TB3> INFO: scanning low vcal = 110
[13:50:46.927] <TB3> INFO: Expecting 41600 events.
[13:50:51.144] <TB3> INFO: 41600 events read in total (3502ms).
[13:50:51.145] <TB3> INFO: Test took 4663ms.
[13:50:51.147] <TB3> INFO: scanning low vcal = 120
[13:50:51.580] <TB3> INFO: Expecting 41600 events.
[13:50:55.807] <TB3> INFO: 41600 events read in total (3512ms).
[13:50:55.808] <TB3> INFO: Test took 4661ms.
[13:50:55.811] <TB3> INFO: scanning low vcal = 130
[13:50:56.240] <TB3> INFO: Expecting 41600 events.
[13:51:00.424] <TB3> INFO: 41600 events read in total (3469ms).
[13:51:00.424] <TB3> INFO: Test took 4613ms.
[13:51:00.427] <TB3> INFO: scanning low vcal = 140
[13:51:00.866] <TB3> INFO: Expecting 41600 events.
[13:51:05.064] <TB3> INFO: 41600 events read in total (3483ms).
[13:51:05.065] <TB3> INFO: Test took 4638ms.
[13:51:05.068] <TB3> INFO: scanning low vcal = 150
[13:51:05.490] <TB3> INFO: Expecting 41600 events.
[13:51:09.683] <TB3> INFO: 41600 events read in total (3478ms).
[13:51:09.684] <TB3> INFO: Test took 4616ms.
[13:51:09.686] <TB3> INFO: scanning low vcal = 160
[13:51:10.118] <TB3> INFO: Expecting 41600 events.
[13:51:14.466] <TB3> INFO: 41600 events read in total (3634ms).
[13:51:14.467] <TB3> INFO: Test took 4781ms.
[13:51:14.469] <TB3> INFO: scanning low vcal = 170
[13:51:14.905] <TB3> INFO: Expecting 41600 events.
[13:51:19.263] <TB3> INFO: 41600 events read in total (3643ms).
[13:51:19.264] <TB3> INFO: Test took 4795ms.
[13:51:19.274] <TB3> INFO: scanning low vcal = 180
[13:51:19.702] <TB3> INFO: Expecting 41600 events.
[13:51:24.030] <TB3> INFO: 41600 events read in total (3613ms).
[13:51:24.030] <TB3> INFO: Test took 4756ms.
[13:51:24.033] <TB3> INFO: scanning low vcal = 190
[13:51:24.478] <TB3> INFO: Expecting 41600 events.
[13:51:28.816] <TB3> INFO: 41600 events read in total (3622ms).
[13:51:28.817] <TB3> INFO: Test took 4784ms.
[13:51:28.820] <TB3> INFO: scanning low vcal = 200
[13:51:29.265] <TB3> INFO: Expecting 41600 events.
[13:51:33.629] <TB3> INFO: 41600 events read in total (3649ms).
[13:51:33.629] <TB3> INFO: Test took 4809ms.
[13:51:33.632] <TB3> INFO: scanning low vcal = 210
[13:51:34.079] <TB3> INFO: Expecting 41600 events.
[13:51:38.396] <TB3> INFO: 41600 events read in total (3602ms).
[13:51:38.397] <TB3> INFO: Test took 4765ms.
[13:51:38.399] <TB3> INFO: scanning low vcal = 220
[13:51:38.835] <TB3> INFO: Expecting 41600 events.
[13:51:43.129] <TB3> INFO: 41600 events read in total (3578ms).
[13:51:43.129] <TB3> INFO: Test took 4730ms.
[13:51:43.132] <TB3> INFO: scanning low vcal = 230
[13:51:43.581] <TB3> INFO: Expecting 41600 events.
[13:51:47.947] <TB3> INFO: 41600 events read in total (3650ms).
[13:51:47.948] <TB3> INFO: Test took 4816ms.
[13:51:47.951] <TB3> INFO: scanning low vcal = 240
[13:51:48.392] <TB3> INFO: Expecting 41600 events.
[13:51:52.729] <TB3> INFO: 41600 events read in total (3622ms).
[13:51:52.730] <TB3> INFO: Test took 4779ms.
[13:51:52.732] <TB3> INFO: scanning low vcal = 250
[13:51:53.180] <TB3> INFO: Expecting 41600 events.
[13:51:57.499] <TB3> INFO: 41600 events read in total (3604ms).
[13:51:57.499] <TB3> INFO: Test took 4767ms.
[13:51:57.503] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[13:51:57.952] <TB3> INFO: Expecting 41600 events.
[13:52:02.264] <TB3> INFO: 41600 events read in total (3597ms).
[13:52:02.265] <TB3> INFO: Test took 4761ms.
[13:52:02.267] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[13:52:02.715] <TB3> INFO: Expecting 41600 events.
[13:52:07.026] <TB3> INFO: 41600 events read in total (3595ms).
[13:52:07.027] <TB3> INFO: Test took 4760ms.
[13:52:07.030] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[13:52:07.468] <TB3> INFO: Expecting 41600 events.
[13:52:11.841] <TB3> INFO: 41600 events read in total (3658ms).
[13:52:11.842] <TB3> INFO: Test took 4812ms.
[13:52:11.846] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[13:52:12.276] <TB3> INFO: Expecting 41600 events.
[13:52:16.640] <TB3> INFO: 41600 events read in total (3649ms).
[13:52:16.640] <TB3> INFO: Test took 4794ms.
[13:52:16.643] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[13:52:17.073] <TB3> INFO: Expecting 41600 events.
[13:52:21.385] <TB3> INFO: 41600 events read in total (3596ms).
[13:52:21.385] <TB3> INFO: Test took 4742ms.
[13:52:21.854] <TB3> INFO: PixTestGainPedestal::measure() done
[13:52:54.126] <TB3> INFO: PixTestGainPedestal::fit() done
[13:52:54.126] <TB3> INFO: non-linearity mean: 0.957 0.956 0.950 0.958 0.957 0.953 0.956 0.956 0.953 0.955 0.955 0.954 0.955 0.952 0.955 0.955
[13:52:54.126] <TB3> INFO: non-linearity RMS: 0.006 0.006 0.006 0.007 0.005 0.006 0.004 0.006 0.005 0.005 0.004 0.005 0.004 0.005 0.005 0.006
[13:52:54.126] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[13:52:54.148] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[13:52:54.168] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[13:52:54.188] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[13:52:54.208] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[13:52:54.228] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[13:52:54.249] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[13:52:54.269] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[13:52:54.289] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[13:52:54.309] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[13:52:54.329] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[13:52:54.349] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[13:52:54.370] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[13:52:54.390] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[13:52:54.410] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[13:52:54.428] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[13:52:54.447] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 174 seconds
[13:52:54.447] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:54.447] <TB3> INFO: Decoding statistics:
[13:52:54.447] <TB3> INFO: General information:
[13:52:54.447] <TB3> INFO: 16bit words read: 2329502
[13:52:54.447] <TB3> INFO: valid events total: 83200
[13:52:54.447] <TB3> INFO: empty events: 0
[13:52:54.447] <TB3> INFO: valid events with pixels: 83200
[13:52:54.447] <TB3> INFO: valid pixel hits: 665551
[13:52:54.447] <TB3> INFO: Event errors: 0
[13:52:54.447] <TB3> INFO: start marker: 0
[13:52:54.447] <TB3> INFO: stop marker: 0
[13:52:54.447] <TB3> INFO: overflow: 0
[13:52:54.447] <TB3> INFO: invalid 5bit words: 0
[13:52:54.447] <TB3> INFO: invalid XOR eye diagram: 0
[13:52:54.447] <TB3> INFO: TBM errors: 0
[13:52:54.447] <TB3> INFO: flawed TBM headers: 0
[13:52:54.447] <TB3> INFO: flawed TBM trailers: 0
[13:52:54.447] <TB3> INFO: event ID mismatches: 0
[13:52:54.447] <TB3> INFO: ROC errors: 0
[13:52:54.447] <TB3> INFO: missing ROC header(s): 0
[13:52:54.447] <TB3> INFO: misplaced readback start: 0
[13:52:54.447] <TB3> INFO: Pixel decoding errors: 0
[13:52:54.447] <TB3> INFO: pixel data incomplete: 0
[13:52:54.447] <TB3> INFO: pixel address: 0
[13:52:54.447] <TB3> INFO: pulse height fill bit: 0
[13:52:54.447] <TB3> INFO: buffer corruption: 0
[13:52:54.456] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C15.dat
[13:52:54.458] <TB3> INFO: ######################################################################
[13:52:54.458] <TB3> INFO: PixTestReadback::doTest()
[13:52:54.458] <TB3> INFO: ######################################################################
[13:52:54.459] <TB3> INFO: PixTestReadback::RES sent once
[13:53:05.708] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C0.dat
[13:53:05.708] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C1.dat
[13:53:05.708] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C2.dat
[13:53:05.708] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C3.dat
[13:53:05.708] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C4.dat
[13:53:05.708] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C5.dat
[13:53:05.708] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C6.dat
[13:53:05.708] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C7.dat
[13:53:05.708] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C8.dat
[13:53:05.709] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C9.dat
[13:53:05.709] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C10.dat
[13:53:05.709] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C11.dat
[13:53:05.709] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C12.dat
[13:53:05.709] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C13.dat
[13:53:05.709] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C14.dat
[13:53:05.709] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C15.dat
[13:53:05.742] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:53:05.742] <TB3> INFO: PixTestReadback::RES sent once
[13:53:16.927] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C0.dat
[13:53:16.927] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C1.dat
[13:53:16.928] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C2.dat
[13:53:16.928] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C3.dat
[13:53:16.928] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C4.dat
[13:53:16.928] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C5.dat
[13:53:16.928] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C6.dat
[13:53:16.928] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C7.dat
[13:53:16.928] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C8.dat
[13:53:16.928] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C9.dat
[13:53:16.928] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C10.dat
[13:53:16.928] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C11.dat
[13:53:16.929] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C12.dat
[13:53:16.929] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C13.dat
[13:53:16.929] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C14.dat
[13:53:16.929] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C15.dat
[13:53:16.962] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:53:16.963] <TB3> INFO: PixTestReadback::RES sent once
[13:53:25.552] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:53:25.552] <TB3> INFO: Vbg will be calibrated using Vd calibration
[13:53:25.552] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 150.4calibrated Vbg = 1.23831 :::*/*/*/*/
[13:53:25.553] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 149.9calibrated Vbg = 1.23678 :::*/*/*/*/
[13:53:25.553] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 146.4calibrated Vbg = 1.24007 :::*/*/*/*/
[13:53:25.553] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 148.3calibrated Vbg = 1.24603 :::*/*/*/*/
[13:53:25.553] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 155.9calibrated Vbg = 1.24591 :::*/*/*/*/
[13:53:25.553] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 150.9calibrated Vbg = 1.25218 :::*/*/*/*/
[13:53:25.553] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 153.1calibrated Vbg = 1.24524 :::*/*/*/*/
[13:53:25.553] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 148calibrated Vbg = 1.2516 :::*/*/*/*/
[13:53:25.553] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 151.6calibrated Vbg = 1.25962 :::*/*/*/*/
[13:53:25.553] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 156.2calibrated Vbg = 1.25994 :::*/*/*/*/
[13:53:25.553] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 151.1calibrated Vbg = 1.26304 :::*/*/*/*/
[13:53:25.553] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 154.1calibrated Vbg = 1.25609 :::*/*/*/*/
[13:53:25.553] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 148.4calibrated Vbg = 1.24694 :::*/*/*/*/
[13:53:25.553] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 147.4calibrated Vbg = 1.23787 :::*/*/*/*/
[13:53:25.553] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 157calibrated Vbg = 1.24155 :::*/*/*/*/
[13:53:25.553] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 159.2calibrated Vbg = 1.24326 :::*/*/*/*/
[13:53:25.556] <TB3> INFO: PixTestReadback::RES sent once
[13:56:19.542] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C0.dat
[13:56:19.542] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C1.dat
[13:56:19.543] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C2.dat
[13:56:19.543] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C3.dat
[13:56:19.543] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C4.dat
[13:56:19.543] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C5.dat
[13:56:19.543] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C6.dat
[13:56:19.543] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C7.dat
[13:56:19.543] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C8.dat
[13:56:19.543] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C9.dat
[13:56:19.544] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C10.dat
[13:56:19.544] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C11.dat
[13:56:19.544] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C12.dat
[13:56:19.544] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C13.dat
[13:56:19.544] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C14.dat
[13:56:19.544] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M4080_FullQualification_2015-12-08_13h26m_1449577585//000_FulltestPxar_m20//readbackCal_C15.dat
[13:56:19.575] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:56:19.575] <TB3> INFO: PixTestReadback::doTest() done
[13:56:19.576] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:19.576] <TB3> INFO: Decoding statistics:
[13:56:19.576] <TB3> INFO: General information:
[13:56:19.576] <TB3> INFO: 16bit words read: 768
[13:56:19.576] <TB3> INFO: valid events total: 64
[13:56:19.576] <TB3> INFO: empty events: 64
[13:56:19.576] <TB3> INFO: valid events with pixels: 0
[13:56:19.576] <TB3> INFO: valid pixel hits: 0
[13:56:19.576] <TB3> INFO: Event errors: 0
[13:56:19.576] <TB3> INFO: start marker: 0
[13:56:19.576] <TB3> INFO: stop marker: 0
[13:56:19.576] <TB3> INFO: overflow: 0
[13:56:19.576] <TB3> INFO: invalid 5bit words: 0
[13:56:19.576] <TB3> INFO: invalid XOR eye diagram: 0
[13:56:19.576] <TB3> INFO: TBM errors: 0
[13:56:19.576] <TB3> INFO: flawed TBM headers: 0
[13:56:19.576] <TB3> INFO: flawed TBM trailers: 0
[13:56:19.576] <TB3> INFO: event ID mismatches: 0
[13:56:19.576] <TB3> INFO: ROC errors: 0
[13:56:19.576] <TB3> INFO: missing ROC header(s): 0
[13:56:19.576] <TB3> INFO: misplaced readback start: 0
[13:56:19.576] <TB3> INFO: Pixel decoding errors: 0
[13:56:19.576] <TB3> INFO: pixel data incomplete: 0
[13:56:19.576] <TB3> INFO: pixel address: 0
[13:56:19.576] <TB3> INFO: pulse height fill bit: 0
[13:56:19.576] <TB3> INFO: buffer corruption: 0
[13:56:19.589] <TB3> INFO: Decoding statistics:
[13:56:19.589] <TB3> INFO: General information:
[13:56:19.589] <TB3> INFO: 16bit words read: 2396712
[13:56:19.589] <TB3> INFO: valid events total: 88384
[13:56:19.589] <TB3> INFO: empty events: 2683
[13:56:19.589] <TB3> INFO: valid events with pixels: 85701
[13:56:19.589] <TB3> INFO: valid pixel hits: 668052
[13:56:19.589] <TB3> INFO: Event errors: 0
[13:56:19.589] <TB3> INFO: start marker: 0
[13:56:19.589] <TB3> INFO: stop marker: 0
[13:56:19.589] <TB3> INFO: overflow: 0
[13:56:19.589] <TB3> INFO: invalid 5bit words: 0
[13:56:19.589] <TB3> INFO: invalid XOR eye diagram: 0
[13:56:19.589] <TB3> INFO: TBM errors: 0
[13:56:19.589] <TB3> INFO: flawed TBM headers: 0
[13:56:19.589] <TB3> INFO: flawed TBM trailers: 0
[13:56:19.589] <TB3> INFO: event ID mismatches: 0
[13:56:19.589] <TB3> INFO: ROC errors: 0
[13:56:19.589] <TB3> INFO: missing ROC header(s): 0
[13:56:19.589] <TB3> INFO: misplaced readback start: 0
[13:56:19.589] <TB3> INFO: Pixel decoding errors: 0
[13:56:19.589] <TB3> INFO: pixel data incomplete: 0
[13:56:19.589] <TB3> INFO: pixel address: 0
[13:56:19.589] <TB3> INFO: pulse height fill bit: 0
[13:56:19.589] <TB3> INFO: buffer corruption: 0
[13:56:19.589] <TB3> INFO: enter test to run
[13:56:19.589] <TB3> INFO: test: exit no parameter change
[13:56:19.807] <TB3> QUIET: Connection to board 170 closed.
[13:56:19.887] <TB3> INFO: pXar: this is the end, my friend