[15:08:46.291] <TB3> INFO: *** Welcome to pxar ***
[15:08:46.291] <TB3> INFO: *** Today: 2015/09/10
[15:08:46.291] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C15.dat
[15:08:46.292] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//tbmParameters_C0b.dat
[15:08:46.292] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//defaultMaskFile.dat
[15:08:46.292] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters_C15.dat
[15:08:46.361] <TB3> INFO: clk: 4
[15:08:46.361] <TB3> INFO: ctr: 4
[15:08:46.361] <TB3> INFO: sda: 19
[15:08:46.361] <TB3> INFO: tin: 9
[15:08:46.361] <TB3> INFO: level: 15
[15:08:46.362] <TB3> INFO: triggerdelay: 0
[15:08:46.362] <TB3> QUIET: Instanciating API for pxar prod-10+24~g09f6d2c
[15:08:46.362] <TB3> INFO: Log level: INFO
[15:08:46.369] <TB3> INFO: Found DTB DTB_WZ4I6J
[15:08:46.379] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[15:08:46.383] <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:
------------------------------------------------------
[15:08:46.385] <TB3> INFO: RPC call hashes of host and DTB match: 398089610
[15:08:47.919] <TB3> INFO: DUT info:
[15:08:47.919] <TB3> INFO: The DUT currently contains the following objects:
[15:08:47.919] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[15:08:47.919] <TB3> INFO: TBM Core alpha (0): 7 registers set
[15:08:47.919] <TB3> INFO: TBM Core beta (1): 7 registers set
[15:08:47.919] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:08:47.919] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:47.919] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:47.919] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:47.919] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:47.919] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:47.919] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:47.919] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:47.919] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:47.919] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:47.919] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:47.919] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:47.919] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:47.919] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:47.919] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:47.919] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:47.919] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:08:48.320] <TB3> INFO: enter 'restricted' command line mode
[15:08:48.320] <TB3> INFO: enter test to run
[15:08:48.321] <TB3> INFO: test: pretest no parameter change
[15:08:48.321] <TB3> INFO: running: pretest
[15:08:48.328] <TB3> INFO: ######################################################################
[15:08:48.328] <TB3> INFO: PixTestPretest::doTest()
[15:08:48.328] <TB3> INFO: ######################################################################
[15:08:48.330] <TB3> INFO: ----------------------------------------------------------------------
[15:08:48.330] <TB3> INFO: PixTestPretest::programROC()
[15:08:48.330] <TB3> INFO: ----------------------------------------------------------------------
[15:09:06.349] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[15:09:06.349] <TB3> INFO: IA differences per ROC: 18.5 19.3 19.3 17.7 19.3 19.3 16.9 18.5 16.9 18.5 19.3 19.3 18.5 18.5 17.7 19.3
[15:09:06.436] <TB3> INFO: ----------------------------------------------------------------------
[15:09:06.437] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[15:09:06.437] <TB3> INFO: ----------------------------------------------------------------------
[15:09:12.327] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 353.7 mA = 22.1062 mA/ROC
[15:09:12.330] <TB3> INFO: ----------------------------------------------------------------------
[15:09:12.330] <TB3> INFO: PixTestPretest::findTiming()
[15:09:12.330] <TB3> INFO: ----------------------------------------------------------------------
[15:09:12.330] <TB3> INFO: PixTestCmd::init()

[15:11:10.744] <TB3> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[15:11:10.744] <TB3> INFO: (success/tries = 100/100), width = 3
[15:11:10.746] <TB3> INFO: ----------------------------------------------------------------------
[15:11:10.747] <TB3> INFO: PixTestPretest::findWorkingPixel()
[15:11:10.747] <TB3> INFO: ----------------------------------------------------------------------
[15:11:10.885] <TB3> INFO: Expecting 231680 events.
[15:11:19.426] <TB3> INFO: 231680 events read in total (7825ms).
[15:11:19.431] <TB3> INFO: Test took 8681ms.
[15:11:19.747] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[15:11:19.787] <TB3> INFO: ----------------------------------------------------------------------
[15:11:19.787] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[15:11:19.787] <TB3> INFO: ----------------------------------------------------------------------
[15:11:19.923] <TB3> INFO: Expecting 231680 events.
[15:11:28.759] <TB3> INFO: 231680 events read in total (8120ms).
[15:11:28.763] <TB3> INFO: Test took 8971ms.
[15:11:29.096] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[15:11:29.097] <TB3> INFO: CalDel: 137 130 138 113 136 129 143 140 158 142 137 145 134 157 157 149
[15:11:29.097] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[15:11:29.100] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C0.dat
[15:11:29.100] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C1.dat
[15:11:29.101] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C2.dat
[15:11:29.101] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C3.dat
[15:11:29.101] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C4.dat
[15:11:29.101] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C5.dat
[15:11:29.102] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C6.dat
[15:11:29.102] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C7.dat
[15:11:29.102] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C8.dat
[15:11:29.103] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C9.dat
[15:11:29.103] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C10.dat
[15:11:29.103] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C11.dat
[15:11:29.103] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C12.dat
[15:11:29.104] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C13.dat
[15:11:29.104] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C14.dat
[15:11:29.104] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters_C15.dat
[15:11:29.105] <TB3> INFO: write tbm parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//tbmParameters_C0a.dat
[15:11:29.105] <TB3> INFO: write tbm parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//tbmParameters_C0b.dat
[15:11:29.105] <TB3> INFO: PixTestPretest::doTest() done, duration: 160 seconds
[15:11:29.177] <TB3> INFO: enter test to run
[15:11:29.177] <TB3> INFO: test: fulltest no parameter change
[15:11:29.177] <TB3> INFO: running: fulltest
[15:11:29.177] <TB3> INFO: ######################################################################
[15:11:29.177] <TB3> INFO: PixTestFullTest::doTest()
[15:11:29.177] <TB3> INFO: ######################################################################
[15:11:29.178] <TB3> INFO: ######################################################################
[15:11:29.178] <TB3> INFO: PixTestAlive::doTest()
[15:11:29.178] <TB3> INFO: ######################################################################
[15:11:29.180] <TB3> INFO: ----------------------------------------------------------------------
[15:11:29.180] <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)
[15:11:29.180] <TB3> INFO: ----------------------------------------------------------------------
[15:11:29.493] <TB3> INFO: Expecting 41600 events.
[15:11:34.096] <TB3> INFO: 41600 events read in total (3888ms).
[15:11:34.096] <TB3> INFO: Test took 4915ms.
[15:11:34.103] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:34.390] <TB3> INFO: PixTestAlive::aliveTest() done
[15:11:34.390] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0
[15:11:34.392] <TB3> INFO: ----------------------------------------------------------------------
[15:11:34.392] <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)
[15:11:34.392] <TB3> INFO: ----------------------------------------------------------------------
[15:11:34.712] <TB3> INFO: Expecting 41600 events.
[15:11:37.993] <TB3> INFO: 41600 events read in total (2565ms).
[15:11:37.993] <TB3> INFO: Test took 3600ms.
[15:11:37.994] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:37.994] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:11:38.312] <TB3> INFO: PixTestAlive::maskTest() done
[15:11:38.312] <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
[15:11:38.313] <TB3> INFO: ----------------------------------------------------------------------
[15:11:38.313] <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)
[15:11:38.313] <TB3> INFO: ----------------------------------------------------------------------
[15:11:38.628] <TB3> INFO: Expecting 41600 events.
[15:11:43.105] <TB3> INFO: 41600 events read in total (3761ms).
[15:11:43.106] <TB3> INFO: Test took 4790ms.
[15:11:43.112] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:43.404] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[15:11:43.404] <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
[15:11:43.404] <TB3> INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:11:43.404] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:43.404] <TB3> INFO: Decoding statistics:
[15:11:43.404] <TB3> INFO: General information:
[15:11:43.404] <TB3> INFO: 16bit words read: 0
[15:11:43.404] <TB3> INFO: valid events total: 0
[15:11:43.404] <TB3> INFO: empty events: 0
[15:11:43.404] <TB3> INFO: valid events with pixels: 0
[15:11:43.404] <TB3> INFO: valid pixel hits: 0
[15:11:43.404] <TB3> INFO: Event errors: 0
[15:11:43.404] <TB3> INFO: start marker: 0
[15:11:43.404] <TB3> INFO: stop marker: 0
[15:11:43.404] <TB3> INFO: overflow: 0
[15:11:43.404] <TB3> INFO: invalid 5bit words: 0
[15:11:43.404] <TB3> INFO: invalid XOR eye diagram: 0
[15:11:43.404] <TB3> INFO: TBM errors: 0
[15:11:43.404] <TB3> INFO: flawed TBM headers: 0
[15:11:43.404] <TB3> INFO: flawed TBM trailers: 0
[15:11:43.404] <TB3> INFO: event ID mismatches: 0
[15:11:43.404] <TB3> INFO: ROC errors: 0
[15:11:43.404] <TB3> INFO: missing ROC header(s): 0
[15:11:43.404] <TB3> INFO: misplaced readback start: 0
[15:11:43.404] <TB3> INFO: Pixel decoding errors: 0
[15:11:43.404] <TB3> INFO: pixel data incomplete: 0
[15:11:43.404] <TB3> INFO: pixel address: 0
[15:11:43.404] <TB3> INFO: pulse height fill bit: 0
[15:11:43.404] <TB3> INFO: buffer corruption: 0
[15:11:43.417] <TB3> INFO: ######################################################################
[15:11:43.417] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[15:11:43.417] <TB3> INFO: ######################################################################
[15:11:43.420] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[15:11:43.431] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:11:43.431] <TB3> INFO: run 1 of 1
[15:11:43.737] <TB3> INFO: Expecting 3120000 events.
[15:12:27.050] <TB3> INFO: 835250 events read in total (42597ms).
[15:13:09.384] <TB3> INFO: 1661730 events read in total (84931ms).
[15:13:50.533] <TB3> INFO: 2501820 events read in total (126080ms).
[15:14:18.629] <TB3> INFO: 3120000 events read in total (154176ms).
[15:14:18.685] <TB3> INFO: Test took 155254ms.
[15:14:18.775] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:14:41.888] <TB3> INFO: PixTestBBMap::doTest() done, duration: 178 seconds
[15:14:41.888] <TB3> INFO: number of dead bumps (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 1 2
[15:14:41.888] <TB3> INFO: separation cut (per ROC): 84 93 94 89 75 75 65 61 63 65 84 69 95 80 84 76
[15:14:41.888] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:14:41.888] <TB3> INFO: Decoding statistics:
[15:14:41.888] <TB3> INFO: General information:
[15:14:41.888] <TB3> INFO: 16bit words read: 0
[15:14:41.888] <TB3> INFO: valid events total: 0
[15:14:41.888] <TB3> INFO: empty events: 0
[15:14:41.888] <TB3> INFO: valid events with pixels: 0
[15:14:41.888] <TB3> INFO: valid pixel hits: 0
[15:14:41.888] <TB3> INFO: Event errors: 0
[15:14:41.888] <TB3> INFO: start marker: 0
[15:14:41.888] <TB3> INFO: stop marker: 0
[15:14:41.888] <TB3> INFO: overflow: 0
[15:14:41.888] <TB3> INFO: invalid 5bit words: 0
[15:14:41.888] <TB3> INFO: invalid XOR eye diagram: 0
[15:14:41.888] <TB3> INFO: TBM errors: 0
[15:14:41.888] <TB3> INFO: flawed TBM headers: 0
[15:14:41.888] <TB3> INFO: flawed TBM trailers: 0
[15:14:41.888] <TB3> INFO: event ID mismatches: 0
[15:14:41.888] <TB3> INFO: ROC errors: 0
[15:14:41.888] <TB3> INFO: missing ROC header(s): 0
[15:14:41.889] <TB3> INFO: misplaced readback start: 0
[15:14:41.889] <TB3> INFO: Pixel decoding errors: 0
[15:14:41.889] <TB3> INFO: pixel data incomplete: 0
[15:14:41.889] <TB3> INFO: pixel address: 0
[15:14:41.889] <TB3> INFO: pulse height fill bit: 0
[15:14:41.889] <TB3> INFO: buffer corruption: 0
[15:14:41.959] <TB3> INFO: ######################################################################
[15:14:41.959] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:14:41.959] <TB3> INFO: ######################################################################
[15:14:41.959] <TB3> INFO: ----------------------------------------------------------------------
[15:14:41.959] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:14:41.959] <TB3> INFO: ----------------------------------------------------------------------
[15:14:41.959] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:14:41.968] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[15:14:41.968] <TB3> INFO: run 1 of 1
[15:14:42.271] <TB3> INFO: Expecting 26208000 events.
[15:15:11.699] <TB3> INFO: 867300 events read in total (28712ms).
[15:15:39.827] <TB3> INFO: 1722500 events read in total (56840ms).
[15:16:09.793] <TB3> INFO: 2576900 events read in total (86806ms).
[15:16:39.828] <TB3> INFO: 3431100 events read in total (116841ms).
[15:17:10.521] <TB3> INFO: 4283850 events read in total (147534ms).
[15:17:38.574] <TB3> INFO: 5135800 events read in total (175587ms).
[15:18:07.334] <TB3> INFO: 5986750 events read in total (204347ms).
[15:18:34.856] <TB3> INFO: 6837800 events read in total (231869ms).
[15:19:04.407] <TB3> INFO: 7689350 events read in total (261420ms).
[15:19:34.249] <TB3> INFO: 8536400 events read in total (291262ms).
[15:20:04.078] <TB3> INFO: 9388800 events read in total (321091ms).
[15:20:34.286] <TB3> INFO: 10236600 events read in total (351299ms).
[15:21:04.081] <TB3> INFO: 11083500 events read in total (381094ms).
[15:21:34.029] <TB3> INFO: 11929600 events read in total (411042ms).
[15:22:03.767] <TB3> INFO: 12777250 events read in total (440780ms).
[15:22:34.068] <TB3> INFO: 13615950 events read in total (471081ms).
[15:23:04.306] <TB3> INFO: 14450050 events read in total (501319ms).
[15:23:34.911] <TB3> INFO: 15286650 events read in total (531924ms).
[15:24:04.621] <TB3> INFO: 16120100 events read in total (561634ms).
[15:24:34.317] <TB3> INFO: 16950650 events read in total (591330ms).
[15:25:04.000] <TB3> INFO: 17783200 events read in total (621013ms).
[15:25:33.738] <TB3> INFO: 18613400 events read in total (650751ms).
[15:26:03.093] <TB3> INFO: 19439650 events read in total (680106ms).
[15:26:32.696] <TB3> INFO: 20270800 events read in total (709709ms).
[15:27:02.405] <TB3> INFO: 21097450 events read in total (739418ms).
[15:27:32.188] <TB3> INFO: 21926450 events read in total (769201ms).
[15:28:02.911] <TB3> INFO: 22754350 events read in total (799924ms).
[15:28:31.966] <TB3> INFO: 23581750 events read in total (828979ms).
[15:29:01.245] <TB3> INFO: 24412100 events read in total (858258ms).
[15:29:30.468] <TB3> INFO: 25244400 events read in total (887481ms).
[15:29:59.687] <TB3> INFO: 26081800 events read in total (916700ms).
[15:30:04.636] <TB3> INFO: 26208000 events read in total (921649ms).
[15:30:04.671] <TB3> INFO: Test took 922703ms.
[15:30:04.768] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:04.930] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:30:06.572] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:30:08.223] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:30:09.776] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:30:11.431] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:30:13.025] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:30:14.527] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:30:16.170] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:30:17.764] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:30:19.398] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:30:21.083] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:30:22.820] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:30:24.421] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:30:26.027] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:30:27.637] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:30:29.367] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:30:31.029] <TB3> INFO: PixTestScurves::scurves() done
[15:30:31.029] <TB3> INFO: Vcal mean: 89.55 98.93 98.72 95.65 87.10 92.12 74.54 63.59 65.65 73.09 87.74 82.07 92.84 87.33 93.71 98.55
[15:30:31.029] <TB3> INFO: Vcal RMS: 5.93 6.36 5.47 5.79 4.82 5.26 5.17 5.39 6.10 5.63 5.63 5.12 5.89 5.31 5.43 5.49
[15:30:31.029] <TB3> INFO: PixTestScurves::fullTest() done, duration: 949 seconds
[15:30:31.030] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:31.030] <TB3> INFO: Decoding statistics:
[15:30:31.030] <TB3> INFO: General information:
[15:30:31.030] <TB3> INFO: 16bit words read: 0
[15:30:31.030] <TB3> INFO: valid events total: 0
[15:30:31.030] <TB3> INFO: empty events: 0
[15:30:31.030] <TB3> INFO: valid events with pixels: 0
[15:30:31.030] <TB3> INFO: valid pixel hits: 0
[15:30:31.030] <TB3> INFO: Event errors: 0
[15:30:31.030] <TB3> INFO: start marker: 0
[15:30:31.030] <TB3> INFO: stop marker: 0
[15:30:31.030] <TB3> INFO: overflow: 0
[15:30:31.030] <TB3> INFO: invalid 5bit words: 0
[15:30:31.030] <TB3> INFO: invalid XOR eye diagram: 0
[15:30:31.030] <TB3> INFO: TBM errors: 0
[15:30:31.030] <TB3> INFO: flawed TBM headers: 0
[15:30:31.030] <TB3> INFO: flawed TBM trailers: 0
[15:30:31.030] <TB3> INFO: event ID mismatches: 0
[15:30:31.030] <TB3> INFO: ROC errors: 0
[15:30:31.030] <TB3> INFO: missing ROC header(s): 0
[15:30:31.030] <TB3> INFO: misplaced readback start: 0
[15:30:31.030] <TB3> INFO: Pixel decoding errors: 0
[15:30:31.030] <TB3> INFO: pixel data incomplete: 0
[15:30:31.030] <TB3> INFO: pixel address: 0
[15:30:31.030] <TB3> INFO: pulse height fill bit: 0
[15:30:31.030] <TB3> INFO: buffer corruption: 0
[15:30:31.105] <TB3> INFO: ######################################################################
[15:30:31.105] <TB3> INFO: PixTestTrim::doTest()
[15:30:31.105] <TB3> INFO: ######################################################################
[15:30:31.107] <TB3> INFO: ----------------------------------------------------------------------
[15:30:31.107] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[15:30:31.107] <TB3> INFO: ----------------------------------------------------------------------
[15:30:31.209] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[15:30:31.209] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:30:31.218] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:30:31.218] <TB3> INFO: run 1 of 1
[15:30:31.556] <TB3> INFO: Expecting 6281600 events.
[15:31:09.627] <TB3> INFO: 1079190 events read in total (37355ms).
[15:31:47.084] <TB3> INFO: 2150410 events read in total (74812ms).
[15:32:23.958] <TB3> INFO: 3216850 events read in total (111686ms).
[15:33:02.317] <TB3> INFO: 4292350 events read in total (150045ms).
[15:33:40.264] <TB3> INFO: 5372630 events read in total (187992ms).
[15:34:12.086] <TB3> INFO: 6281600 events read in total (219814ms).
[15:34:12.131] <TB3> INFO: Test took 220913ms.
[15:34:12.186] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:34:30.331] <TB3> INFO: ROC 0 VthrComp = 90
[15:34:30.331] <TB3> INFO: ROC 1 VthrComp = 99
[15:34:30.331] <TB3> INFO: ROC 2 VthrComp = 99
[15:34:30.331] <TB3> INFO: ROC 3 VthrComp = 94
[15:34:30.332] <TB3> INFO: ROC 4 VthrComp = 92
[15:34:30.332] <TB3> INFO: ROC 5 VthrComp = 89
[15:34:30.332] <TB3> INFO: ROC 6 VthrComp = 68
[15:34:30.332] <TB3> INFO: ROC 7 VthrComp = 29
[15:34:30.332] <TB3> INFO: ROC 8 VthrComp = 24
[15:34:30.332] <TB3> INFO: ROC 9 VthrComp = 64
[15:34:30.332] <TB3> INFO: ROC 10 VthrComp = 85
[15:34:30.332] <TB3> INFO: ROC 11 VthrComp = 81
[15:34:30.332] <TB3> INFO: ROC 12 VthrComp = 95
[15:34:30.333] <TB3> INFO: ROC 13 VthrComp = 84
[15:34:30.333] <TB3> INFO: ROC 14 VthrComp = 91
[15:34:30.333] <TB3> INFO: ROC 15 VthrComp = 98
[15:34:30.333] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[15:34:30.333] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:34:30.343] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:34:30.343] <TB3> INFO: run 1 of 1
[15:34:30.649] <TB3> INFO: Expecting 6281600 events.
[15:35:02.829] <TB3> INFO: 763950 events read in total (31464ms).
[15:35:33.904] <TB3> INFO: 1523830 events read in total (62539ms).
[15:36:06.983] <TB3> INFO: 2282840 events read in total (95618ms).
[15:36:39.985] <TB3> INFO: 3042490 events read in total (128620ms).
[15:37:12.812] <TB3> INFO: 3795420 events read in total (161448ms).
[15:37:45.620] <TB3> INFO: 4544590 events read in total (194255ms).
[15:38:17.912] <TB3> INFO: 5291870 events read in total (226547ms).
[15:38:50.341] <TB3> INFO: 6039550 events read in total (258976ms).
[15:39:01.110] <TB3> INFO: 6281600 events read in total (269745ms).
[15:39:01.159] <TB3> INFO: Test took 270817ms.
[15:39:01.296] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:39:27.300] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 60.6778 for pixel 41/79 mean/min/max = 46.5316/32.2718/60.7915
[15:39:27.300] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 61.2389 for pixel 0/0 mean/min/max = 46.5226/31.4539/61.5914
[15:39:27.301] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 57.77 for pixel 19/10 mean/min/max = 44.8149/31.6928/57.937
[15:39:27.301] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 58.6216 for pixel 45/79 mean/min/max = 45.6732/32.719/58.6274
[15:39:27.301] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 56.1854 for pixel 0/54 mean/min/max = 44.8725/33.488/56.257
[15:39:27.301] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 59.379 for pixel 8/10 mean/min/max = 47.0938/34.8033/59.3844
[15:39:27.302] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 63.2398 for pixel 7/45 mean/min/max = 49.1299/34.8923/63.3675
[15:39:27.302] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 127.616 for pixel 0/42 mean/min/max = 110.355/93.0687/127.642
[15:39:27.302] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 156.811 for pixel 11/67 mean/min/max = 132.191/96.569/167.813
[15:39:27.302] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 70.8727 for pixel 5/27 mean/min/max = 54.2001/37.2152/71.1849
[15:39:27.303] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 61.3606 for pixel 5/77 mean/min/max = 47.3458/33.0769/61.6147
[15:39:27.303] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 60.5885 for pixel 5/13 mean/min/max = 46.7591/32.725/60.7931
[15:39:27.303] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 59.5228 for pixel 10/7 mean/min/max = 46.0648/32.552/59.5776
[15:39:27.303] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 58.834 for pixel 6/79 mean/min/max = 45.6383/32.4336/58.8429
[15:39:27.304] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 58.8868 for pixel 0/59 mean/min/max = 45.9233/32.9372/58.9094
[15:39:27.304] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 60.2751 for pixel 11/66 mean/min/max = 46.2877/32.2769/60.2986
[15:39:27.304] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:39:27.435] <TB3> INFO: Expecting 514560 events.
[15:39:38.657] <TB3> INFO: 514560 events read in total (10506ms).
[15:39:38.664] <TB3> INFO: Expecting 514560 events.
[15:39:49.217] <TB3> INFO: 514560 events read in total (9983ms).
[15:39:49.224] <TB3> INFO: Expecting 514560 events.
[15:39:59.802] <TB3> INFO: 514560 events read in total (9997ms).
[15:39:59.812] <TB3> INFO: Expecting 514560 events.
[15:40:10.444] <TB3> INFO: 514560 events read in total (10058ms).
[15:40:10.456] <TB3> INFO: Expecting 514560 events.
[15:40:21.032] <TB3> INFO: 514560 events read in total (10001ms).
[15:40:21.045] <TB3> INFO: Expecting 514560 events.
[15:40:31.579] <TB3> INFO: 514560 events read in total (9958ms).
[15:40:31.595] <TB3> INFO: Expecting 514560 events.
[15:40:42.014] <TB3> INFO: 514560 events read in total (9851ms).
[15:40:42.030] <TB3> INFO: Expecting 514560 events.
[15:40:52.563] <TB3> INFO: 514560 events read in total (9956ms).
[15:40:52.579] <TB3> INFO: Expecting 514560 events.
[15:41:04.135] <TB3> INFO: 514560 events read in total (10979ms).
[15:41:04.154] <TB3> INFO: Expecting 514560 events.
[15:41:15.602] <TB3> INFO: 514560 events read in total (10877ms).
[15:41:15.621] <TB3> INFO: Expecting 514560 events.
[15:41:27.206] <TB3> INFO: 514560 events read in total (11011ms).
[15:41:27.229] <TB3> INFO: Expecting 514560 events.
[15:41:38.829] <TB3> INFO: 514560 events read in total (11042ms).
[15:41:38.852] <TB3> INFO: Expecting 514560 events.
[15:41:50.531] <TB3> INFO: 514560 events read in total (11123ms).
[15:41:50.557] <TB3> INFO: Expecting 514560 events.
[15:42:02.055] <TB3> INFO: 514560 events read in total (10949ms).
[15:42:02.085] <TB3> INFO: Expecting 514560 events.
[15:42:13.486] <TB3> INFO: 514560 events read in total (10841ms).
[15:42:13.514] <TB3> INFO: Expecting 514560 events.
[15:42:25.048] <TB3> INFO: 514560 events read in total (10977ms).
[15:42:25.080] <TB3> INFO: Test took 177776ms.
[15:42:26.056] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:42:26.065] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:42:26.065] <TB3> INFO: run 1 of 1
[15:42:26.401] <TB3> INFO: Expecting 6281600 events.
[15:43:02.012] <TB3> INFO: 748070 events read in total (34895ms).
[15:43:35.762] <TB3> INFO: 1494050 events read in total (68645ms).
[15:44:08.950] <TB3> INFO: 2239400 events read in total (101833ms).
[15:44:42.245] <TB3> INFO: 2984900 events read in total (135128ms).
[15:45:15.010] <TB3> INFO: 3724170 events read in total (167893ms).
[15:45:48.427] <TB3> INFO: 4459190 events read in total (201310ms).
[15:46:20.979] <TB3> INFO: 5191630 events read in total (233862ms).
[15:46:53.380] <TB3> INFO: 5923070 events read in total (266263ms).
[15:47:09.287] <TB3> INFO: 6281600 events read in total (282170ms).
[15:47:09.348] <TB3> INFO: Test took 283283ms.
[15:47:09.483] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:47:35.502] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.063237 .. 255.000000
[15:47:35.601] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[15:47:35.613] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:47:35.613] <TB3> INFO: run 1 of 1
[15:47:35.948] <TB3> INFO: Expecting 10649600 events.
[15:48:08.598] <TB3> INFO: 713050 events read in total (31934ms).
[15:48:39.290] <TB3> INFO: 1425910 events read in total (62626ms).
[15:49:09.065] <TB3> INFO: 2139360 events read in total (92401ms).
[15:49:39.600] <TB3> INFO: 2852880 events read in total (122936ms).
[15:50:11.344] <TB3> INFO: 3566150 events read in total (154680ms).
[15:50:43.416] <TB3> INFO: 4279960 events read in total (186752ms).
[15:51:15.388] <TB3> INFO: 4993670 events read in total (218724ms).
[15:51:47.768] <TB3> INFO: 5707310 events read in total (251104ms).
[15:52:19.867] <TB3> INFO: 6420450 events read in total (283203ms).
[15:52:52.179] <TB3> INFO: 7133080 events read in total (315515ms).
[15:53:24.592] <TB3> INFO: 7845630 events read in total (347928ms).
[15:53:56.683] <TB3> INFO: 8557420 events read in total (380019ms).
[15:54:29.363] <TB3> INFO: 9269080 events read in total (412699ms).
[15:55:02.019] <TB3> INFO: 9980930 events read in total (445355ms).
[15:55:32.974] <TB3> INFO: 10649600 events read in total (476310ms).
[15:55:33.066] <TB3> INFO: Test took 477452ms.
[15:55:33.363] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:56:06.741] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 15.586686 .. 77.801964
[15:56:06.823] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 5 .. 87 (-1/-1) hits flags = 528 (plus default)
[15:56:06.831] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:56:06.831] <TB3> INFO: run 1 of 1
[15:56:07.135] <TB3> INFO: Expecting 3452800 events.
[15:56:40.048] <TB3> INFO: 806590 events read in total (32197ms).
[15:57:13.221] <TB3> INFO: 1614200 events read in total (65370ms).
[15:57:46.569] <TB3> INFO: 2420210 events read in total (98719ms).
[15:58:19.373] <TB3> INFO: 3226530 events read in total (131522ms).
[15:58:28.314] <TB3> INFO: 3452800 events read in total (140463ms).
[15:58:28.340] <TB3> INFO: Test took 141509ms.
[15:58:28.408] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:58:46.155] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 1.457098 .. 60.766650
[15:58:46.255] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 1 .. 70 (-1/-1) hits flags = 528 (plus default)
[15:58:46.264] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:58:46.264] <TB3> INFO: run 1 of 1
[15:58:46.606] <TB3> INFO: Expecting 2912000 events.
[15:59:21.826] <TB3> INFO: 877510 events read in total (34504ms).
[15:59:55.771] <TB3> INFO: 1754850 events read in total (68449ms).
[16:00:30.118] <TB3> INFO: 2631230 events read in total (102797ms).
[16:00:42.152] <TB3> INFO: 2912000 events read in total (114830ms).
[16:00:42.169] <TB3> INFO: Test took 115905ms.
[16:00:42.215] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:00:59.648] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 1.457098 .. 54.364910
[16:00:59.735] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 1 .. 64 (-1/-1) hits flags = 528 (plus default)
[16:00:59.745] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:00:59.745] <TB3> INFO: run 1 of 1
[16:01:00.084] <TB3> INFO: Expecting 2662400 events.
[16:01:35.688] <TB3> INFO: 904110 events read in total (34885ms).
[16:02:10.884] <TB3> INFO: 1808280 events read in total (70081ms).
[16:02:43.144] <TB3> INFO: 2662400 events read in total (102341ms).
[16:02:43.165] <TB3> INFO: Test took 103421ms.
[16:02:43.210] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:02:58.815] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[16:02:58.815] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[16:02:58.824] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:02:58.824] <TB3> INFO: run 1 of 1
[16:02:59.130] <TB3> INFO: Expecting 1705600 events.
[16:03:34.817] <TB3> INFO: 878890 events read in total (34971ms).
[16:04:07.782] <TB3> INFO: 1705600 events read in total (67936ms).
[16:04:07.797] <TB3> INFO: Test took 68973ms.
[16:04:07.830] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:22.929] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:04:22.929] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:04:22.929] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:04:22.929] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:04:22.929] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:04:22.929] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:04:22.929] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:04:22.929] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:04:22.929] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:04:22.930] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:04:22.930] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:04:22.930] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:04:22.930] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:04:22.930] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:04:22.930] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:04:22.930] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:04:22.930] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C0.dat
[16:04:22.939] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C1.dat
[16:04:22.948] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C2.dat
[16:04:22.956] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C3.dat
[16:04:22.964] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C4.dat
[16:04:22.971] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C5.dat
[16:04:22.977] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C6.dat
[16:04:22.984] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C7.dat
[16:04:22.991] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C8.dat
[16:04:22.997] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C9.dat
[16:04:23.004] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C10.dat
[16:04:23.010] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C11.dat
[16:04:23.017] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C12.dat
[16:04:23.024] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C13.dat
[16:04:23.030] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C14.dat
[16:04:23.037] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//trimParameters35_C15.dat
[16:04:23.043] <TB3> INFO: PixTestTrim::trimTest() done
[16:04:23.043] <TB3> INFO: vtrim: 114 107 108 81 90 104 88 150 174 95 99 109 113 90 89 111
[16:04:23.043] <TB3> INFO: vthrcomp: 90 99 99 94 92 89 68 29 24 64 85 81 95 84 91 98
[16:04:23.043] <TB3> INFO: vcal mean: 34.97 34.98 34.98 34.99 34.99 35.00 34.99 35.59 36.21 34.93 34.98 34.97 34.98 34.94 34.98 34.98
[16:04:23.043] <TB3> INFO: vcal RMS: 0.89 0.88 0.87 0.85 0.77 0.86 0.89 2.40 3.32 1.17 0.85 1.02 0.85 1.12 0.85 0.88
[16:04:23.043] <TB3> INFO: bits mean: 9.37 8.90 9.95 9.36 9.39 8.74 8.25 2.01 1.68 7.44 8.93 9.55 9.48 9.52 9.11 9.40
[16:04:23.043] <TB3> INFO: bits RMS: 2.66 3.00 2.58 2.63 2.60 2.48 2.50 0.96 0.81 2.24 2.69 2.52 2.61 2.65 2.64 2.68
[16:04:23.050] <TB3> INFO: ----------------------------------------------------------------------
[16:04:23.050] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[16:04:23.050] <TB3> INFO: ----------------------------------------------------------------------
[16:04:23.053] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[16:04:23.061] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:04:23.061] <TB3> INFO: run 1 of 1
[16:04:23.363] <TB3> INFO: Expecting 8320000 events.
[16:04:59.350] <TB3> INFO: 927770 events read in total (35271ms).
[16:05:34.662] <TB3> INFO: 1848990 events read in total (70583ms).
[16:06:10.545] <TB3> INFO: 2768020 events read in total (106466ms).
[16:06:45.976] <TB3> INFO: 3683910 events read in total (141897ms).
[16:07:20.195] <TB3> INFO: 4593700 events read in total (176116ms).
[16:07:55.809] <TB3> INFO: 5498900 events read in total (211730ms).
[16:08:28.521] <TB3> INFO: 6401160 events read in total (244442ms).
[16:09:03.528] <TB3> INFO: 7301890 events read in total (279449ms).
[16:09:38.920] <TB3> INFO: 8206590 events read in total (314841ms).
[16:09:43.794] <TB3> INFO: 8320000 events read in total (319715ms).
[16:09:43.839] <TB3> INFO: Test took 320777ms.
[16:09:43.955] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:10:10.115] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 183 (-1/-1) hits flags = 528 (plus default)
[16:10:10.123] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:10:10.123] <TB3> INFO: run 1 of 1
[16:10:10.425] <TB3> INFO: Expecting 7654400 events.
[16:10:45.280] <TB3> INFO: 920840 events read in total (34139ms).
[16:11:20.406] <TB3> INFO: 1834450 events read in total (69265ms).
[16:11:55.658] <TB3> INFO: 2746730 events read in total (104517ms).
[16:12:30.333] <TB3> INFO: 3654830 events read in total (139192ms).
[16:13:05.274] <TB3> INFO: 4554190 events read in total (174133ms).
[16:13:39.855] <TB3> INFO: 5449900 events read in total (208714ms).
[16:14:14.822] <TB3> INFO: 6343580 events read in total (243681ms).
[16:14:49.973] <TB3> INFO: 7237580 events read in total (278832ms).
[16:15:06.489] <TB3> INFO: 7654400 events read in total (295348ms).
[16:15:06.530] <TB3> INFO: Test took 296407ms.
[16:15:06.639] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:15:34.767] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 163 (-1/-1) hits flags = 528 (plus default)
[16:15:34.776] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:15:34.776] <TB3> INFO: run 1 of 1
[16:15:35.110] <TB3> INFO: Expecting 6822400 events.
[16:16:12.170] <TB3> INFO: 965220 events read in total (36344ms).
[16:16:46.811] <TB3> INFO: 1922020 events read in total (70985ms).
[16:17:21.914] <TB3> INFO: 2875710 events read in total (106088ms).
[16:17:57.666] <TB3> INFO: 3820390 events read in total (141840ms).
[16:18:32.490] <TB3> INFO: 4756560 events read in total (176664ms).
[16:19:07.893] <TB3> INFO: 5689040 events read in total (212067ms).
[16:19:43.727] <TB3> INFO: 6623320 events read in total (247901ms).
[16:19:52.024] <TB3> INFO: 6822400 events read in total (256198ms).
[16:19:52.060] <TB3> INFO: Test took 257284ms.
[16:19:52.146] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:20:19.536] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 164 (-1/-1) hits flags = 528 (plus default)
[16:20:19.545] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:20:19.545] <TB3> INFO: run 1 of 1
[16:20:19.875] <TB3> INFO: Expecting 6864000 events.
[16:20:57.055] <TB3> INFO: 961670 events read in total (36464ms).
[16:21:34.080] <TB3> INFO: 1915740 events read in total (73489ms).
[16:22:09.299] <TB3> INFO: 2865720 events read in total (108708ms).
[16:22:46.282] <TB3> INFO: 3807700 events read in total (145691ms).
[16:23:22.734] <TB3> INFO: 4741080 events read in total (182143ms).
[16:23:59.437] <TB3> INFO: 5670720 events read in total (218846ms).
[16:24:36.368] <TB3> INFO: 6601670 events read in total (255777ms).
[16:24:46.533] <TB3> INFO: 6864000 events read in total (265942ms).
[16:24:46.564] <TB3> INFO: Test took 267019ms.
[16:24:46.643] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:10.541] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 162 (-1/-1) hits flags = 528 (plus default)
[16:25:10.549] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:25:10.550] <TB3> INFO: run 1 of 1
[16:25:10.851] <TB3> INFO: Expecting 6780800 events.
[16:25:46.313] <TB3> INFO: 966020 events read in total (34746ms).
[16:26:21.844] <TB3> INFO: 1924140 events read in total (70277ms).
[16:26:57.712] <TB3> INFO: 2878320 events read in total (106145ms).
[16:27:32.133] <TB3> INFO: 3822960 events read in total (140566ms).
[16:28:08.481] <TB3> INFO: 4759420 events read in total (176914ms).
[16:28:45.440] <TB3> INFO: 5693030 events read in total (213873ms).
[16:29:22.369] <TB3> INFO: 6628030 events read in total (250802ms).
[16:29:28.642] <TB3> INFO: 6780800 events read in total (257075ms).
[16:29:28.671] <TB3> INFO: Test took 258121ms.
[16:29:28.747] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:53.799] <TB3> INFO: PixTestTrim::trimBitTest() done
[16:29:53.800] <TB3> INFO: PixTestTrim::doTest() done, duration: 3562 seconds
[16:29:53.800] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:53.800] <TB3> INFO: Decoding statistics:
[16:29:53.800] <TB3> INFO: General information:
[16:29:53.800] <TB3> INFO: 16bit words read: 0
[16:29:53.800] <TB3> INFO: valid events total: 0
[16:29:53.800] <TB3> INFO: empty events: 0
[16:29:53.800] <TB3> INFO: valid events with pixels: 0
[16:29:53.800] <TB3> INFO: valid pixel hits: 0
[16:29:53.800] <TB3> INFO: Event errors: 0
[16:29:53.800] <TB3> INFO: start marker: 0
[16:29:53.800] <TB3> INFO: stop marker: 0
[16:29:53.800] <TB3> INFO: overflow: 0
[16:29:53.800] <TB3> INFO: invalid 5bit words: 0
[16:29:53.800] <TB3> INFO: invalid XOR eye diagram: 0
[16:29:53.800] <TB3> INFO: TBM errors: 0
[16:29:53.800] <TB3> INFO: flawed TBM headers: 0
[16:29:53.800] <TB3> INFO: flawed TBM trailers: 0
[16:29:53.800] <TB3> INFO: event ID mismatches: 0
[16:29:53.800] <TB3> INFO: ROC errors: 0
[16:29:53.800] <TB3> INFO: missing ROC header(s): 0
[16:29:53.800] <TB3> INFO: misplaced readback start: 0
[16:29:53.800] <TB3> INFO: Pixel decoding errors: 0
[16:29:53.800] <TB3> INFO: pixel data incomplete: 0
[16:29:53.800] <TB3> INFO: pixel address: 0
[16:29:53.800] <TB3> INFO: pulse height fill bit: 0
[16:29:53.800] <TB3> INFO: buffer corruption: 0
[16:29:54.458] <TB3> INFO: ######################################################################
[16:29:54.458] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[16:29:54.458] <TB3> INFO: ######################################################################
[16:29:54.766] <TB3> INFO: Expecting 41600 events.
[16:29:59.119] <TB3> INFO: 41600 events read in total (3637ms).
[16:29:59.119] <TB3> INFO: Test took 4659ms.
[16:29:59.126] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:59.704] <TB3> INFO: Expecting 41600 events.
[16:30:04.106] <TB3> INFO: 41600 events read in total (3687ms).
[16:30:04.107] <TB3> INFO: Test took 4722ms.
[16:30:04.114] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:04.458] <TB3> INFO: Expecting 41600 events.
[16:30:08.811] <TB3> INFO: 41600 events read in total (3637ms).
[16:30:08.811] <TB3> INFO: Test took 4685ms.
[16:30:08.818] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:09.159] <TB3> INFO: Expecting 2560 events.
[16:30:10.225] <TB3> INFO: 2560 events read in total (350ms).
[16:30:10.225] <TB3> INFO: Test took 1400ms.
[16:30:10.639] <TB3> INFO: Expecting 2560 events.
[16:30:11.704] <TB3> INFO: 2560 events read in total (349ms).
[16:30:11.704] <TB3> INFO: Test took 1478ms.
[16:30:12.118] <TB3> INFO: Expecting 2560 events.
[16:30:13.181] <TB3> INFO: 2560 events read in total (348ms).
[16:30:13.181] <TB3> INFO: Test took 1476ms.
[16:30:13.595] <TB3> INFO: Expecting 2560 events.
[16:30:14.658] <TB3> INFO: 2560 events read in total (347ms).
[16:30:14.658] <TB3> INFO: Test took 1477ms.
[16:30:15.073] <TB3> INFO: Expecting 2560 events.
[16:30:16.135] <TB3> INFO: 2560 events read in total (347ms).
[16:30:16.135] <TB3> INFO: Test took 1476ms.
[16:30:16.550] <TB3> INFO: Expecting 2560 events.
[16:30:17.620] <TB3> INFO: 2560 events read in total (354ms).
[16:30:17.620] <TB3> INFO: Test took 1484ms.
[16:30:18.034] <TB3> INFO: Expecting 2560 events.
[16:30:19.098] <TB3> INFO: 2560 events read in total (349ms).
[16:30:19.098] <TB3> INFO: Test took 1478ms.
[16:30:19.513] <TB3> INFO: Expecting 2560 events.
[16:30:20.575] <TB3> INFO: 2560 events read in total (347ms).
[16:30:20.575] <TB3> INFO: Test took 1476ms.
[16:30:20.989] <TB3> INFO: Expecting 2560 events.
[16:30:22.054] <TB3> INFO: 2560 events read in total (348ms).
[16:30:22.054] <TB3> INFO: Test took 1478ms.
[16:30:22.468] <TB3> INFO: Expecting 2560 events.
[16:30:23.534] <TB3> INFO: 2560 events read in total (350ms).
[16:30:23.534] <TB3> INFO: Test took 1471ms.
[16:30:23.949] <TB3> INFO: Expecting 2560 events.
[16:30:25.014] <TB3> INFO: 2560 events read in total (350ms).
[16:30:25.014] <TB3> INFO: Test took 1480ms.
[16:30:25.428] <TB3> INFO: Expecting 2560 events.
[16:30:26.498] <TB3> INFO: 2560 events read in total (354ms).
[16:30:26.499] <TB3> INFO: Test took 1485ms.
[16:30:26.913] <TB3> INFO: Expecting 2560 events.
[16:30:27.982] <TB3> INFO: 2560 events read in total (353ms).
[16:30:27.982] <TB3> INFO: Test took 1483ms.
[16:30:28.396] <TB3> INFO: Expecting 2560 events.
[16:30:29.465] <TB3> INFO: 2560 events read in total (353ms).
[16:30:29.465] <TB3> INFO: Test took 1483ms.
[16:30:29.879] <TB3> INFO: Expecting 2560 events.
[16:30:30.947] <TB3> INFO: 2560 events read in total (352ms).
[16:30:30.947] <TB3> INFO: Test took 1482ms.
[16:30:31.362] <TB3> INFO: Expecting 2560 events.
[16:30:32.429] <TB3> INFO: 2560 events read in total (352ms).
[16:30:32.430] <TB3> INFO: Test took 1482ms.
[16:30:32.433] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:30:32.846] <TB3> INFO: Expecting 655360 events.
[16:30:47.246] <TB3> INFO: 655360 events read in total (13685ms).
[16:30:47.257] <TB3> INFO: Expecting 655360 events.
[16:31:01.586] <TB3> INFO: 655360 events read in total (13801ms).
[16:31:01.599] <TB3> INFO: Expecting 655360 events.
[16:31:16.269] <TB3> INFO: 655360 events read in total (14144ms).
[16:31:16.285] <TB3> INFO: Expecting 655360 events.
[16:31:30.704] <TB3> INFO: 655360 events read in total (13891ms).
[16:31:30.723] <TB3> INFO: Expecting 655360 events.
[16:31:45.049] <TB3> INFO: 655360 events read in total (13799ms).
[16:31:45.072] <TB3> INFO: Expecting 655360 events.
[16:31:59.827] <TB3> INFO: 655360 events read in total (14228ms).
[16:31:59.858] <TB3> INFO: Expecting 655360 events.
[16:32:14.158] <TB3> INFO: 655360 events read in total (13772ms).
[16:32:14.195] <TB3> INFO: Expecting 655360 events.
[16:32:29.037] <TB3> INFO: 655360 events read in total (14315ms).
[16:32:29.069] <TB3> INFO: Expecting 655360 events.
[16:32:43.498] <TB3> INFO: 655360 events read in total (13902ms).
[16:32:43.537] <TB3> INFO: Expecting 655360 events.
[16:32:57.999] <TB3> INFO: 655360 events read in total (13935ms).
[16:32:58.044] <TB3> INFO: Expecting 655360 events.
[16:33:12.069] <TB3> INFO: 655360 events read in total (13497ms).
[16:33:12.112] <TB3> INFO: Expecting 655360 events.
[16:33:25.733] <TB3> INFO: 655360 events read in total (13094ms).
[16:33:25.779] <TB3> INFO: Expecting 655360 events.
[16:33:39.955] <TB3> INFO: 655360 events read in total (13649ms).
[16:33:40.005] <TB3> INFO: Expecting 655360 events.
[16:33:53.846] <TB3> INFO: 655360 events read in total (13313ms).
[16:33:53.910] <TB3> INFO: Expecting 655360 events.
[16:34:07.508] <TB3> INFO: 655360 events read in total (13071ms).
[16:34:07.574] <TB3> INFO: Expecting 655360 events.
[16:34:21.139] <TB3> INFO: 655360 events read in total (13038ms).
[16:34:21.205] <TB3> INFO: Test took 228773ms.
[16:34:21.289] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:34:21.598] <TB3> INFO: Expecting 655360 events.
[16:34:36.735] <TB3> INFO: 655360 events read in total (14421ms).
[16:34:36.744] <TB3> INFO: Expecting 655360 events.
[16:34:50.993] <TB3> INFO: 655360 events read in total (13722ms).
[16:34:51.006] <TB3> INFO: Expecting 655360 events.
[16:35:05.660] <TB3> INFO: 655360 events read in total (14127ms).
[16:35:05.678] <TB3> INFO: Expecting 655360 events.
[16:35:20.153] <TB3> INFO: 655360 events read in total (13948ms).
[16:35:20.178] <TB3> INFO: Expecting 655360 events.
[16:35:34.548] <TB3> INFO: 655360 events read in total (13843ms).
[16:35:34.570] <TB3> INFO: Expecting 655360 events.
[16:35:49.367] <TB3> INFO: 655360 events read in total (14270ms).
[16:35:49.397] <TB3> INFO: Expecting 655360 events.
[16:36:03.835] <TB3> INFO: 655360 events read in total (13910ms).
[16:36:03.864] <TB3> INFO: Expecting 655360 events.
[16:36:18.045] <TB3> INFO: 655360 events read in total (13653ms).
[16:36:18.078] <TB3> INFO: Expecting 655360 events.
[16:36:32.598] <TB3> INFO: 655360 events read in total (13992ms).
[16:36:32.639] <TB3> INFO: Expecting 655360 events.
[16:36:46.242] <TB3> INFO: 655360 events read in total (13075ms).
[16:36:46.285] <TB3> INFO: Expecting 655360 events.
[16:37:00.840] <TB3> INFO: 655360 events read in total (14027ms).
[16:37:00.882] <TB3> INFO: Expecting 655360 events.
[16:37:15.647] <TB3> INFO: 655360 events read in total (14238ms).
[16:37:15.700] <TB3> INFO: Expecting 655360 events.
[16:37:30.007] <TB3> INFO: 655360 events read in total (13779ms).
[16:37:30.061] <TB3> INFO: Expecting 655360 events.
[16:37:44.608] <TB3> INFO: 655360 events read in total (14019ms).
[16:37:44.662] <TB3> INFO: Expecting 655360 events.
[16:37:58.986] <TB3> INFO: 655360 events read in total (13796ms).
[16:37:59.042] <TB3> INFO: Expecting 655360 events.
[16:38:13.385] <TB3> INFO: 655360 events read in total (13815ms).
[16:38:13.445] <TB3> INFO: Test took 232156ms.
[16:38:13.635] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:13.642] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:13.651] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:13.658] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:13.664] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:13.671] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:13.678] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:13.685] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:13.692] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:13.699] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:13.706] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:13.713] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:13.720] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:38:13.727] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[16:38:13.734] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[16:38:13.741] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[16:38:13.748] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[16:38:13.755] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:13.762] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:13.769] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:13.776] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:38:13.783] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[16:38:13.790] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[16:38:13.797] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[16:38:13.804] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[16:38:13.811] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[16:38:13.817] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:38:13.857] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:38:13.862] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:38:13.916] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:38:13.916] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:38:13.916] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:38:13.916] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:38:13.916] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:38:13.916] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:38:13.916] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:38:13.917] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:38:13.917] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:38:13.917] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:38:13.917] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:38:13.917] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:38:13.917] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:38:13.917] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:38:14.226] <TB3> INFO: Expecting 41600 events.
[16:38:18.266] <TB3> INFO: 41600 events read in total (3324ms).
[16:38:18.267] <TB3> INFO: Test took 4347ms.
[16:38:18.820] <TB3> INFO: Expecting 41600 events.
[16:38:22.844] <TB3> INFO: 41600 events read in total (3308ms).
[16:38:22.845] <TB3> INFO: Test took 4338ms.
[16:38:23.430] <TB3> INFO: Expecting 41600 events.
[16:38:27.462] <TB3> INFO: 41600 events read in total (3316ms).
[16:38:27.463] <TB3> INFO: Test took 4382ms.
[16:38:27.691] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:27.823] <TB3> INFO: Expecting 2560 events.
[16:38:28.891] <TB3> INFO: 2560 events read in total (352ms).
[16:38:28.891] <TB3> INFO: Test took 1200ms.
[16:38:28.893] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:29.308] <TB3> INFO: Expecting 2560 events.
[16:38:30.376] <TB3> INFO: 2560 events read in total (352ms).
[16:38:30.376] <TB3> INFO: Test took 1483ms.
[16:38:30.378] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:30.790] <TB3> INFO: Expecting 2560 events.
[16:38:31.852] <TB3> INFO: 2560 events read in total (346ms).
[16:38:31.852] <TB3> INFO: Test took 1474ms.
[16:38:31.854] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:32.266] <TB3> INFO: Expecting 2560 events.
[16:38:33.328] <TB3> INFO: 2560 events read in total (346ms).
[16:38:33.328] <TB3> INFO: Test took 1474ms.
[16:38:33.330] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:33.742] <TB3> INFO: Expecting 2560 events.
[16:38:34.804] <TB3> INFO: 2560 events read in total (346ms).
[16:38:34.804] <TB3> INFO: Test took 1474ms.
[16:38:34.806] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:35.219] <TB3> INFO: Expecting 2560 events.
[16:38:36.281] <TB3> INFO: 2560 events read in total (346ms).
[16:38:36.281] <TB3> INFO: Test took 1475ms.
[16:38:36.282] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:36.696] <TB3> INFO: Expecting 2560 events.
[16:38:37.758] <TB3> INFO: 2560 events read in total (346ms).
[16:38:37.758] <TB3> INFO: Test took 1476ms.
[16:38:37.760] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:38.173] <TB3> INFO: Expecting 2560 events.
[16:38:39.235] <TB3> INFO: 2560 events read in total (346ms).
[16:38:39.235] <TB3> INFO: Test took 1475ms.
[16:38:39.238] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:39.650] <TB3> INFO: Expecting 2560 events.
[16:38:40.711] <TB3> INFO: 2560 events read in total (345ms).
[16:38:40.711] <TB3> INFO: Test took 1474ms.
[16:38:40.713] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:41.126] <TB3> INFO: Expecting 2560 events.
[16:38:42.189] <TB3> INFO: 2560 events read in total (347ms).
[16:38:42.189] <TB3> INFO: Test took 1476ms.
[16:38:42.191] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:42.604] <TB3> INFO: Expecting 2560 events.
[16:38:43.668] <TB3> INFO: 2560 events read in total (349ms).
[16:38:43.668] <TB3> INFO: Test took 1477ms.
[16:38:43.670] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:44.082] <TB3> INFO: Expecting 2560 events.
[16:38:45.144] <TB3> INFO: 2560 events read in total (346ms).
[16:38:45.144] <TB3> INFO: Test took 1474ms.
[16:38:45.146] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:45.559] <TB3> INFO: Expecting 2560 events.
[16:38:46.622] <TB3> INFO: 2560 events read in total (347ms).
[16:38:46.623] <TB3> INFO: Test took 1477ms.
[16:38:46.625] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:47.037] <TB3> INFO: Expecting 2560 events.
[16:38:48.101] <TB3> INFO: 2560 events read in total (348ms).
[16:38:48.101] <TB3> INFO: Test took 1477ms.
[16:38:48.103] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:48.516] <TB3> INFO: Expecting 2560 events.
[16:38:49.579] <TB3> INFO: 2560 events read in total (348ms).
[16:38:49.580] <TB3> INFO: Test took 1477ms.
[16:38:49.582] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:49.994] <TB3> INFO: Expecting 2560 events.
[16:38:51.056] <TB3> INFO: 2560 events read in total (346ms).
[16:38:51.056] <TB3> INFO: Test took 1474ms.
[16:38:51.058] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:51.471] <TB3> INFO: Expecting 2560 events.
[16:38:52.532] <TB3> INFO: 2560 events read in total (346ms).
[16:38:52.532] <TB3> INFO: Test took 1474ms.
[16:38:52.534] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:52.947] <TB3> INFO: Expecting 2560 events.
[16:38:54.011] <TB3> INFO: 2560 events read in total (348ms).
[16:38:54.011] <TB3> INFO: Test took 1477ms.
[16:38:54.013] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:54.426] <TB3> INFO: Expecting 2560 events.
[16:38:55.488] <TB3> INFO: 2560 events read in total (346ms).
[16:38:55.488] <TB3> INFO: Test took 1475ms.
[16:38:55.490] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:55.903] <TB3> INFO: Expecting 2560 events.
[16:38:56.964] <TB3> INFO: 2560 events read in total (346ms).
[16:38:56.965] <TB3> INFO: Test took 1475ms.
[16:38:56.967] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:57.379] <TB3> INFO: Expecting 2560 events.
[16:38:58.443] <TB3> INFO: 2560 events read in total (348ms).
[16:38:58.443] <TB3> INFO: Test took 1476ms.
[16:38:58.446] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:38:58.857] <TB3> INFO: Expecting 2560 events.
[16:38:59.920] <TB3> INFO: 2560 events read in total (347ms).
[16:38:59.920] <TB3> INFO: Test took 1475ms.
[16:38:59.922] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:00.335] <TB3> INFO: Expecting 2560 events.
[16:39:01.396] <TB3> INFO: 2560 events read in total (346ms).
[16:39:01.396] <TB3> INFO: Test took 1474ms.
[16:39:01.398] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:01.811] <TB3> INFO: Expecting 2560 events.
[16:39:02.872] <TB3> INFO: 2560 events read in total (345ms).
[16:39:02.873] <TB3> INFO: Test took 1475ms.
[16:39:02.874] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:03.289] <TB3> INFO: Expecting 2560 events.
[16:39:04.351] <TB3> INFO: 2560 events read in total (347ms).
[16:39:04.351] <TB3> INFO: Test took 1477ms.
[16:39:04.353] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:04.766] <TB3> INFO: Expecting 2560 events.
[16:39:05.829] <TB3> INFO: 2560 events read in total (348ms).
[16:39:05.829] <TB3> INFO: Test took 1476ms.
[16:39:05.831] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:06.243] <TB3> INFO: Expecting 2560 events.
[16:39:07.307] <TB3> INFO: 2560 events read in total (348ms).
[16:39:07.307] <TB3> INFO: Test took 1476ms.
[16:39:07.309] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:07.722] <TB3> INFO: Expecting 2560 events.
[16:39:08.787] <TB3> INFO: 2560 events read in total (349ms).
[16:39:08.787] <TB3> INFO: Test took 1478ms.
[16:39:08.790] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:09.202] <TB3> INFO: Expecting 2560 events.
[16:39:10.271] <TB3> INFO: 2560 events read in total (353ms).
[16:39:10.271] <TB3> INFO: Test took 1481ms.
[16:39:10.276] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:10.687] <TB3> INFO: Expecting 2560 events.
[16:39:11.749] <TB3> INFO: 2560 events read in total (347ms).
[16:39:11.749] <TB3> INFO: Test took 1475ms.
[16:39:11.754] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:12.165] <TB3> INFO: Expecting 2560 events.
[16:39:13.229] <TB3> INFO: 2560 events read in total (348ms).
[16:39:13.229] <TB3> INFO: Test took 1476ms.
[16:39:13.233] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:39:13.644] <TB3> INFO: Expecting 2560 events.
[16:39:14.708] <TB3> INFO: 2560 events read in total (348ms).
[16:39:14.709] <TB3> INFO: Test took 1476ms.
[16:39:15.325] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 560 seconds
[16:39:15.325] <TB3> INFO: PH scale (per ROC): 70 76 74 79 74 69 76 72 66 65 78 80 73 70 69 69
[16:39:15.325] <TB3> INFO: PH offset (per ROC): 174 174 193 185 171 191 195 169 187 188 161 170 188 170 176 187
[16:39:15.330] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:15.330] <TB3> INFO: Decoding statistics:
[16:39:15.330] <TB3> INFO: General information:
[16:39:15.330] <TB3> INFO: 16bit words read: 91928
[16:39:15.330] <TB3> INFO: valid events total: 10240
[16:39:15.330] <TB3> INFO: empty events: 7738
[16:39:15.330] <TB3> INFO: valid events with pixels: 2502
[16:39:15.330] <TB3> INFO: valid pixel hits: 2502
[16:39:15.330] <TB3> INFO: Event errors: 0
[16:39:15.330] <TB3> INFO: start marker: 0
[16:39:15.330] <TB3> INFO: stop marker: 0
[16:39:15.330] <TB3> INFO: overflow: 0
[16:39:15.330] <TB3> INFO: invalid 5bit words: 0
[16:39:15.330] <TB3> INFO: invalid XOR eye diagram: 0
[16:39:15.330] <TB3> INFO: TBM errors: 0
[16:39:15.330] <TB3> INFO: flawed TBM headers: 0
[16:39:15.330] <TB3> INFO: flawed TBM trailers: 0
[16:39:15.330] <TB3> INFO: event ID mismatches: 0
[16:39:15.330] <TB3> INFO: ROC errors: 0
[16:39:15.330] <TB3> INFO: missing ROC header(s): 0
[16:39:15.330] <TB3> INFO: misplaced readback start: 0
[16:39:15.330] <TB3> INFO: Pixel decoding errors: 0
[16:39:15.330] <TB3> INFO: pixel data incomplete: 0
[16:39:15.330] <TB3> INFO: pixel address: 0
[16:39:15.330] <TB3> INFO: pulse height fill bit: 0
[16:39:15.330] <TB3> INFO: buffer corruption: 0
[16:39:15.502] <TB3> INFO: ######################################################################
[16:39:15.502] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[16:39:15.502] <TB3> INFO: ######################################################################
[16:39:15.513] <TB3> INFO: scanning low vcal = 10
[16:39:15.847] <TB3> INFO: Expecting 41600 events.
[16:39:19.626] <TB3> INFO: 41600 events read in total (3064ms).
[16:39:19.626] <TB3> INFO: Test took 4113ms.
[16:39:19.629] <TB3> INFO: scanning low vcal = 20
[16:39:20.041] <TB3> INFO: Expecting 41600 events.
[16:39:23.826] <TB3> INFO: 41600 events read in total (3069ms).
[16:39:23.826] <TB3> INFO: Test took 4197ms.
[16:39:23.829] <TB3> INFO: scanning low vcal = 30
[16:39:24.241] <TB3> INFO: Expecting 41600 events.
[16:39:28.053] <TB3> INFO: 41600 events read in total (3096ms).
[16:39:28.054] <TB3> INFO: Test took 4225ms.
[16:39:28.056] <TB3> INFO: scanning low vcal = 40
[16:39:28.461] <TB3> INFO: Expecting 41600 events.
[16:39:32.864] <TB3> INFO: 41600 events read in total (3687ms).
[16:39:32.864] <TB3> INFO: Test took 4808ms.
[16:39:32.867] <TB3> INFO: scanning low vcal = 50
[16:39:33.209] <TB3> INFO: Expecting 41600 events.
[16:39:37.586] <TB3> INFO: 41600 events read in total (3661ms).
[16:39:37.587] <TB3> INFO: Test took 4720ms.
[16:39:37.590] <TB3> INFO: scanning low vcal = 60
[16:39:37.936] <TB3> INFO: Expecting 41600 events.
[16:39:42.325] <TB3> INFO: 41600 events read in total (3673ms).
[16:39:42.325] <TB3> INFO: Test took 4735ms.
[16:39:42.328] <TB3> INFO: scanning low vcal = 70
[16:39:42.681] <TB3> INFO: Expecting 41600 events.
[16:39:47.044] <TB3> INFO: 41600 events read in total (3647ms).
[16:39:47.045] <TB3> INFO: Test took 4717ms.
[16:39:47.048] <TB3> INFO: scanning low vcal = 80
[16:39:47.394] <TB3> INFO: Expecting 41600 events.
[16:39:51.748] <TB3> INFO: 41600 events read in total (3639ms).
[16:39:51.749] <TB3> INFO: Test took 4701ms.
[16:39:51.752] <TB3> INFO: scanning low vcal = 90
[16:39:52.100] <TB3> INFO: Expecting 41600 events.
[16:39:56.438] <TB3> INFO: 41600 events read in total (3622ms).
[16:39:56.439] <TB3> INFO: Test took 4687ms.
[16:39:56.443] <TB3> INFO: scanning low vcal = 100
[16:39:56.783] <TB3> INFO: Expecting 41600 events.
[16:40:01.238] <TB3> INFO: 41600 events read in total (3738ms).
[16:40:01.239] <TB3> INFO: Test took 4796ms.
[16:40:01.242] <TB3> INFO: scanning low vcal = 110
[16:40:01.579] <TB3> INFO: Expecting 41600 events.
[16:40:05.954] <TB3> INFO: 41600 events read in total (3659ms).
[16:40:05.955] <TB3> INFO: Test took 4713ms.
[16:40:05.958] <TB3> INFO: scanning low vcal = 120
[16:40:06.302] <TB3> INFO: Expecting 41600 events.
[16:40:10.656] <TB3> INFO: 41600 events read in total (3638ms).
[16:40:10.657] <TB3> INFO: Test took 4699ms.
[16:40:10.660] <TB3> INFO: scanning low vcal = 130
[16:40:11.006] <TB3> INFO: Expecting 41600 events.
[16:40:15.424] <TB3> INFO: 41600 events read in total (3702ms).
[16:40:15.425] <TB3> INFO: Test took 4765ms.
[16:40:15.429] <TB3> INFO: scanning low vcal = 140
[16:40:15.780] <TB3> INFO: Expecting 41600 events.
[16:40:20.202] <TB3> INFO: 41600 events read in total (3706ms).
[16:40:20.203] <TB3> INFO: Test took 4774ms.
[16:40:20.207] <TB3> INFO: scanning low vcal = 150
[16:40:20.559] <TB3> INFO: Expecting 41600 events.
[16:40:24.912] <TB3> INFO: 41600 events read in total (3637ms).
[16:40:24.915] <TB3> INFO: Test took 4708ms.
[16:40:24.919] <TB3> INFO: scanning low vcal = 160
[16:40:25.257] <TB3> INFO: Expecting 41600 events.
[16:40:29.574] <TB3> INFO: 41600 events read in total (3601ms).
[16:40:29.576] <TB3> INFO: Test took 4657ms.
[16:40:29.579] <TB3> INFO: scanning low vcal = 170
[16:40:29.930] <TB3> INFO: Expecting 41600 events.
[16:40:34.255] <TB3> INFO: 41600 events read in total (3609ms).
[16:40:34.256] <TB3> INFO: Test took 4677ms.
[16:40:34.261] <TB3> INFO: scanning low vcal = 180
[16:40:34.603] <TB3> INFO: Expecting 41600 events.
[16:40:38.949] <TB3> INFO: 41600 events read in total (3630ms).
[16:40:38.950] <TB3> INFO: Test took 4688ms.
[16:40:38.953] <TB3> INFO: scanning low vcal = 190
[16:40:39.300] <TB3> INFO: Expecting 41600 events.
[16:40:43.625] <TB3> INFO: 41600 events read in total (3609ms).
[16:40:43.626] <TB3> INFO: Test took 4673ms.
[16:40:43.629] <TB3> INFO: scanning low vcal = 200
[16:40:43.974] <TB3> INFO: Expecting 41600 events.
[16:40:48.371] <TB3> INFO: 41600 events read in total (3681ms).
[16:40:48.372] <TB3> INFO: Test took 4743ms.
[16:40:48.374] <TB3> INFO: scanning low vcal = 210
[16:40:48.721] <TB3> INFO: Expecting 41600 events.
[16:40:53.155] <TB3> INFO: 41600 events read in total (3718ms).
[16:40:53.156] <TB3> INFO: Test took 4782ms.
[16:40:53.159] <TB3> INFO: scanning low vcal = 220
[16:40:53.508] <TB3> INFO: Expecting 41600 events.
[16:40:57.925] <TB3> INFO: 41600 events read in total (3701ms).
[16:40:57.926] <TB3> INFO: Test took 4767ms.
[16:40:57.929] <TB3> INFO: scanning low vcal = 230
[16:40:58.266] <TB3> INFO: Expecting 41600 events.
[16:41:02.629] <TB3> INFO: 41600 events read in total (3647ms).
[16:41:02.630] <TB3> INFO: Test took 4701ms.
[16:41:02.633] <TB3> INFO: scanning low vcal = 240
[16:41:02.977] <TB3> INFO: Expecting 41600 events.
[16:41:07.317] <TB3> INFO: 41600 events read in total (3624ms).
[16:41:07.318] <TB3> INFO: Test took 4685ms.
[16:41:07.321] <TB3> INFO: scanning low vcal = 250
[16:41:07.673] <TB3> INFO: Expecting 41600 events.
[16:41:12.063] <TB3> INFO: 41600 events read in total (3674ms).
[16:41:12.065] <TB3> INFO: Test took 4744ms.
[16:41:12.069] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[16:41:12.419] <TB3> INFO: Expecting 41600 events.
[16:41:16.751] <TB3> INFO: 41600 events read in total (3616ms).
[16:41:16.751] <TB3> INFO: Test took 4682ms.
[16:41:16.754] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[16:41:17.102] <TB3> INFO: Expecting 41600 events.
[16:41:21.440] <TB3> INFO: 41600 events read in total (3623ms).
[16:41:21.442] <TB3> INFO: Test took 4688ms.
[16:41:21.445] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[16:41:21.780] <TB3> INFO: Expecting 41600 events.
[16:41:26.128] <TB3> INFO: 41600 events read in total (3633ms).
[16:41:26.129] <TB3> INFO: Test took 4684ms.
[16:41:26.132] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[16:41:26.481] <TB3> INFO: Expecting 41600 events.
[16:41:30.879] <TB3> INFO: 41600 events read in total (3682ms).
[16:41:30.880] <TB3> INFO: Test took 4748ms.
[16:41:30.882] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:41:31.235] <TB3> INFO: Expecting 41600 events.
[16:41:35.658] <TB3> INFO: 41600 events read in total (3707ms).
[16:41:35.658] <TB3> INFO: Test took 4776ms.
[16:41:36.157] <TB3> INFO: PixTestGainPedestal::measure() done
[16:42:12.757] <TB3> INFO: PixTestGainPedestal::fit() done
[16:42:12.758] <TB3> INFO: non-linearity mean: 0.954 0.958 0.961 0.960 0.958 0.962 0.961 0.963 0.953 0.957 0.952 0.964 0.950 0.959 0.966 0.964
[16:42:12.758] <TB3> INFO: non-linearity RMS: 0.007 0.006 0.006 0.005 0.006 0.006 0.006 0.008 0.007 0.006 0.007 0.004 0.006 0.006 0.005 0.006
[16:42:12.758] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[16:42:12.776] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[16:42:12.795] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[16:42:12.817] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[16:42:12.842] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[16:42:12.869] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[16:42:12.891] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[16:42:12.910] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[16:42:12.929] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[16:42:12.949] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[16:42:12.968] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[16:42:12.986] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[16:42:13.004] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[16:42:13.030] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[16:42:13.057] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[16:42:13.079] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[16:42:13.097] <TB3> INFO: PixTestGainPedestal::doTest() done, duration: 177 seconds
[16:42:13.097] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:13.097] <TB3> INFO: Decoding statistics:
[16:42:13.097] <TB3> INFO: General information:
[16:42:13.097] <TB3> INFO: 16bit words read: 2662400
[16:42:13.097] <TB3> INFO: valid events total: 166400
[16:42:13.097] <TB3> INFO: empty events: 0
[16:42:13.097] <TB3> INFO: valid events with pixels: 166400
[16:42:13.097] <TB3> INFO: valid pixel hits: 665547
[16:42:13.097] <TB3> INFO: Event errors: 0
[16:42:13.097] <TB3> INFO: start marker: 0
[16:42:13.097] <TB3> INFO: stop marker: 0
[16:42:13.097] <TB3> INFO: overflow: 0
[16:42:13.097] <TB3> INFO: invalid 5bit words: 0
[16:42:13.097] <TB3> INFO: invalid XOR eye diagram: 0
[16:42:13.097] <TB3> INFO: TBM errors: 0
[16:42:13.097] <TB3> INFO: flawed TBM headers: 0
[16:42:13.097] <TB3> INFO: flawed TBM trailers: 0
[16:42:13.097] <TB3> INFO: event ID mismatches: 0
[16:42:13.097] <TB3> INFO: ROC errors: 0
[16:42:13.097] <TB3> INFO: missing ROC header(s): 0
[16:42:13.097] <TB3> INFO: misplaced readback start: 0
[16:42:13.097] <TB3> INFO: Pixel decoding errors: 0
[16:42:13.097] <TB3> INFO: pixel data incomplete: 0
[16:42:13.097] <TB3> INFO: pixel address: 0
[16:42:13.097] <TB3> INFO: pulse height fill bit: 0
[16:42:13.097] <TB3> INFO: buffer corruption: 0
[16:42:13.103] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C15.dat
[16:42:13.104] <TB3> INFO: ######################################################################
[16:42:13.104] <TB3> INFO: PixTestTrim::doTest()
[16:42:13.104] <TB3> INFO: ######################################################################
[16:42:13.105] <TB3> INFO: PixTestReadback::RES sent once


[16:42:24.349] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C0.dat
[16:42:24.349] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C1.dat
[16:42:24.349] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C2.dat
[16:42:24.349] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C3.dat
[16:42:24.349] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C4.dat
[16:42:24.349] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C5.dat
[16:42:24.349] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C6.dat
[16:42:24.349] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C7.dat
[16:42:24.350] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C8.dat
[16:42:24.350] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C9.dat
[16:42:24.350] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C10.dat
[16:42:24.350] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C11.dat
[16:42:24.350] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C12.dat
[16:42:24.350] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C13.dat
[16:42:24.350] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C14.dat
[16:42:24.350] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C15.dat
[16:42:24.381] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:42:24.381] <TB3> INFO: PixTestReadback::RES sent once
[16:42:35.552] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C0.dat
[16:42:35.552] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C1.dat
[16:42:35.552] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C2.dat
[16:42:35.552] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C3.dat
[16:42:35.552] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C4.dat
[16:42:35.552] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C5.dat
[16:42:35.552] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C6.dat
[16:42:35.552] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C7.dat
[16:42:35.552] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C8.dat
[16:42:35.552] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C9.dat
[16:42:35.552] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C10.dat
[16:42:35.553] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C11.dat
[16:42:35.553] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C12.dat
[16:42:35.553] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C13.dat
[16:42:35.553] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C14.dat
[16:42:35.553] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C15.dat
[16:42:35.585] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:42:35.585] <TB3> INFO: PixTestReadback::RES sent once
[16:42:44.190] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:42:44.190] <TB3> INFO: Vbg will be calibrated using Vd calibration
[16:42:44.190] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 157.4calibrated Vbg = 1.21507 :::*/*/*/*/
[16:42:44.190] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 146.9calibrated Vbg = 1.19402 :::*/*/*/*/
[16:42:44.190] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 159.8calibrated Vbg = 1.25328 :::*/*/*/*/
[16:42:44.190] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 170calibrated Vbg = 1.34633 :::*/*/*/*/
[16:42:44.190] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 160.1calibrated Vbg = 1.38511 :::*/*/*/*/
[16:42:44.190] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 159.5calibrated Vbg = 1.41871 :::*/*/*/*/
[16:42:44.190] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 155.5calibrated Vbg = 1.43867 :::*/*/*/*/
[16:42:44.190] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 157.6calibrated Vbg = 1.4317 :::*/*/*/*/
[16:42:44.190] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 155.6calibrated Vbg = 1.40552 :::*/*/*/*/
[16:42:44.190] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 152.4calibrated Vbg = 1.38709 :::*/*/*/*/
[16:42:44.190] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 151.6calibrated Vbg = 1.33404 :::*/*/*/*/
[16:42:44.190] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 139.1calibrated Vbg = 1.28724 :::*/*/*/*/
[16:42:44.190] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 155calibrated Vbg = 1.21098 :::*/*/*/*/
[16:42:44.190] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 155calibrated Vbg = 1.20453 :::*/*/*/*/
[16:42:44.190] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 160.3calibrated Vbg = 1.24291 :::*/*/*/*/
[16:42:44.190] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 159.5calibrated Vbg = 1.24175 :::*/*/*/*/
[16:42:44.193] <TB3> INFO: PixTestReadback::RES sent once
[16:45:38.733] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C0.dat
[16:45:38.733] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C1.dat
[16:45:38.734] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C2.dat
[16:45:38.734] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C3.dat
[16:45:38.734] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C4.dat
[16:45:38.734] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C5.dat
[16:45:38.734] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C6.dat
[16:45:38.734] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C7.dat
[16:45:38.734] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C8.dat
[16:45:38.734] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C9.dat
[16:45:38.734] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C10.dat
[16:45:38.734] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C11.dat
[16:45:38.734] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C12.dat
[16:45:38.734] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C13.dat
[16:45:38.734] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C14.dat
[16:45:38.735] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2109_FullQualification_2015-09-10_11h04m_1441875843//004_FulltestPxar_p17//readbackCal_C15.dat
[16:45:38.757] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:45:38.758] <TB3> INFO: PixTestReadback::doTest() done
[16:45:38.758] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:38.758] <TB3> INFO: Decoding statistics:
[16:45:38.758] <TB3> INFO: General information:
[16:45:38.758] <TB3> INFO: 16bit words read: 1024
[16:45:38.758] <TB3> INFO: valid events total: 128
[16:45:38.758] <TB3> INFO: empty events: 128
[16:45:38.758] <TB3> INFO: valid events with pixels: 0
[16:45:38.758] <TB3> INFO: valid pixel hits: 0
[16:45:38.758] <TB3> INFO: Event errors: 0
[16:45:38.758] <TB3> INFO: start marker: 0
[16:45:38.758] <TB3> INFO: stop marker: 0
[16:45:38.759] <TB3> INFO: overflow: 0
[16:45:38.759] <TB3> INFO: invalid 5bit words: 0
[16:45:38.759] <TB3> INFO: invalid XOR eye diagram: 0
[16:45:38.759] <TB3> INFO: TBM errors: 0
[16:45:38.759] <TB3> INFO: flawed TBM headers: 0
[16:45:38.759] <TB3> INFO: flawed TBM trailers: 0
[16:45:38.759] <TB3> INFO: event ID mismatches: 0
[16:45:38.759] <TB3> INFO: ROC errors: 0
[16:45:38.759] <TB3> INFO: missing ROC header(s): 0
[16:45:38.759] <TB3> INFO: misplaced readback start: 0
[16:45:38.759] <TB3> INFO: Pixel decoding errors: 0
[16:45:38.759] <TB3> INFO: pixel data incomplete: 0
[16:45:38.759] <TB3> INFO: pixel address: 0
[16:45:38.759] <TB3> INFO: pulse height fill bit: 0
[16:45:38.759] <TB3> INFO: buffer corruption: 0
[16:45:38.775] <TB3> INFO: Decoding statistics:
[16:45:38.775] <TB3> INFO: General information:
[16:45:38.775] <TB3> INFO: 16bit words read: 2755352
[16:45:38.775] <TB3> INFO: valid events total: 176768
[16:45:38.775] <TB3> INFO: empty events: 7866
[16:45:38.775] <TB3> INFO: valid events with pixels: 168902
[16:45:38.775] <TB3> INFO: valid pixel hits: 668049
[16:45:38.775] <TB3> INFO: Event errors: 0
[16:45:38.775] <TB3> INFO: start marker: 0
[16:45:38.775] <TB3> INFO: stop marker: 0
[16:45:38.775] <TB3> INFO: overflow: 0
[16:45:38.775] <TB3> INFO: invalid 5bit words: 0
[16:45:38.775] <TB3> INFO: invalid XOR eye diagram: 0
[16:45:38.775] <TB3> INFO: TBM errors: 0
[16:45:38.775] <TB3> INFO: flawed TBM headers: 0
[16:45:38.775] <TB3> INFO: flawed TBM trailers: 0
[16:45:38.775] <TB3> INFO: event ID mismatches: 0
[16:45:38.775] <TB3> INFO: ROC errors: 0
[16:45:38.775] <TB3> INFO: missing ROC header(s): 0
[16:45:38.775] <TB3> INFO: misplaced readback start: 0
[16:45:38.775] <TB3> INFO: Pixel decoding errors: 0
[16:45:38.775] <TB3> INFO: pixel data incomplete: 0
[16:45:38.775] <TB3> INFO: pixel address: 0
[16:45:38.775] <TB3> INFO: pulse height fill bit: 0
[16:45:38.775] <TB3> INFO: buffer corruption: 0
[16:45:38.775] <TB3> INFO: enter test to run
[16:45:38.775] <TB3> INFO: test: exit no parameter change
[16:45:39.379] <TB3> QUIET: Connection to board 170 closed.
[16:45:39.459] <TB3> INFO: pXar: this is the end, my friend