[12:32:03.199] <TB3> INFO: *** Welcome to pxar ***
[12:32:03.199] <TB3> INFO: *** Today: 2015/11/20
[12:32:03.312] <TB3> INFO: *** Version: 9da6-dirty
[12:32:03.312] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters_C15.dat
[12:32:03.313] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//tbmParameters_C0b.dat
[12:32:03.314] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//defaultMaskFile.dat
[12:32:03.314] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//trimParameters_C15.dat
[12:32:03.399] <TB3> INFO: clk: 4
[12:32:03.399] <TB3> INFO: ctr: 4
[12:32:03.399] <TB3> INFO: sda: 19
[12:32:03.399] <TB3> INFO: tin: 9
[12:32:03.399] <TB3> INFO: level: 15
[12:32:03.399] <TB3> INFO: triggerdelay: 0
[12:32:03.399] <TB3> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[12:32:03.399] <TB3> INFO: Log level: INFO
[12:32:03.405] <TB3> INFO: Found DTB DTB_WZ4I6J
[12:32:03.421] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[12:32:03.424] <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:32:03.426] <TB3> INFO: RPC call hashes of host and DTB match: 398089610
[12:32:04.987] <TB3> INFO: DUT info:
[12:32:04.987] <TB3> INFO: The DUT currently contains the following objects:
[12:32:04.987] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[12:32:04.987] <TB3> INFO: TBM Core alpha (0): 7 registers set
[12:32:04.987] <TB3> INFO: TBM Core beta (1): 7 registers set
[12:32:04.987] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:32:04.987] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:32:04.987] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:32:04.987] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:32:04.987] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:32:04.987] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:32:04.987] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:32:04.987] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:32:04.987] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:32:04.987] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:32:04.987] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:32:04.987] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:32:04.987] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:32:04.987] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:32:04.987] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:32:04.987] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:32:04.987] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:32:05.388] <TB3> INFO: enter 'restricted' command line mode
[12:32:05.388] <TB3> INFO: enter test to run
[12:32:05.388] <TB3> INFO: test: pretest no parameter change
[12:32:05.388] <TB3> INFO: running: pretest
[12:32:05.396] <TB3> INFO: ######################################################################
[12:32:05.396] <TB3> INFO: PixTestPretest::doTest()
[12:32:05.396] <TB3> INFO: ######################################################################
[12:32:05.398] <TB3> INFO: ----------------------------------------------------------------------
[12:32:05.398] <TB3> INFO: PixTestPretest::programROC()
[12:32:05.398] <TB3> INFO: ----------------------------------------------------------------------
[12:32:23.416] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:32:23.416] <TB3> INFO: IA differences per ROC: 16.1 16.9 18.5 19.3 17.7 17.7 16.9 17.7 19.3 16.1 19.3 17.7 16.9 15.3 18.5 16.9
[12:32:23.496] <TB3> INFO: ----------------------------------------------------------------------
[12:32:23.496] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:32:23.496] <TB3> INFO: ----------------------------------------------------------------------
[12:32:30.197] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 385.9 mA = 24.1188 mA/ROC
[12:32:30.197] <TB3> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 19.3 20.1 19.3 19.3 18.5 19.3 20.1 19.3 19.3 19.3 18.5 19.3 19.3
[12:32:30.233] <TB3> INFO: ----------------------------------------------------------------------
[12:32:30.233] <TB3> INFO: PixTestPretest::findTiming()
[12:32:30.233] <TB3> INFO: ----------------------------------------------------------------------
[12:32:30.233] <TB3> INFO: PixTestCmd::init()

[12:34:27.433] <TB3> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[12:34:27.433] <TB3> INFO: (success/tries = 100/100), width = 3
[12:34:27.436] <TB3> INFO: ----------------------------------------------------------------------
[12:34:27.436] <TB3> INFO: PixTestPretest::findWorkingPixel()
[12:34:27.436] <TB3> INFO: ----------------------------------------------------------------------
[12:34:27.575] <TB3> INFO: Expecting 231680 events.
[12:34:35.928] <TB3> INFO: 231680 events read in total (7637ms).
[12:34:35.932] <TB3> INFO: Test took 8493ms.
[12:34:36.231] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[12:34:36.268] <TB3> INFO: ----------------------------------------------------------------------
[12:34:36.268] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[12:34:36.268] <TB3> INFO: ----------------------------------------------------------------------
[12:34:36.403] <TB3> INFO: Expecting 231680 events.
[12:34:45.100] <TB3> INFO: 231680 events read in total (7981ms).
[12:34:45.104] <TB3> INFO: Test took 8832ms.
[12:34:45.427] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[12:34:45.427] <TB3> INFO: CalDel: 145 141 143 144 138 149 122 130 140 138 162 158 140 131 143 124
[12:34:45.427] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 53 52 51 51 53 54 51 51
[12:34:45.430] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters_C0.dat
[12:34:45.430] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters_C1.dat
[12:34:45.430] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters_C2.dat
[12:34:45.430] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters_C3.dat
[12:34:45.431] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters_C4.dat
[12:34:45.431] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters_C5.dat
[12:34:45.431] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters_C6.dat
[12:34:45.431] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters_C7.dat
[12:34:45.432] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters_C8.dat
[12:34:45.432] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters_C9.dat
[12:34:45.432] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters_C10.dat
[12:34:45.432] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters_C11.dat
[12:34:45.432] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters_C12.dat
[12:34:45.433] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters_C13.dat
[12:34:45.433] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters_C14.dat
[12:34:45.433] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters_C15.dat
[12:34:45.433] <TB3> INFO: write tbm parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//tbmParameters_C0a.dat
[12:34:45.434] <TB3> INFO: write tbm parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//tbmParameters_C0b.dat
[12:34:45.434] <TB3> INFO: PixTestPretest::doTest() done, duration: 160 seconds
[12:34:45.517] <TB3> INFO: enter test to run
[12:34:45.518] <TB3> INFO: test: fulltest no parameter change
[12:34:45.518] <TB3> INFO: running: fulltest
[12:34:45.518] <TB3> INFO: ######################################################################
[12:34:45.518] <TB3> INFO: PixTestFullTest::doTest()
[12:34:45.518] <TB3> INFO: ######################################################################
[12:34:45.519] <TB3> INFO: ######################################################################
[12:34:45.519] <TB3> INFO: PixTestAlive::doTest()
[12:34:45.519] <TB3> INFO: ######################################################################
[12:34:45.521] <TB3> INFO: ----------------------------------------------------------------------
[12:34:45.521] <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:34:45.521] <TB3> INFO: ----------------------------------------------------------------------
[12:34:45.863] <TB3> INFO: Expecting 41600 events.
[12:34:50.253] <TB3> INFO: 41600 events read in total (3674ms).
[12:34:50.254] <TB3> INFO: Test took 4731ms.
[12:34:50.261] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:50.547] <TB3> INFO: PixTestAlive::aliveTest() done
[12:34:50.547] <TB3> INFO: number of dead pixels (per ROC): 2 1 0 0 0 0 0 0 0 0 0 0 0 0 1 1
[12:34:50.549] <TB3> INFO: ----------------------------------------------------------------------
[12:34:50.549] <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:34:50.549] <TB3> INFO: ----------------------------------------------------------------------
[12:34:50.885] <TB3> INFO: Expecting 41600 events.
[12:34:54.017] <TB3> INFO: 41600 events read in total (2416ms).
[12:34:54.018] <TB3> INFO: Test took 3467ms.
[12:34:54.018] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:54.018] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:34:54.331] <TB3> INFO: PixTestAlive::maskTest() done
[12:34:54.331] <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:34:54.332] <TB3> INFO: ----------------------------------------------------------------------
[12:34:54.333] <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:34:54.333] <TB3> INFO: ----------------------------------------------------------------------
[12:34:54.636] <TB3> INFO: Expecting 41600 events.
[12:34:58.796] <TB3> INFO: 41600 events read in total (3444ms).
[12:34:58.796] <TB3> INFO: Test took 4462ms.
[12:34:58.802] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:59.096] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[12:34:59.096] <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:34:59.096] <TB3> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[12:34:59.096] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:59.096] <TB3> INFO: Decoding statistics:
[12:34:59.096] <TB3> INFO: General information:
[12:34:59.096] <TB3> INFO: 16bit words read: 0
[12:34:59.096] <TB3> INFO: valid events total: 0
[12:34:59.096] <TB3> INFO: empty events: 0
[12:34:59.096] <TB3> INFO: valid events with pixels: 0
[12:34:59.096] <TB3> INFO: valid pixel hits: 0
[12:34:59.096] <TB3> INFO: Event errors: 0
[12:34:59.096] <TB3> INFO: start marker: 0
[12:34:59.096] <TB3> INFO: stop marker: 0
[12:34:59.096] <TB3> INFO: overflow: 0
[12:34:59.096] <TB3> INFO: invalid 5bit words: 0
[12:34:59.096] <TB3> INFO: invalid XOR eye diagram: 0
[12:34:59.096] <TB3> INFO: TBM errors: 0
[12:34:59.096] <TB3> INFO: flawed TBM headers: 0
[12:34:59.096] <TB3> INFO: flawed TBM trailers: 0
[12:34:59.096] <TB3> INFO: event ID mismatches: 0
[12:34:59.096] <TB3> INFO: ROC errors: 0
[12:34:59.096] <TB3> INFO: missing ROC header(s): 0
[12:34:59.096] <TB3> INFO: misplaced readback start: 0
[12:34:59.096] <TB3> INFO: Pixel decoding errors: 0
[12:34:59.096] <TB3> INFO: pixel data incomplete: 0
[12:34:59.096] <TB3> INFO: pixel address: 0
[12:34:59.097] <TB3> INFO: pulse height fill bit: 0
[12:34:59.097] <TB3> INFO: buffer corruption: 0
[12:34:59.105] <TB3> INFO: ######################################################################
[12:34:59.105] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[12:34:59.105] <TB3> INFO: ######################################################################
[12:34:59.108] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[12:34:59.131] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[12:34:59.131] <TB3> INFO: run 1 of 1
[12:34:59.441] <TB3> INFO: Expecting 3120000 events.
[12:35:43.000] <TB3> INFO: 986495 events read in total (42843ms).
[12:36:26.606] <TB3> INFO: 1964055 events read in total (86449ms).
[12:37:07.920] <TB3> INFO: 2942720 events read in total (127764ms).
[12:37:15.403] <TB3> INFO: 3120000 events read in total (135246ms).
[12:37:15.439] <TB3> INFO: Test took 136307ms.
[12:37:15.503] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:37:40.639] <TB3> INFO: PixTestBBMap::doTest() done, duration: 161 seconds
[12:37:40.639] <TB3> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:37:40.639] <TB3> INFO: separation cut (per ROC): 133 122 131 136 136 134 129 144 144 139 140 132 145 140 143 134
[12:37:40.639] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:37:40.639] <TB3> INFO: Decoding statistics:
[12:37:40.639] <TB3> INFO: General information:
[12:37:40.639] <TB3> INFO: 16bit words read: 0
[12:37:40.639] <TB3> INFO: valid events total: 0
[12:37:40.639] <TB3> INFO: empty events: 0
[12:37:40.639] <TB3> INFO: valid events with pixels: 0
[12:37:40.639] <TB3> INFO: valid pixel hits: 0
[12:37:40.639] <TB3> INFO: Event errors: 0
[12:37:40.639] <TB3> INFO: start marker: 0
[12:37:40.639] <TB3> INFO: stop marker: 0
[12:37:40.639] <TB3> INFO: overflow: 0
[12:37:40.639] <TB3> INFO: invalid 5bit words: 0
[12:37:40.639] <TB3> INFO: invalid XOR eye diagram: 0
[12:37:40.639] <TB3> INFO: TBM errors: 0
[12:37:40.639] <TB3> INFO: flawed TBM headers: 0
[12:37:40.639] <TB3> INFO: flawed TBM trailers: 0
[12:37:40.639] <TB3> INFO: event ID mismatches: 0
[12:37:40.639] <TB3> INFO: ROC errors: 0
[12:37:40.639] <TB3> INFO: missing ROC header(s): 0
[12:37:40.639] <TB3> INFO: misplaced readback start: 0
[12:37:40.639] <TB3> INFO: Pixel decoding errors: 0
[12:37:40.639] <TB3> INFO: pixel data incomplete: 0
[12:37:40.639] <TB3> INFO: pixel address: 0
[12:37:40.639] <TB3> INFO: pulse height fill bit: 0
[12:37:40.639] <TB3> INFO: buffer corruption: 0
[12:37:40.709] <TB3> INFO: ######################################################################
[12:37:40.709] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:37:40.709] <TB3> INFO: ######################################################################
[12:37:40.710] <TB3> INFO: ----------------------------------------------------------------------
[12:37:40.710] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:37:40.710] <TB3> INFO: ----------------------------------------------------------------------
[12:37:40.710] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:37:40.719] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[12:37:40.719] <TB3> INFO: run 1 of 1
[12:37:41.054] <TB3> INFO: Expecting 26208000 events.
[12:38:12.221] <TB3> INFO: 989550 events read in total (30451ms).
[12:38:42.833] <TB3> INFO: 1963650 events read in total (61063ms).
[12:39:13.256] <TB3> INFO: 2932300 events read in total (91486ms).
[12:39:43.881] <TB3> INFO: 3899500 events read in total (122111ms).
[12:40:14.512] <TB3> INFO: 4862500 events read in total (152742ms).
[12:40:44.805] <TB3> INFO: 5828250 events read in total (183035ms).
[12:41:14.482] <TB3> INFO: 6795300 events read in total (212712ms).
[12:41:44.270] <TB3> INFO: 7759850 events read in total (242500ms).
[12:42:14.213] <TB3> INFO: 8724300 events read in total (272443ms).
[12:42:44.221] <TB3> INFO: 9689500 events read in total (302451ms).
[12:43:14.083] <TB3> INFO: 10651650 events read in total (332313ms).
[12:43:43.628] <TB3> INFO: 11612150 events read in total (361858ms).
[12:44:13.440] <TB3> INFO: 12574100 events read in total (391670ms).
[12:44:43.216] <TB3> INFO: 13525950 events read in total (421446ms).
[12:45:12.784] <TB3> INFO: 14475650 events read in total (451014ms).
[12:45:42.257] <TB3> INFO: 15423100 events read in total (480487ms).
[12:46:11.658] <TB3> INFO: 16369400 events read in total (509888ms).
[12:46:41.257] <TB3> INFO: 17313450 events read in total (539487ms).
[12:47:11.130] <TB3> INFO: 18256250 events read in total (569360ms).
[12:47:41.024] <TB3> INFO: 19199250 events read in total (599254ms).
[12:48:11.083] <TB3> INFO: 20140750 events read in total (629313ms).
[12:48:41.775] <TB3> INFO: 21082000 events read in total (660005ms).
[12:49:12.101] <TB3> INFO: 22022650 events read in total (690331ms).
[12:49:42.126] <TB3> INFO: 22962450 events read in total (720356ms).
[12:50:12.820] <TB3> INFO: 23903400 events read in total (751050ms).
[12:50:42.687] <TB3> INFO: 24845200 events read in total (780917ms).
[12:51:14.155] <TB3> INFO: 25788450 events read in total (812385ms).
[12:51:27.284] <TB3> INFO: 26208000 events read in total (825514ms).
[12:51:27.307] <TB3> INFO: Test took 826587ms.
[12:51:27.367] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:51:27.489] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:51:28.944] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:51:30.576] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:51:32.230] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:51:33.974] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:51:35.690] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:51:37.227] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:51:38.647] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:51:40.062] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:51:41.480] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:51:42.959] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:51:44.414] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:51:45.752] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:51:47.095] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:51:48.461] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:51:49.887] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[12:51:51.318] <TB3> INFO: PixTestScurves::scurves() done
[12:51:51.318] <TB3> INFO: Vcal mean: 100.72 97.14 111.07 100.44 101.72 107.89 95.75 113.32 112.28 110.07 105.99 113.75 115.03 111.93 110.39 105.37
[12:51:51.318] <TB3> INFO: Vcal RMS: 6.20 5.88 5.22 5.64 5.60 5.33 4.98 5.30 6.39 5.31 5.13 5.39 6.00 5.29 5.03 5.32
[12:51:51.318] <TB3> INFO: PixTestScurves::fullTest() done, duration: 850 seconds
[12:51:51.318] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:51:51.318] <TB3> INFO: Decoding statistics:
[12:51:51.318] <TB3> INFO: General information:
[12:51:51.318] <TB3> INFO: 16bit words read: 0
[12:51:51.318] <TB3> INFO: valid events total: 0
[12:51:51.318] <TB3> INFO: empty events: 0
[12:51:51.318] <TB3> INFO: valid events with pixels: 0
[12:51:51.318] <TB3> INFO: valid pixel hits: 0
[12:51:51.318] <TB3> INFO: Event errors: 0
[12:51:51.318] <TB3> INFO: start marker: 0
[12:51:51.318] <TB3> INFO: stop marker: 0
[12:51:51.318] <TB3> INFO: overflow: 0
[12:51:51.318] <TB3> INFO: invalid 5bit words: 0
[12:51:51.318] <TB3> INFO: invalid XOR eye diagram: 0
[12:51:51.318] <TB3> INFO: TBM errors: 0
[12:51:51.318] <TB3> INFO: flawed TBM headers: 0
[12:51:51.318] <TB3> INFO: flawed TBM trailers: 0
[12:51:51.318] <TB3> INFO: event ID mismatches: 0
[12:51:51.319] <TB3> INFO: ROC errors: 0
[12:51:51.319] <TB3> INFO: missing ROC header(s): 0
[12:51:51.319] <TB3> INFO: misplaced readback start: 0
[12:51:51.319] <TB3> INFO: Pixel decoding errors: 0
[12:51:51.319] <TB3> INFO: pixel data incomplete: 0
[12:51:51.319] <TB3> INFO: pixel address: 0
[12:51:51.319] <TB3> INFO: pulse height fill bit: 0
[12:51:51.319] <TB3> INFO: buffer corruption: 0
[12:51:51.394] <TB3> INFO: ######################################################################
[12:51:51.394] <TB3> INFO: PixTestTrim::doTest()
[12:51:51.394] <TB3> INFO: ######################################################################
[12:51:51.395] <TB3> INFO: ----------------------------------------------------------------------
[12:51:51.395] <TB3> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[12:51:51.395] <TB3> INFO: ----------------------------------------------------------------------
[12:51:51.480] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[12:51:51.480] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:51:51.489] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[12:51:51.489] <TB3> INFO: run 1 of 1
[12:51:51.795] <TB3> INFO: Expecting 5025280 events.
[12:52:32.328] <TB3> INFO: 1084520 events read in total (39813ms).
[12:53:08.616] <TB3> INFO: 2163184 events read in total (76102ms).
[12:53:47.610] <TB3> INFO: 3238664 events read in total (115095ms).
[12:54:26.466] <TB3> INFO: 4313448 events read in total (153951ms).
[12:54:53.679] <TB3> INFO: 5025280 events read in total (181165ms).
[12:54:53.706] <TB3> INFO: Test took 182218ms.
[12:54:53.757] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:55:13.785] <TB3> INFO: ROC 0 VthrComp = 100
[12:55:13.785] <TB3> INFO: ROC 1 VthrComp = 95
[12:55:13.785] <TB3> INFO: ROC 2 VthrComp = 104
[12:55:13.785] <TB3> INFO: ROC 3 VthrComp = 103
[12:55:13.785] <TB3> INFO: ROC 4 VthrComp = 103
[12:55:13.785] <TB3> INFO: ROC 5 VthrComp = 105
[12:55:13.785] <TB3> INFO: ROC 6 VthrComp = 103
[12:55:13.786] <TB3> INFO: ROC 7 VthrComp = 108
[12:55:13.786] <TB3> INFO: ROC 8 VthrComp = 105
[12:55:13.786] <TB3> INFO: ROC 9 VthrComp = 105
[12:55:13.787] <TB3> INFO: ROC 10 VthrComp = 106
[12:55:13.787] <TB3> INFO: ROC 11 VthrComp = 104
[12:55:13.787] <TB3> INFO: ROC 12 VthrComp = 108
[12:55:13.787] <TB3> INFO: ROC 13 VthrComp = 107
[12:55:13.788] <TB3> INFO: ROC 14 VthrComp = 109
[12:55:13.788] <TB3> INFO: ROC 15 VthrComp = 105
[12:55:13.788] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[12:55:13.788] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:55:13.800] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[12:55:13.800] <TB3> INFO: run 1 of 1
[12:55:14.136] <TB3> INFO: Expecting 5025280 events.
[12:55:48.476] <TB3> INFO: 760944 events read in total (33619ms).
[12:56:22.673] <TB3> INFO: 1520320 events read in total (67816ms).
[12:56:55.893] <TB3> INFO: 2280856 events read in total (101036ms).
[12:57:30.487] <TB3> INFO: 3036792 events read in total (135630ms).
[12:58:05.139] <TB3> INFO: 3787200 events read in total (170282ms).
[12:58:39.293] <TB3> INFO: 4535384 events read in total (204436ms).
[12:59:02.218] <TB3> INFO: 5025280 events read in total (227361ms).
[12:59:02.263] <TB3> INFO: Test took 228463ms.
[12:59:02.405] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:59:30.172] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 61.2019 for pixel 2/57 mean/min/max = 46.8785/32.1553/61.6018
[12:59:30.172] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 60.3663 for pixel 0/79 mean/min/max = 45.8144/31.1973/60.4316
[12:59:30.172] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 64.6787 for pixel 19/15 mean/min/max = 49.1611/33.4917/64.8305
[12:59:30.173] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 59.4184 for pixel 0/11 mean/min/max = 45.9698/32.4326/59.5071
[12:59:30.173] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 59.598 for pixel 51/22 mean/min/max = 45.7914/31.9407/59.6421
[12:59:30.187] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 63.0218 for pixel 51/20 mean/min/max = 48.535/33.9725/63.0975
[12:59:30.187] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 56.9953 for pixel 22/8 mean/min/max = 44.6303/32.1588/57.1017
[12:59:30.187] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 67.9626 for pixel 0/73 mean/min/max = 52.1518/36.1985/68.1052
[12:59:30.188] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 72.624 for pixel 22/79 mean/min/max = 54.0435/35.4113/72.6757
[12:59:30.188] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 64.5291 for pixel 26/2 mean/min/max = 49.2605/33.7433/64.7776
[12:59:30.189] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 61.026 for pixel 21/79 mean/min/max = 47.7171/34.3308/61.1035
[12:59:30.189] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 65.4867 for pixel 0/78 mean/min/max = 50.0387/34.2146/65.8627
[12:59:30.189] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 67.3877 for pixel 10/73 mean/min/max = 50.6468/33.7868/67.5068
[12:59:30.190] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 65.5134 for pixel 14/1 mean/min/max = 49.8843/34.2539/65.5148
[12:59:30.190] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 61.5429 for pixel 51/25 mean/min/max = 48.0158/34.2787/61.753
[12:59:30.191] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 61.153 for pixel 16/9 mean/min/max = 47.7707/34.048/61.4935
[12:59:30.191] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:59:30.322] <TB3> INFO: Expecting 411648 events.
[12:59:39.621] <TB3> INFO: 411648 events read in total (8583ms).
[12:59:39.626] <TB3> INFO: Expecting 411648 events.
[12:59:48.596] <TB3> INFO: 411648 events read in total (8389ms).
[12:59:48.602] <TB3> INFO: Expecting 411648 events.
[12:59:57.916] <TB3> INFO: 411648 events read in total (8729ms).
[12:59:57.927] <TB3> INFO: Expecting 411648 events.
[13:00:07.217] <TB3> INFO: 411648 events read in total (8728ms).
[13:00:07.227] <TB3> INFO: Expecting 411648 events.
[13:00:16.503] <TB3> INFO: 411648 events read in total (8697ms).
[13:00:16.516] <TB3> INFO: Expecting 411648 events.
[13:00:25.542] <TB3> INFO: 411648 events read in total (8454ms).
[13:00:25.558] <TB3> INFO: Expecting 411648 events.
[13:00:34.898] <TB3> INFO: 411648 events read in total (8763ms).
[13:00:34.914] <TB3> INFO: Expecting 411648 events.
[13:00:44.184] <TB3> INFO: 411648 events read in total (8699ms).
[13:00:44.203] <TB3> INFO: Expecting 411648 events.
[13:00:53.576] <TB3> INFO: 411648 events read in total (8804ms).
[13:00:53.602] <TB3> INFO: Expecting 411648 events.
[13:01:02.415] <TB3> INFO: 411648 events read in total (8252ms).
[13:01:02.438] <TB3> INFO: Expecting 411648 events.
[13:01:11.218] <TB3> INFO: 411648 events read in total (8216ms).
[13:01:11.242] <TB3> INFO: Expecting 411648 events.
[13:01:20.354] <TB3> INFO: 411648 events read in total (8547ms).
[13:01:20.387] <TB3> INFO: Expecting 411648 events.
[13:01:29.937] <TB3> INFO: 411648 events read in total (9001ms).
[13:01:29.965] <TB3> INFO: Expecting 411648 events.
[13:01:39.079] <TB3> INFO: 411648 events read in total (8558ms).
[13:01:39.109] <TB3> INFO: Expecting 411648 events.
[13:01:48.368] <TB3> INFO: 411648 events read in total (8700ms).
[13:01:48.401] <TB3> INFO: Expecting 411648 events.
[13:01:57.736] <TB3> INFO: 411648 events read in total (8783ms).
[13:01:57.772] <TB3> INFO: Test took 147581ms.
[13:01:58.779] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[13:01:58.790] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[13:01:58.790] <TB3> INFO: run 1 of 1
[13:01:59.095] <TB3> INFO: Expecting 5025280 events.
[13:02:34.190] <TB3> INFO: 742472 events read in total (34379ms).
[13:03:09.041] <TB3> INFO: 1485656 events read in total (69230ms).
[13:03:43.556] <TB3> INFO: 2229680 events read in total (103745ms).
[13:04:17.504] <TB3> INFO: 2969672 events read in total (137693ms).
[13:04:51.025] <TB3> INFO: 3703560 events read in total (171214ms).
[13:05:24.659] <TB3> INFO: 4434832 events read in total (204848ms).
[13:05:52.025] <TB3> INFO: 5025280 events read in total (232214ms).
[13:05:52.090] <TB3> INFO: Test took 233299ms.
[13:05:52.258] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:16.550] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.113109 .. 255.000000
[13:06:16.631] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[13:06:16.640] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[13:06:16.640] <TB3> INFO: run 1 of 1
[13:06:16.946] <TB3> INFO: Expecting 8519680 events.
[13:06:51.835] <TB3> INFO: 711912 events read in total (34173ms).
[13:07:26.063] <TB3> INFO: 1424272 events read in total (68401ms).
[13:07:57.221] <TB3> INFO: 2136504 events read in total (99559ms).
[13:08:27.543] <TB3> INFO: 2848608 events read in total (129881ms).
[13:09:01.777] <TB3> INFO: 3560840 events read in total (164115ms).
[13:09:36.861] <TB3> INFO: 4273224 events read in total (199199ms).
[13:10:11.229] <TB3> INFO: 4985808 events read in total (233567ms).
[13:10:44.239] <TB3> INFO: 5698152 events read in total (266577ms).
[13:11:17.389] <TB3> INFO: 6409776 events read in total (299727ms).
[13:11:49.237] <TB3> INFO: 7121400 events read in total (331575ms).
[13:12:21.721] <TB3> INFO: 7832312 events read in total (364059ms).
[13:12:53.586] <TB3> INFO: 8519680 events read in total (395924ms).
[13:12:53.661] <TB3> INFO: Test took 397021ms.
[13:12:53.937] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:13:22.267] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 15.756392 .. 48.006513
[13:13:22.347] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 5 .. 58 (-1/-1) hits flags = 528 (plus default)
[13:13:22.356] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[13:13:22.356] <TB3> INFO: run 1 of 1
[13:13:22.662] <TB3> INFO: Expecting 1797120 events.
[13:14:00.782] <TB3> INFO: 915376 events read in total (37398ms).
[13:14:37.237] <TB3> INFO: 1797120 events read in total (73853ms).
[13:14:37.263] <TB3> INFO: Test took 74907ms.
[13:14:37.303] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:51.901] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 21.491072 .. 45.640534
[13:14:51.981] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 11 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:14:51.989] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[13:14:51.989] <TB3> INFO: run 1 of 1
[13:14:52.309] <TB3> INFO: Expecting 1497600 events.
[13:15:28.125] <TB3> INFO: 904304 events read in total (35100ms).
[13:15:50.927] <TB3> INFO: 1497600 events read in total (57902ms).
[13:15:50.941] <TB3> INFO: Test took 58952ms.
[13:15:50.970] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:16:05.445] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 23.488603 .. 45.640534
[13:16:05.523] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 13 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:16:05.532] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[13:16:05.532] <TB3> INFO: run 1 of 1
[13:16:05.869] <TB3> INFO: Expecting 1431040 events.
[13:16:42.998] <TB3> INFO: 890896 events read in total (36413ms).
[13:17:05.717] <TB3> INFO: 1431040 events read in total (59132ms).
[13:17:05.733] <TB3> INFO: Test took 60201ms.
[13:17:05.763] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:19.404] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[13:17:19.404] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:17:19.414] <TB3> INFO: dacScan split into 1 runs with ntrig = 8
[13:17:19.414] <TB3> INFO: run 1 of 1
[13:17:19.724] <TB3> INFO: Expecting 1364480 events.
[13:17:56.542] <TB3> INFO: 877888 events read in total (36102ms).
[13:18:17.512] <TB3> INFO: 1364480 events read in total (57072ms).
[13:18:17.528] <TB3> INFO: Test took 58114ms.
[13:18:17.560] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:31.102] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C0.dat
[13:18:31.102] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C1.dat
[13:18:31.102] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C2.dat
[13:18:31.102] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C3.dat
[13:18:31.102] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C4.dat
[13:18:31.102] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C5.dat
[13:18:31.102] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C6.dat
[13:18:31.103] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C7.dat
[13:18:31.103] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C8.dat
[13:18:31.103] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C9.dat
[13:18:31.103] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C10.dat
[13:18:31.103] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C11.dat
[13:18:31.103] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C12.dat
[13:18:31.104] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C13.dat
[13:18:31.104] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C14.dat
[13:18:31.104] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C15.dat
[13:18:31.104] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//trimParameters35_C0.dat
[13:18:31.110] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//trimParameters35_C1.dat
[13:18:31.116] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//trimParameters35_C2.dat
[13:18:31.122] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//trimParameters35_C3.dat
[13:18:31.128] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//trimParameters35_C4.dat
[13:18:31.134] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//trimParameters35_C5.dat
[13:18:31.141] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//trimParameters35_C6.dat
[13:18:31.147] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//trimParameters35_C7.dat
[13:18:31.153] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//trimParameters35_C8.dat
[13:18:31.159] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//trimParameters35_C9.dat
[13:18:31.165] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//trimParameters35_C10.dat
[13:18:31.171] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//trimParameters35_C11.dat
[13:18:31.177] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//trimParameters35_C12.dat
[13:18:31.183] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//trimParameters35_C13.dat
[13:18:31.189] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//trimParameters35_C14.dat
[13:18:31.195] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//trimParameters35_C15.dat
[13:18:31.201] <TB3> INFO: PixTestTrim::trimTest() done
[13:18:31.201] <TB3> INFO: vtrim: 114 90 122 104 121 90 102 122 138 120 117 124 132 117 123 116
[13:18:31.201] <TB3> INFO: vthrcomp: 100 95 104 103 103 105 103 108 105 105 106 104 108 107 109 105
[13:18:31.201] <TB3> INFO: vcal mean: 34.96 35.04 34.98 34.98 34.98 34.96 34.98 35.01 35.01 35.02 34.95 34.95 35.00 34.96 35.01 34.98
[13:18:31.201] <TB3> INFO: vcal RMS: 1.08 1.08 1.02 0.91 0.94 0.95 0.93 1.01 1.04 0.97 0.94 1.12 1.05 1.02 1.06 1.09
[13:18:31.201] <TB3> INFO: bits mean: 9.08 8.93 8.63 8.83 9.67 7.81 9.89 7.52 7.30 8.39 9.09 8.53 8.85 8.65 8.39 8.91
[13:18:31.201] <TB3> INFO: bits RMS: 2.75 3.07 2.58 2.89 2.63 2.83 2.54 2.43 2.54 2.62 2.41 2.51 2.41 2.43 2.64 2.51
[13:18:31.208] <TB3> INFO: ----------------------------------------------------------------------
[13:18:31.208] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[13:18:31.208] <TB3> INFO: ----------------------------------------------------------------------
[13:18:31.211] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[13:18:31.222] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:18:31.222] <TB3> INFO: run 1 of 1
[13:18:31.531] <TB3> INFO: Expecting 8320000 events.
[13:19:06.353] <TB3> INFO: 996510 events read in total (34106ms).
[13:19:42.139] <TB3> INFO: 1983160 events read in total (69892ms).
[13:20:18.292] <TB3> INFO: 2968220 events read in total (106045ms).
[13:20:54.324] <TB3> INFO: 3950470 events read in total (142077ms).
[13:21:29.858] <TB3> INFO: 4923960 events read in total (177612ms).
[13:22:02.760] <TB3> INFO: 5893980 events read in total (210513ms).
[13:22:37.631] <TB3> INFO: 6863500 events read in total (245384ms).
[13:23:12.326] <TB3> INFO: 7831750 events read in total (280079ms).
[13:23:30.831] <TB3> INFO: 8320000 events read in total (298584ms).
[13:23:30.859] <TB3> INFO: Test took 299638ms.
[13:23:30.945] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:55.411] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 190 (-1/-1) hits flags = 528 (plus default)
[13:23:55.419] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:23:55.419] <TB3> INFO: run 1 of 1
[13:23:55.721] <TB3> INFO: Expecting 7945600 events.
[13:24:30.241] <TB3> INFO: 977930 events read in total (33804ms).
[13:25:05.645] <TB3> INFO: 1947300 events read in total (69208ms).
[13:25:38.992] <TB3> INFO: 2915920 events read in total (102555ms).
[13:26:14.141] <TB3> INFO: 3880700 events read in total (137704ms).
[13:26:49.845] <TB3> INFO: 4837530 events read in total (173408ms).
[13:27:25.084] <TB3> INFO: 5791730 events read in total (208647ms).
[13:28:00.909] <TB3> INFO: 6745580 events read in total (244472ms).
[13:28:36.328] <TB3> INFO: 7699110 events read in total (279891ms).
[13:28:45.923] <TB3> INFO: 7945600 events read in total (289486ms).
[13:28:45.957] <TB3> INFO: Test took 290538ms.
[13:28:46.041] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:29:12.969] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 178 (-1/-1) hits flags = 528 (plus default)
[13:29:12.978] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:29:12.978] <TB3> INFO: run 1 of 1
[13:29:13.284] <TB3> INFO: Expecting 7446400 events.
[13:29:50.498] <TB3> INFO: 1009520 events read in total (36498ms).
[13:30:26.166] <TB3> INFO: 2009100 events read in total (72166ms).
[13:31:01.618] <TB3> INFO: 3007000 events read in total (107618ms).
[13:31:35.892] <TB3> INFO: 3998880 events read in total (141892ms).
[13:32:11.175] <TB3> INFO: 4983200 events read in total (177175ms).
[13:32:45.500] <TB3> INFO: 5965990 events read in total (211500ms).
[13:33:21.297] <TB3> INFO: 6948350 events read in total (247297ms).
[13:33:39.047] <TB3> INFO: 7446400 events read in total (265047ms).
[13:33:39.079] <TB3> INFO: Test took 266101ms.
[13:33:39.161] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:34:05.414] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 179 (-1/-1) hits flags = 528 (plus default)
[13:34:05.423] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:34:05.423] <TB3> INFO: run 1 of 1
[13:34:05.727] <TB3> INFO: Expecting 7488000 events.
[13:34:43.839] <TB3> INFO: 1006280 events read in total (37396ms).
[13:35:21.158] <TB3> INFO: 2002810 events read in total (74715ms).
[13:35:57.329] <TB3> INFO: 2997280 events read in total (110886ms).
[13:36:33.872] <TB3> INFO: 3986720 events read in total (147429ms).
[13:37:10.565] <TB3> INFO: 4968190 events read in total (184122ms).
[13:37:44.580] <TB3> INFO: 5948540 events read in total (218137ms).
[13:38:20.421] <TB3> INFO: 6927670 events read in total (253978ms).
[13:38:40.620] <TB3> INFO: 7488000 events read in total (274177ms).
[13:38:40.651] <TB3> INFO: Test took 275228ms.
[13:38:40.728] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:39:04.946] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 179 (-1/-1) hits flags = 528 (plus default)
[13:39:04.955] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[13:39:04.955] <TB3> INFO: run 1 of 1
[13:39:05.258] <TB3> INFO: Expecting 7488000 events.
[13:39:40.353] <TB3> INFO: 1005850 events read in total (34379ms).
[13:40:16.241] <TB3> INFO: 2001530 events read in total (70267ms).
[13:40:52.255] <TB3> INFO: 2996110 events read in total (106281ms).
[13:41:27.992] <TB3> INFO: 3984910 events read in total (142018ms).
[13:42:03.462] <TB3> INFO: 4965950 events read in total (177488ms).
[13:42:39.363] <TB3> INFO: 5945600 events read in total (213389ms).
[13:43:15.172] <TB3> INFO: 6924340 events read in total (249198ms).
[13:43:34.320] <TB3> INFO: 7488000 events read in total (268346ms).
[13:43:34.349] <TB3> INFO: Test took 269394ms.
[13:43:34.420] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:57.473] <TB3> INFO: PixTestTrim::trimBitTest() done
[13:43:57.474] <TB3> INFO: PixTestTrim::doTest() done, duration: 3126 seconds
[13:43:57.474] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:57.474] <TB3> INFO: Decoding statistics:
[13:43:57.474] <TB3> INFO: General information:
[13:43:57.474] <TB3> INFO: 16bit words read: 0
[13:43:57.474] <TB3> INFO: valid events total: 0
[13:43:57.474] <TB3> INFO: empty events: 0
[13:43:57.474] <TB3> INFO: valid events with pixels: 0
[13:43:57.474] <TB3> INFO: valid pixel hits: 0
[13:43:57.474] <TB3> INFO: Event errors: 0
[13:43:57.474] <TB3> INFO: start marker: 0
[13:43:57.474] <TB3> INFO: stop marker: 0
[13:43:57.474] <TB3> INFO: overflow: 0
[13:43:57.474] <TB3> INFO: invalid 5bit words: 0
[13:43:57.474] <TB3> INFO: invalid XOR eye diagram: 0
[13:43:57.474] <TB3> INFO: TBM errors: 0
[13:43:57.474] <TB3> INFO: flawed TBM headers: 0
[13:43:57.474] <TB3> INFO: flawed TBM trailers: 0
[13:43:57.474] <TB3> INFO: event ID mismatches: 0
[13:43:57.474] <TB3> INFO: ROC errors: 0
[13:43:57.474] <TB3> INFO: missing ROC header(s): 0
[13:43:57.474] <TB3> INFO: misplaced readback start: 0
[13:43:57.474] <TB3> INFO: Pixel decoding errors: 0
[13:43:57.474] <TB3> INFO: pixel data incomplete: 0
[13:43:57.474] <TB3> INFO: pixel address: 0
[13:43:57.474] <TB3> INFO: pulse height fill bit: 0
[13:43:57.474] <TB3> INFO: buffer corruption: 0
[13:43:58.133] <TB3> INFO: ######################################################################
[13:43:58.133] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[13:43:58.133] <TB3> INFO: ######################################################################
[13:43:58.437] <TB3> INFO: Expecting 41600 events.
[13:44:02.753] <TB3> INFO: 41600 events read in total (3600ms).
[13:44:02.754] <TB3> INFO: Test took 4619ms.
[13:44:02.760] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:03.362] <TB3> INFO: Expecting 41600 events.
[13:44:07.739] <TB3> INFO: 41600 events read in total (3661ms).
[13:44:07.740] <TB3> INFO: Test took 4717ms.
[13:44:07.747] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:08.095] <TB3> INFO: Expecting 41600 events.
[13:44:12.483] <TB3> INFO: 41600 events read in total (3672ms).
[13:44:12.484] <TB3> INFO: Test took 4726ms.
[13:44:12.489] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:12.835] <TB3> INFO: Expecting 2560 events.
[13:44:13.899] <TB3> INFO: 2560 events read in total (348ms).
[13:44:13.899] <TB3> INFO: Test took 1403ms.
[13:44:14.313] <TB3> INFO: Expecting 2560 events.
[13:44:15.379] <TB3> INFO: 2560 events read in total (350ms).
[13:44:15.379] <TB3> INFO: Test took 1479ms.
[13:44:15.793] <TB3> INFO: Expecting 2560 events.
[13:44:16.868] <TB3> INFO: 2560 events read in total (359ms).
[13:44:16.868] <TB3> INFO: Test took 1489ms.
[13:44:17.283] <TB3> INFO: Expecting 2560 events.
[13:44:18.345] <TB3> INFO: 2560 events read in total (347ms).
[13:44:18.345] <TB3> INFO: Test took 1476ms.
[13:44:18.759] <TB3> INFO: Expecting 2560 events.
[13:44:19.827] <TB3> INFO: 2560 events read in total (352ms).
[13:44:19.827] <TB3> INFO: Test took 1482ms.
[13:44:20.241] <TB3> INFO: Expecting 2560 events.
[13:44:21.303] <TB3> INFO: 2560 events read in total (346ms).
[13:44:21.303] <TB3> INFO: Test took 1476ms.
[13:44:21.717] <TB3> INFO: Expecting 2560 events.
[13:44:22.779] <TB3> INFO: 2560 events read in total (346ms).
[13:44:22.779] <TB3> INFO: Test took 1476ms.
[13:44:23.194] <TB3> INFO: Expecting 2560 events.
[13:44:24.258] <TB3> INFO: 2560 events read in total (348ms).
[13:44:24.259] <TB3> INFO: Test took 1479ms.
[13:44:24.673] <TB3> INFO: Expecting 2560 events.
[13:44:25.744] <TB3> INFO: 2560 events read in total (355ms).
[13:44:25.744] <TB3> INFO: Test took 1485ms.
[13:44:26.158] <TB3> INFO: Expecting 2560 events.
[13:44:27.225] <TB3> INFO: 2560 events read in total (351ms).
[13:44:27.226] <TB3> INFO: Test took 1482ms.
[13:44:27.640] <TB3> INFO: Expecting 2560 events.
[13:44:28.707] <TB3> INFO: 2560 events read in total (351ms).
[13:44:28.708] <TB3> INFO: Test took 1481ms.
[13:44:29.122] <TB3> INFO: Expecting 2560 events.
[13:44:30.191] <TB3> INFO: 2560 events read in total (353ms).
[13:44:30.191] <TB3> INFO: Test took 1483ms.
[13:44:30.606] <TB3> INFO: Expecting 2560 events.
[13:44:31.675] <TB3> INFO: 2560 events read in total (353ms).
[13:44:31.675] <TB3> INFO: Test took 1483ms.
[13:44:32.089] <TB3> INFO: Expecting 2560 events.
[13:44:33.158] <TB3> INFO: 2560 events read in total (353ms).
[13:44:33.158] <TB3> INFO: Test took 1482ms.
[13:44:33.572] <TB3> INFO: Expecting 2560 events.
[13:44:34.643] <TB3> INFO: 2560 events read in total (355ms).
[13:44:34.643] <TB3> INFO: Test took 1484ms.
[13:44:35.058] <TB3> INFO: Expecting 2560 events.
[13:44:36.122] <TB3> INFO: 2560 events read in total (348ms).
[13:44:36.123] <TB3> INFO: Test took 1479ms.
[13:44:36.126] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:44:36.539] <TB3> INFO: Expecting 655360 events.
[13:44:51.086] <TB3> INFO: 655360 events read in total (13831ms).
[13:44:51.100] <TB3> INFO: Expecting 655360 events.
[13:45:05.390] <TB3> INFO: 655360 events read in total (13763ms).
[13:45:05.405] <TB3> INFO: Expecting 655360 events.
[13:45:20.142] <TB3> INFO: 655360 events read in total (14210ms).
[13:45:20.161] <TB3> INFO: Expecting 655360 events.
[13:45:34.568] <TB3> INFO: 655360 events read in total (13879ms).
[13:45:34.590] <TB3> INFO: Expecting 655360 events.
[13:45:49.373] <TB3> INFO: 655360 events read in total (14256ms).
[13:45:49.399] <TB3> INFO: Expecting 655360 events.
[13:46:03.837] <TB3> INFO: 655360 events read in total (13911ms).
[13:46:03.866] <TB3> INFO: Expecting 655360 events.
[13:46:18.247] <TB3> INFO: 655360 events read in total (13854ms).
[13:46:18.284] <TB3> INFO: Expecting 655360 events.
[13:46:32.863] <TB3> INFO: 655360 events read in total (14051ms).
[13:46:32.897] <TB3> INFO: Expecting 655360 events.
[13:46:46.621] <TB3> INFO: 655360 events read in total (13196ms).
[13:46:46.660] <TB3> INFO: Expecting 655360 events.
[13:47:01.404] <TB3> INFO: 655360 events read in total (14216ms).
[13:47:01.443] <TB3> INFO: Expecting 655360 events.
[13:47:15.874] <TB3> INFO: 655360 events read in total (13903ms).
[13:47:15.921] <TB3> INFO: Expecting 655360 events.
[13:47:30.315] <TB3> INFO: 655360 events read in total (13866ms).
[13:47:30.362] <TB3> INFO: Expecting 655360 events.
[13:47:44.918] <TB3> INFO: 655360 events read in total (14028ms).
[13:47:44.971] <TB3> INFO: Expecting 655360 events.
[13:47:59.458] <TB3> INFO: 655360 events read in total (13960ms).
[13:47:59.515] <TB3> INFO: Expecting 655360 events.
[13:48:14.198] <TB3> INFO: 655360 events read in total (14156ms).
[13:48:14.275] <TB3> INFO: Expecting 655360 events.
[13:48:28.957] <TB3> INFO: 655360 events read in total (14154ms).
[13:48:29.020] <TB3> INFO: Test took 232894ms.
[13:48:29.100] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:48:29.407] <TB3> INFO: Expecting 655360 events.
[13:48:43.853] <TB3> INFO: 655360 events read in total (13730ms).
[13:48:43.865] <TB3> INFO: Expecting 655360 events.
[13:48:58.294] <TB3> INFO: 655360 events read in total (13902ms).
[13:48:58.309] <TB3> INFO: Expecting 655360 events.
[13:49:11.961] <TB3> INFO: 655360 events read in total (13124ms).
[13:49:11.979] <TB3> INFO: Expecting 655360 events.
[13:49:26.084] <TB3> INFO: 655360 events read in total (13578ms).
[13:49:26.104] <TB3> INFO: Expecting 655360 events.
[13:49:40.540] <TB3> INFO: 655360 events read in total (13907ms).
[13:49:40.570] <TB3> INFO: Expecting 655360 events.
[13:49:54.736] <TB3> INFO: 655360 events read in total (13638ms).
[13:49:54.771] <TB3> INFO: Expecting 655360 events.
[13:50:09.230] <TB3> INFO: 655360 events read in total (13930ms).
[13:50:09.272] <TB3> INFO: Expecting 655360 events.
[13:50:23.472] <TB3> INFO: 655360 events read in total (13672ms).
[13:50:23.512] <TB3> INFO: Expecting 655360 events.
[13:50:37.972] <TB3> INFO: 655360 events read in total (13932ms).
[13:50:38.009] <TB3> INFO: Expecting 655360 events.
[13:50:52.409] <TB3> INFO: 655360 events read in total (13873ms).
[13:50:52.452] <TB3> INFO: Expecting 655360 events.
[13:51:06.945] <TB3> INFO: 655360 events read in total (13965ms).
[13:51:06.995] <TB3> INFO: Expecting 655360 events.
[13:51:21.323] <TB3> INFO: 655360 events read in total (13800ms).
[13:51:21.376] <TB3> INFO: Expecting 655360 events.
[13:51:35.638] <TB3> INFO: 655360 events read in total (13734ms).
[13:51:35.693] <TB3> INFO: Expecting 655360 events.
[13:51:50.140] <TB3> INFO: 655360 events read in total (13919ms).
[13:51:50.208] <TB3> INFO: Expecting 655360 events.
[13:52:04.623] <TB3> INFO: 655360 events read in total (13887ms).
[13:52:04.679] <TB3> INFO: Expecting 655360 events.
[13:52:18.852] <TB3> INFO: 655360 events read in total (13645ms).
[13:52:18.916] <TB3> INFO: Test took 229817ms.
[13:52:19.107] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:19.114] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:19.121] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:19.128] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:19.134] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:19.141] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:19.148] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:19.155] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[13:52:19.162] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:19.169] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:19.176] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:19.182] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:19.189] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:19.196] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:19.203] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:19.210] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:19.217] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:19.260] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C0.dat
[13:52:19.260] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C1.dat
[13:52:19.260] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C2.dat
[13:52:19.260] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C3.dat
[13:52:19.260] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C4.dat
[13:52:19.260] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C5.dat
[13:52:19.260] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C6.dat
[13:52:19.260] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C7.dat
[13:52:19.260] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C8.dat
[13:52:19.260] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C9.dat
[13:52:19.260] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C10.dat
[13:52:19.260] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C11.dat
[13:52:19.261] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C12.dat
[13:52:19.261] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C13.dat
[13:52:19.261] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C14.dat
[13:52:19.261] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//dacParameters35_C15.dat
[13:52:19.595] <TB3> INFO: Expecting 41600 events.
[13:52:23.592] <TB3> INFO: 41600 events read in total (3281ms).
[13:52:23.594] <TB3> INFO: Test took 4330ms.
[13:52:24.154] <TB3> INFO: Expecting 41600 events.
[13:52:28.087] <TB3> INFO: 41600 events read in total (3217ms).
[13:52:28.088] <TB3> INFO: Test took 4263ms.
[13:52:28.646] <TB3> INFO: Expecting 41600 events.
[13:52:32.612] <TB3> INFO: 41600 events read in total (3250ms).
[13:52:32.613] <TB3> INFO: Test took 4287ms.
[13:52:32.851] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:52:32.982] <TB3> INFO: Expecting 2560 events.
[13:52:34.050] <TB3> INFO: 2560 events read in total (352ms).
[13:52:34.051] <TB3> INFO: Test took 1200ms.
[13:52:34.052] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:52:34.467] <TB3> INFO: Expecting 2560 events.
[13:52:35.531] <TB3> INFO: 2560 events read in total (349ms).
[13:52:35.531] <TB3> INFO: Test took 1479ms.
[13:52:35.535] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:52:35.946] <TB3> INFO: Expecting 2560 events.
[13:52:37.009] <TB3> INFO: 2560 events read in total (347ms).
[13:52:37.009] <TB3> INFO: Test took 1474ms.
[13:52:37.012] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:52:37.423] <TB3> INFO: Expecting 2560 events.
[13:52:38.488] <TB3> INFO: 2560 events read in total (349ms).
[13:52:38.488] <TB3> INFO: Test took 1476ms.
[13:52:38.490] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:52:38.904] <TB3> INFO: Expecting 2560 events.
[13:52:39.967] <TB3> INFO: 2560 events read in total (348ms).
[13:52:39.967] <TB3> INFO: Test took 1477ms.
[13:52:39.969] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:52:40.383] <TB3> INFO: Expecting 2560 events.
[13:52:41.446] <TB3> INFO: 2560 events read in total (347ms).
[13:52:41.447] <TB3> INFO: Test took 1478ms.
[13:52:41.449] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:52:41.861] <TB3> INFO: Expecting 2560 events.
[13:52:42.944] <TB3> INFO: 2560 events read in total (367ms).
[13:52:42.945] <TB3> INFO: Test took 1496ms.
[13:52:42.947] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:52:43.359] <TB3> INFO: Expecting 2560 events.
[13:52:44.421] <TB3> INFO: 2560 events read in total (347ms).
[13:52:44.421] <TB3> INFO: Test took 1474ms.
[13:52:44.424] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:52:44.836] <TB3> INFO: Expecting 2560 events.
[13:52:45.900] <TB3> INFO: 2560 events read in total (348ms).
[13:52:45.900] <TB3> INFO: Test took 1476ms.
[13:52:45.904] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:52:46.315] <TB3> INFO: Expecting 2560 events.
[13:52:47.377] <TB3> INFO: 2560 events read in total (346ms).
[13:52:47.378] <TB3> INFO: Test took 1474ms.
[13:52:47.381] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:52:47.793] <TB3> INFO: Expecting 2560 events.
[13:52:48.860] <TB3> INFO: 2560 events read in total (351ms).
[13:52:48.860] <TB3> INFO: Test took 1479ms.
[13:52:48.863] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:52:49.277] <TB3> INFO: Expecting 2560 events.
[13:52:50.346] <TB3> INFO: 2560 events read in total (354ms).
[13:52:50.346] <TB3> INFO: Test took 1483ms.
[13:52:50.349] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:52:50.761] <TB3> INFO: Expecting 2560 events.
[13:52:51.829] <TB3> INFO: 2560 events read in total (352ms).
[13:52:51.829] <TB3> INFO: Test took 1480ms.
[13:52:51.832] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:52:52.244] <TB3> INFO: Expecting 2560 events.
[13:52:53.313] <TB3> INFO: 2560 events read in total (353ms).
[13:52:53.314] <TB3> INFO: Test took 1483ms.
[13:52:53.316] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:52:53.728] <TB3> INFO: Expecting 2560 events.
[13:52:54.797] <TB3> INFO: 2560 events read in total (353ms).
[13:52:54.798] <TB3> INFO: Test took 1482ms.
[13:52:54.800] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:52:55.212] <TB3> INFO: Expecting 2560 events.
[13:52:56.277] <TB3> INFO: 2560 events read in total (349ms).
[13:52:56.278] <TB3> INFO: Test took 1478ms.
[13:52:56.279] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:52:56.693] <TB3> INFO: Expecting 2560 events.
[13:52:57.756] <TB3> INFO: 2560 events read in total (347ms).
[13:52:57.756] <TB3> INFO: Test took 1477ms.
[13:52:57.759] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:52:58.170] <TB3> INFO: Expecting 2560 events.
[13:52:59.236] <TB3> INFO: 2560 events read in total (350ms).
[13:52:59.237] <TB3> INFO: Test took 1479ms.
[13:52:59.239] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:52:59.652] <TB3> INFO: Expecting 2560 events.
[13:53:00.723] <TB3> INFO: 2560 events read in total (355ms).
[13:53:00.724] <TB3> INFO: Test took 1485ms.
[13:53:00.726] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:01.139] <TB3> INFO: Expecting 2560 events.
[13:53:02.202] <TB3> INFO: 2560 events read in total (347ms).
[13:53:02.203] <TB3> INFO: Test took 1477ms.
[13:53:02.205] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:02.620] <TB3> INFO: Expecting 2560 events.
[13:53:03.683] <TB3> INFO: 2560 events read in total (347ms).
[13:53:03.683] <TB3> INFO: Test took 1478ms.
[13:53:03.685] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:04.098] <TB3> INFO: Expecting 2560 events.
[13:53:05.166] <TB3> INFO: 2560 events read in total (352ms).
[13:53:05.166] <TB3> INFO: Test took 1481ms.
[13:53:05.168] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:05.581] <TB3> INFO: Expecting 2560 events.
[13:53:06.650] <TB3> INFO: 2560 events read in total (353ms).
[13:53:06.650] <TB3> INFO: Test took 1482ms.
[13:53:06.652] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:07.065] <TB3> INFO: Expecting 2560 events.
[13:53:08.133] <TB3> INFO: 2560 events read in total (352ms).
[13:53:08.134] <TB3> INFO: Test took 1482ms.
[13:53:08.137] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:08.549] <TB3> INFO: Expecting 2560 events.
[13:53:09.614] <TB3> INFO: 2560 events read in total (350ms).
[13:53:09.614] <TB3> INFO: Test took 1477ms.
[13:53:09.616] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:10.030] <TB3> INFO: Expecting 2560 events.
[13:53:11.094] <TB3> INFO: 2560 events read in total (348ms).
[13:53:11.094] <TB3> INFO: Test took 1478ms.
[13:53:11.096] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:11.509] <TB3> INFO: Expecting 2560 events.
[13:53:12.572] <TB3> INFO: 2560 events read in total (347ms).
[13:53:12.573] <TB3> INFO: Test took 1477ms.
[13:53:12.575] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:12.988] <TB3> INFO: Expecting 2560 events.
[13:53:14.058] <TB3> INFO: 2560 events read in total (354ms).
[13:53:14.059] <TB3> INFO: Test took 1484ms.
[13:53:14.061] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:14.474] <TB3> INFO: Expecting 2560 events.
[13:53:15.541] <TB3> INFO: 2560 events read in total (351ms).
[13:53:15.541] <TB3> INFO: Test took 1480ms.
[13:53:15.545] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:15.956] <TB3> INFO: Expecting 2560 events.
[13:53:17.019] <TB3> INFO: 2560 events read in total (347ms).
[13:53:17.019] <TB3> INFO: Test took 1474ms.
[13:53:17.023] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:17.435] <TB3> INFO: Expecting 2560 events.
[13:53:18.499] <TB3> INFO: 2560 events read in total (349ms).
[13:53:18.499] <TB3> INFO: Test took 1476ms.
[13:53:18.502] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:18.914] <TB3> INFO: Expecting 2560 events.
[13:53:19.979] <TB3> INFO: 2560 events read in total (349ms).
[13:53:19.979] <TB3> INFO: Test took 1477ms.
[13:53:20.606] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 562 seconds
[13:53:20.606] <TB3> INFO: PH scale (per ROC): 67 77 69 73 78 76 80 72 66 71 77 70 74 72 70 78
[13:53:20.606] <TB3> INFO: PH offset (per ROC): 171 158 157 145 172 177 166 178 177 173 148 183 165 167 176 176
[13:53:20.613] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:20.613] <TB3> INFO: Decoding statistics:
[13:53:20.613] <TB3> INFO: General information:
[13:53:20.613] <TB3> INFO: 16bit words read: 91916
[13:53:20.613] <TB3> INFO: valid events total: 10240
[13:53:20.613] <TB3> INFO: empty events: 7741
[13:53:20.613] <TB3> INFO: valid events with pixels: 2499
[13:53:20.613] <TB3> INFO: valid pixel hits: 2499
[13:53:20.613] <TB3> INFO: Event errors: 0
[13:53:20.613] <TB3> INFO: start marker: 0
[13:53:20.613] <TB3> INFO: stop marker: 0
[13:53:20.613] <TB3> INFO: overflow: 0
[13:53:20.613] <TB3> INFO: invalid 5bit words: 0
[13:53:20.613] <TB3> INFO: invalid XOR eye diagram: 0
[13:53:20.613] <TB3> INFO: TBM errors: 0
[13:53:20.613] <TB3> INFO: flawed TBM headers: 0
[13:53:20.613] <TB3> INFO: flawed TBM trailers: 0
[13:53:20.613] <TB3> INFO: event ID mismatches: 0
[13:53:20.613] <TB3> INFO: ROC errors: 0
[13:53:20.613] <TB3> INFO: missing ROC header(s): 0
[13:53:20.613] <TB3> INFO: misplaced readback start: 0
[13:53:20.613] <TB3> INFO: Pixel decoding errors: 0
[13:53:20.613] <TB3> INFO: pixel data incomplete: 0
[13:53:20.614] <TB3> INFO: pixel address: 0
[13:53:20.614] <TB3> INFO: pulse height fill bit: 0
[13:53:20.614] <TB3> INFO: buffer corruption: 0
[13:53:20.786] <TB3> INFO: ######################################################################
[13:53:20.786] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[13:53:20.786] <TB3> INFO: ######################################################################
[13:53:20.796] <TB3> INFO: scanning low vcal = 10
[13:53:21.123] <TB3> INFO: Expecting 41600 events.
[13:53:24.911] <TB3> INFO: 41600 events read in total (3073ms).
[13:53:24.912] <TB3> INFO: Test took 4116ms.
[13:53:24.915] <TB3> INFO: scanning low vcal = 20
[13:53:25.325] <TB3> INFO: Expecting 41600 events.
[13:53:29.100] <TB3> INFO: 41600 events read in total (3059ms).
[13:53:29.100] <TB3> INFO: Test took 4184ms.
[13:53:29.103] <TB3> INFO: scanning low vcal = 30
[13:53:29.514] <TB3> INFO: Expecting 41600 events.
[13:53:33.329] <TB3> INFO: 41600 events read in total (3100ms).
[13:53:33.329] <TB3> INFO: Test took 4226ms.
[13:53:33.333] <TB3> INFO: scanning low vcal = 40
[13:53:33.732] <TB3> INFO: Expecting 41600 events.
[13:53:38.046] <TB3> INFO: 41600 events read in total (3599ms).
[13:53:38.046] <TB3> INFO: Test took 4713ms.
[13:53:38.050] <TB3> INFO: scanning low vcal = 50
[13:53:38.390] <TB3> INFO: Expecting 41600 events.
[13:53:42.730] <TB3> INFO: 41600 events read in total (3624ms).
[13:53:42.731] <TB3> INFO: Test took 4681ms.
[13:53:42.734] <TB3> INFO: scanning low vcal = 60
[13:53:43.062] <TB3> INFO: Expecting 41600 events.
[13:53:47.305] <TB3> INFO: 41600 events read in total (3527ms).
[13:53:47.305] <TB3> INFO: Test took 4571ms.
[13:53:47.308] <TB3> INFO: scanning low vcal = 70
[13:53:47.662] <TB3> INFO: Expecting 41600 events.
[13:53:51.902] <TB3> INFO: 41600 events read in total (3525ms).
[13:53:51.903] <TB3> INFO: Test took 4595ms.
[13:53:51.905] <TB3> INFO: scanning low vcal = 80
[13:53:52.246] <TB3> INFO: Expecting 41600 events.
[13:53:56.488] <TB3> INFO: 41600 events read in total (3526ms).
[13:53:56.489] <TB3> INFO: Test took 4584ms.
[13:53:56.492] <TB3> INFO: scanning low vcal = 90
[13:53:56.846] <TB3> INFO: Expecting 41600 events.
[13:54:01.349] <TB3> INFO: 41600 events read in total (3787ms).
[13:54:01.350] <TB3> INFO: Test took 4858ms.
[13:54:01.353] <TB3> INFO: scanning low vcal = 100
[13:54:01.678] <TB3> INFO: Expecting 41600 events.
[13:54:06.190] <TB3> INFO: 41600 events read in total (3796ms).
[13:54:06.190] <TB3> INFO: Test took 4837ms.
[13:54:06.193] <TB3> INFO: scanning low vcal = 110
[13:54:06.528] <TB3> INFO: Expecting 41600 events.
[13:54:10.909] <TB3> INFO: 41600 events read in total (3666ms).
[13:54:10.910] <TB3> INFO: Test took 4717ms.
[13:54:10.913] <TB3> INFO: scanning low vcal = 120
[13:54:11.265] <TB3> INFO: Expecting 41600 events.
[13:54:15.601] <TB3> INFO: 41600 events read in total (3620ms).
[13:54:15.602] <TB3> INFO: Test took 4689ms.
[13:54:15.605] <TB3> INFO: scanning low vcal = 130
[13:54:15.955] <TB3> INFO: Expecting 41600 events.
[13:54:20.406] <TB3> INFO: 41600 events read in total (3736ms).
[13:54:20.406] <TB3> INFO: Test took 4801ms.
[13:54:20.409] <TB3> INFO: scanning low vcal = 140
[13:54:20.750] <TB3> INFO: Expecting 41600 events.
[13:54:25.152] <TB3> INFO: 41600 events read in total (3686ms).
[13:54:25.152] <TB3> INFO: Test took 4743ms.
[13:54:25.155] <TB3> INFO: scanning low vcal = 150
[13:54:25.503] <TB3> INFO: Expecting 41600 events.
[13:54:29.921] <TB3> INFO: 41600 events read in total (3702ms).
[13:54:29.922] <TB3> INFO: Test took 4767ms.
[13:54:29.925] <TB3> INFO: scanning low vcal = 160
[13:54:30.273] <TB3> INFO: Expecting 41600 events.
[13:54:34.674] <TB3> INFO: 41600 events read in total (3685ms).
[13:54:34.675] <TB3> INFO: Test took 4750ms.
[13:54:34.678] <TB3> INFO: scanning low vcal = 170
[13:54:35.017] <TB3> INFO: Expecting 41600 events.
[13:54:39.377] <TB3> INFO: 41600 events read in total (3645ms).
[13:54:39.377] <TB3> INFO: Test took 4699ms.
[13:54:39.381] <TB3> INFO: scanning low vcal = 180
[13:54:39.715] <TB3> INFO: Expecting 41600 events.
[13:54:44.096] <TB3> INFO: 41600 events read in total (3665ms).
[13:54:44.096] <TB3> INFO: Test took 4716ms.
[13:54:44.100] <TB3> INFO: scanning low vcal = 190
[13:54:44.438] <TB3> INFO: Expecting 41600 events.
[13:54:48.667] <TB3> INFO: 41600 events read in total (3513ms).
[13:54:48.668] <TB3> INFO: Test took 4568ms.
[13:54:48.671] <TB3> INFO: scanning low vcal = 200
[13:54:49.025] <TB3> INFO: Expecting 41600 events.
[13:54:53.313] <TB3> INFO: 41600 events read in total (3572ms).
[13:54:53.314] <TB3> INFO: Test took 4643ms.
[13:54:53.317] <TB3> INFO: scanning low vcal = 210
[13:54:53.648] <TB3> INFO: Expecting 41600 events.
[13:54:57.907] <TB3> INFO: 41600 events read in total (3543ms).
[13:54:57.907] <TB3> INFO: Test took 4590ms.
[13:54:57.910] <TB3> INFO: scanning low vcal = 220
[13:54:58.264] <TB3> INFO: Expecting 41600 events.
[13:55:02.508] <TB3> INFO: 41600 events read in total (3529ms).
[13:55:02.508] <TB3> INFO: Test took 4598ms.
[13:55:02.511] <TB3> INFO: scanning low vcal = 230
[13:55:02.867] <TB3> INFO: Expecting 41600 events.
[13:55:07.145] <TB3> INFO: 41600 events read in total (3562ms).
[13:55:07.145] <TB3> INFO: Test took 4634ms.
[13:55:07.150] <TB3> INFO: scanning low vcal = 240
[13:55:07.494] <TB3> INFO: Expecting 41600 events.
[13:55:11.723] <TB3> INFO: 41600 events read in total (3513ms).
[13:55:11.724] <TB3> INFO: Test took 4574ms.
[13:55:11.726] <TB3> INFO: scanning low vcal = 250
[13:55:12.081] <TB3> INFO: Expecting 41600 events.
[13:55:16.352] <TB3> INFO: 41600 events read in total (3555ms).
[13:55:16.352] <TB3> INFO: Test took 4626ms.
[13:55:16.357] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[13:55:16.692] <TB3> INFO: Expecting 41600 events.
[13:55:21.108] <TB3> INFO: 41600 events read in total (3701ms).
[13:55:21.109] <TB3> INFO: Test took 4752ms.
[13:55:21.112] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[13:55:21.446] <TB3> INFO: Expecting 41600 events.
[13:55:25.877] <TB3> INFO: 41600 events read in total (3716ms).
[13:55:25.878] <TB3> INFO: Test took 4766ms.
[13:55:25.881] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[13:55:26.219] <TB3> INFO: Expecting 41600 events.
[13:55:30.650] <TB3> INFO: 41600 events read in total (3715ms).
[13:55:30.652] <TB3> INFO: Test took 4771ms.
[13:55:30.655] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[13:55:31.010] <TB3> INFO: Expecting 41600 events.
[13:55:35.391] <TB3> INFO: 41600 events read in total (3666ms).
[13:55:35.391] <TB3> INFO: Test took 4736ms.
[13:55:35.394] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[13:55:35.748] <TB3> INFO: Expecting 41600 events.
[13:55:40.129] <TB3> INFO: 41600 events read in total (3666ms).
[13:55:40.130] <TB3> INFO: Test took 4736ms.
[13:55:40.664] <TB3> INFO: PixTestGainPedestal::measure() done
[13:56:14.803] <TB3> INFO: PixTestGainPedestal::fit() done
[13:56:14.804] <TB3> INFO: non-linearity mean: 0.951 0.958 0.968 0.955 0.952 0.953 0.957 0.956 0.955 0.960 0.956 0.965 0.956 0.956 0.954 0.958
[13:56:14.804] <TB3> INFO: non-linearity RMS: 0.006 0.005 0.005 0.006 0.007 0.006 0.005 0.006 0.007 0.005 0.005 0.005 0.008 0.007 0.006 0.006
[13:56:14.804] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[13:56:14.822] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[13:56:14.840] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[13:56:14.857] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[13:56:14.875] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[13:56:14.893] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[13:56:14.911] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[13:56:14.929] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[13:56:14.947] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[13:56:14.965] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[13:56:14.983] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[13:56:15.001] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[13:56:15.019] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[13:56:15.037] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[13:56:15.056] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[13:56:15.089] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[13:56:15.110] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 174 seconds
[13:56:15.110] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:15.110] <TB3> INFO: Decoding statistics:
[13:56:15.110] <TB3> INFO: General information:
[13:56:15.110] <TB3> INFO: 16bit words read: 2662400
[13:56:15.110] <TB3> INFO: valid events total: 166400
[13:56:15.110] <TB3> INFO: empty events: 0
[13:56:15.110] <TB3> INFO: valid events with pixels: 166400
[13:56:15.110] <TB3> INFO: valid pixel hits: 665540
[13:56:15.110] <TB3> INFO: Event errors: 0
[13:56:15.110] <TB3> INFO: start marker: 0
[13:56:15.110] <TB3> INFO: stop marker: 0
[13:56:15.110] <TB3> INFO: overflow: 0
[13:56:15.110] <TB3> INFO: invalid 5bit words: 0
[13:56:15.110] <TB3> INFO: invalid XOR eye diagram: 0
[13:56:15.110] <TB3> INFO: TBM errors: 0
[13:56:15.110] <TB3> INFO: flawed TBM headers: 0
[13:56:15.110] <TB3> INFO: flawed TBM trailers: 0
[13:56:15.110] <TB3> INFO: event ID mismatches: 0
[13:56:15.110] <TB3> INFO: ROC errors: 0
[13:56:15.110] <TB3> INFO: missing ROC header(s): 0
[13:56:15.110] <TB3> INFO: misplaced readback start: 0
[13:56:15.110] <TB3> INFO: Pixel decoding errors: 0
[13:56:15.110] <TB3> INFO: pixel data incomplete: 0
[13:56:15.110] <TB3> INFO: pixel address: 0
[13:56:15.110] <TB3> INFO: pulse height fill bit: 0
[13:56:15.110] <TB3> INFO: buffer corruption: 0
[13:56:15.116] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C15.dat
[13:56:15.126] <TB3> INFO: ######################################################################
[13:56:15.126] <TB3> INFO: PixTestReadback::doTest()
[13:56:15.126] <TB3> INFO: ######################################################################
[13:56:15.127] <TB3> INFO: PixTestReadback::RES sent once
[13:56:26.408] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C0.dat
[13:56:26.408] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C1.dat
[13:56:26.408] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C2.dat
[13:56:26.408] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C3.dat
[13:56:26.408] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C4.dat
[13:56:26.408] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C5.dat
[13:56:26.408] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C6.dat
[13:56:26.408] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C7.dat
[13:56:26.409] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C8.dat
[13:56:26.409] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C9.dat
[13:56:26.409] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C10.dat
[13:56:26.409] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C11.dat
[13:56:26.409] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C12.dat
[13:56:26.409] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C13.dat
[13:56:26.409] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C14.dat
[13:56:26.409] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C15.dat
[13:56:26.444] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:56:26.445] <TB3> INFO: PixTestReadback::RES sent once
[13:56:37.652] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C0.dat
[13:56:37.652] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C1.dat
[13:56:37.652] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C2.dat
[13:56:37.652] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C3.dat
[13:56:37.652] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C4.dat
[13:56:37.653] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C5.dat
[13:56:37.653] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C6.dat
[13:56:37.653] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C7.dat
[13:56:37.653] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C8.dat
[13:56:37.653] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C9.dat
[13:56:37.653] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C10.dat
[13:56:37.653] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C11.dat
[13:56:37.653] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C12.dat
[13:56:37.654] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C13.dat
[13:56:37.654] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C14.dat
[13:56:37.654] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C15.dat
[13:56:37.681] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:56:37.682] <TB3> INFO: PixTestReadback::RES sent once
[13:56:46.300] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:56:46.301] <TB3> INFO: Vbg will be calibrated using Vd calibration
[13:56:46.301] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 156.1calibrated Vbg = 1.2064 :::*/*/*/*/
[13:56:46.301] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 147.9calibrated Vbg = 1.2064 :::*/*/*/*/
[13:56:46.301] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 156calibrated Vbg = 1.20965 :::*/*/*/*/
[13:56:46.301] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 149calibrated Vbg = 1.22143 :::*/*/*/*/
[13:56:46.301] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 150.5calibrated Vbg = 1.22152 :::*/*/*/*/
[13:56:46.301] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 146.8calibrated Vbg = 1.22109 :::*/*/*/*/
[13:56:46.301] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 147.1calibrated Vbg = 1.22017 :::*/*/*/*/
[13:56:46.301] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 146.8calibrated Vbg = 1.21789 :::*/*/*/*/
[13:56:46.301] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 151.4calibrated Vbg = 1.22797 :::*/*/*/*/
[13:56:46.301] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 148.5calibrated Vbg = 1.22163 :::*/*/*/*/
[13:56:46.301] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 153calibrated Vbg = 1.23075 :::*/*/*/*/
[13:56:46.301] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 152.5calibrated Vbg = 1.21947 :::*/*/*/*/
[13:56:46.301] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 154.1calibrated Vbg = 1.21617 :::*/*/*/*/
[13:56:46.301] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 170calibrated Vbg = 1.2191 :::*/*/*/*/
[13:56:46.301] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 153calibrated Vbg = 1.22337 :::*/*/*/*/
[13:56:46.301] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 158.4calibrated Vbg = 1.21628 :::*/*/*/*/
[13:56:46.306] <TB3> INFO: PixTestReadback::RES sent once
[13:59:40.938] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C0.dat
[13:59:40.938] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C1.dat
[13:59:40.938] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C2.dat
[13:59:40.938] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C3.dat
[13:59:40.938] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C4.dat
[13:59:40.938] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C5.dat
[13:59:40.939] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C6.dat
[13:59:40.939] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C7.dat
[13:59:40.939] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C8.dat
[13:59:40.939] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C9.dat
[13:59:40.939] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C10.dat
[13:59:40.939] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C11.dat
[13:59:40.939] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C12.dat
[13:59:40.939] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C13.dat
[13:59:40.939] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C14.dat
[13:59:40.939] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2120_FullQualification_2015-11-20_09h55m_1448009726//002_FulltestPxar_m20//readbackCal_C15.dat
[13:59:40.966] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[13:59:40.968] <TB3> INFO: PixTestReadback::doTest() done
[13:59:40.968] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:59:40.968] <TB3> INFO: Decoding statistics:
[13:59:40.968] <TB3> INFO: General information:
[13:59:40.968] <TB3> INFO: 16bit words read: 1024
[13:59:40.968] <TB3> INFO: valid events total: 128
[13:59:40.968] <TB3> INFO: empty events: 128
[13:59:40.968] <TB3> INFO: valid events with pixels: 0
[13:59:40.968] <TB3> INFO: valid pixel hits: 0
[13:59:40.968] <TB3> INFO: Event errors: 0
[13:59:40.968] <TB3> INFO: start marker: 0
[13:59:40.968] <TB3> INFO: stop marker: 0
[13:59:40.968] <TB3> INFO: overflow: 0
[13:59:40.968] <TB3> INFO: invalid 5bit words: 0
[13:59:40.968] <TB3> INFO: invalid XOR eye diagram: 0
[13:59:40.968] <TB3> INFO: TBM errors: 0
[13:59:40.968] <TB3> INFO: flawed TBM headers: 0
[13:59:40.968] <TB3> INFO: flawed TBM trailers: 0
[13:59:40.968] <TB3> INFO: event ID mismatches: 0
[13:59:40.968] <TB3> INFO: ROC errors: 0
[13:59:40.968] <TB3> INFO: missing ROC header(s): 0
[13:59:40.968] <TB3> INFO: misplaced readback start: 0
[13:59:40.968] <TB3> INFO: Pixel decoding errors: 0
[13:59:40.968] <TB3> INFO: pixel data incomplete: 0
[13:59:40.968] <TB3> INFO: pixel address: 0
[13:59:40.968] <TB3> INFO: pulse height fill bit: 0
[13:59:40.969] <TB3> INFO: buffer corruption: 0
[13:59:40.982] <TB3> INFO: Decoding statistics:
[13:59:40.982] <TB3> INFO: General information:
[13:59:40.982] <TB3> INFO: 16bit words read: 2755340
[13:59:40.982] <TB3> INFO: valid events total: 176768
[13:59:40.982] <TB3> INFO: empty events: 7869
[13:59:40.982] <TB3> INFO: valid events with pixels: 168899
[13:59:40.982] <TB3> INFO: valid pixel hits: 668039
[13:59:40.982] <TB3> INFO: Event errors: 0
[13:59:40.982] <TB3> INFO: start marker: 0
[13:59:40.982] <TB3> INFO: stop marker: 0
[13:59:40.982] <TB3> INFO: overflow: 0
[13:59:40.982] <TB3> INFO: invalid 5bit words: 0
[13:59:40.982] <TB3> INFO: invalid XOR eye diagram: 0
[13:59:40.982] <TB3> INFO: TBM errors: 0
[13:59:40.982] <TB3> INFO: flawed TBM headers: 0
[13:59:40.982] <TB3> INFO: flawed TBM trailers: 0
[13:59:40.982] <TB3> INFO: event ID mismatches: 0
[13:59:40.982] <TB3> INFO: ROC errors: 0
[13:59:40.982] <TB3> INFO: missing ROC header(s): 0
[13:59:40.982] <TB3> INFO: misplaced readback start: 0
[13:59:40.982] <TB3> INFO: Pixel decoding errors: 0
[13:59:40.982] <TB3> INFO: pixel data incomplete: 0
[13:59:40.982] <TB3> INFO: pixel address: 0
[13:59:40.982] <TB3> INFO: pulse height fill bit: 0
[13:59:40.982] <TB3> INFO: buffer corruption: 0
[13:59:40.982] <TB3> INFO: enter test to run
[13:59:40.982] <TB3> INFO: test: exit no parameter change
[13:59:41.193] <TB3> QUIET: Connection to board 170 closed.
[13:59:41.273] <TB3> INFO: pXar: this is the end, my friend