[09:35:25.546] <TB2> INFO: *** Welcome to pxar ***
[09:35:25.546] <TB2> INFO: *** Today: 2015/12/07
[09:35:25.718] <TB2> INFO: *** Version: 3197
[09:35:25.718] <TB2> INFO: readRocDacs: /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters_C15.dat
[09:35:25.719] <TB2> INFO: readTbmDacs: /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//tbmParameters_C0b.dat
[09:35:25.719] <TB2> INFO: readMaskFile: /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//defaultMaskFile.dat
[09:35:25.719] <TB2> INFO: readTrimFile: /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//trimParameters_C15.dat
[09:35:25.797] <TB2> INFO: clk: 4
[09:35:25.797] <TB2> INFO: ctr: 4
[09:35:25.797] <TB2> INFO: sda: 19
[09:35:25.797] <TB2> INFO: tin: 9
[09:35:25.797] <TB2> INFO: level: 15
[09:35:25.797] <TB2> INFO: triggerdelay: 0
[09:35:25.797] <TB2> QUIET: Instanciating API for pxar prod-12
[09:35:25.797] <TB2> INFO: Log level: INFO
[09:35:25.805] <TB2> INFO: Found DTB DTB_WXC55Z
[09:35:25.812] <TB2> QUIET: Connection to board DTB_WXC55Z opened.
[09:35:25.815] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 156
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WXC55Z
MAC address: 40D85511809C
Hostname: pixelDTB156
Comment:
------------------------------------------------------
[09:35:25.818] <TB2> INFO: RPC call hashes of host and DTB match: 398089610
[09:35:27.340] <TB2> INFO: DUT info:
[09:35:27.340] <TB2> INFO: The DUT currently contains the following objects:
[09:35:27.340] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[09:35:27.340] <TB2> INFO: TBM Core alpha (0): 7 registers set
[09:35:27.340] <TB2> INFO: TBM Core beta (1): 7 registers set
[09:35:27.340] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:35:27.340] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:35:27.340] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:35:27.340] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:35:27.340] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:35:27.340] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:35:27.340] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:35:27.340] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:35:27.340] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:35:27.340] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:35:27.340] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:35:27.340] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:35:27.340] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:35:27.340] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:35:27.340] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:35:27.341] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:35:27.341] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:35:27.742] <TB2> INFO: enter 'restricted' command line mode
[09:35:27.742] <TB2> INFO: enter test to run
[09:35:27.742] <TB2> INFO: test: pretest no parameter change
[09:35:27.742] <TB2> INFO: running: pretest
[09:35:27.748] <TB2> INFO: ######################################################################
[09:35:27.748] <TB2> INFO: PixTestPretest::doTest()
[09:35:27.748] <TB2> INFO: ######################################################################
[09:35:27.750] <TB2> INFO: ----------------------------------------------------------------------
[09:35:27.750] <TB2> INFO: PixTestPretest::programROC()
[09:35:27.750] <TB2> INFO: ----------------------------------------------------------------------
[09:35:45.767] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[09:35:45.767] <TB2> INFO: IA differences per ROC: 15.3 20.1 18.5 19.3 17.7 17.7 20.9 19.3 20.9 20.9 19.3 18.5 18.5 20.1 17.7 16.9
[09:35:45.858] <TB2> INFO: ----------------------------------------------------------------------
[09:35:45.858] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[09:35:45.858] <TB2> INFO: ----------------------------------------------------------------------
[09:36:07.149] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 381 mA = 23.8125 mA/ROC
[09:36:07.149] <TB2> INFO: i(loss) [mA/ROC]: 19.2 18.4 18.4 19.2 19.2 19.2 18.4 18.4 18.4 19.2 18.4 19.2 19.2 18.4 18.4 17.6
[09:36:07.185] <TB2> INFO: ----------------------------------------------------------------------
[09:36:07.185] <TB2> INFO: PixTestPretest::findTiming()
[09:36:07.185] <TB2> INFO: ----------------------------------------------------------------------
[09:36:07.185] <TB2> INFO: PixTestCmd::init()

[09:37:47.621] <TB2> INFO: TBM phases: 160MHz: 0, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[09:37:47.621] <TB2> INFO: (success/tries = 100/100), width = 4
[09:37:47.624] <TB2> INFO: ----------------------------------------------------------------------
[09:37:47.624] <TB2> INFO: PixTestPretest::findWorkingPixel()
[09:37:47.624] <TB2> INFO: ----------------------------------------------------------------------
[09:37:47.762] <TB2> INFO: Expecting 231680 events.
[09:37:56.370] <TB2> INFO: 231680 events read in total (7892ms).
[09:37:56.375] <TB2> INFO: Test took 8749ms.
[09:37:56.693] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[09:37:56.734] <TB2> INFO: ----------------------------------------------------------------------
[09:37:56.734] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[09:37:56.734] <TB2> INFO: ----------------------------------------------------------------------
[09:37:56.872] <TB2> INFO: Expecting 231680 events.
[09:38:05.653] <TB2> INFO: 231680 events read in total (8065ms).
[09:38:05.657] <TB2> INFO: Test took 8917ms.
[09:38:05.977] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[09:38:05.977] <TB2> INFO: CalDel: 140 162 144 146 156 144 143 151 146 143 162 143 138 143 131 123
[09:38:05.977] <TB2> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[09:38:05.980] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters_C0.dat
[09:38:05.981] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters_C1.dat
[09:38:05.981] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters_C2.dat
[09:38:05.981] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters_C3.dat
[09:38:05.981] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters_C4.dat
[09:38:05.982] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters_C5.dat
[09:38:05.982] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters_C6.dat
[09:38:05.982] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters_C7.dat
[09:38:05.982] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters_C8.dat
[09:38:05.983] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters_C9.dat
[09:38:05.983] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters_C10.dat
[09:38:05.983] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters_C11.dat
[09:38:05.983] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters_C12.dat
[09:38:05.984] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters_C13.dat
[09:38:05.984] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters_C14.dat
[09:38:05.984] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters_C15.dat
[09:38:05.984] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//tbmParameters_C0a.dat
[09:38:05.984] <TB2> INFO: write tbm parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//tbmParameters_C0b.dat
[09:38:05.984] <TB2> INFO: PixTestPretest::doTest() done, duration: 158 seconds
[09:38:06.065] <TB2> INFO: enter test to run
[09:38:06.065] <TB2> INFO: test: fulltest no parameter change
[09:38:06.065] <TB2> INFO: running: fulltest
[09:38:06.065] <TB2> INFO: ######################################################################
[09:38:06.065] <TB2> INFO: PixTestFullTest::doTest()
[09:38:06.065] <TB2> INFO: ######################################################################
[09:38:06.067] <TB2> INFO: ######################################################################
[09:38:06.067] <TB2> INFO: PixTestAlive::doTest()
[09:38:06.067] <TB2> INFO: ######################################################################
[09:38:06.068] <TB2> INFO: ----------------------------------------------------------------------
[09:38:06.068] <TB2> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:38:06.068] <TB2> INFO: ----------------------------------------------------------------------
[09:38:06.379] <TB2> INFO: Expecting 41600 events.
[09:38:11.226] <TB2> INFO: 41600 events read in total (4131ms).
[09:38:11.227] <TB2> INFO: Test took 5157ms.
[09:38:11.234] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:11.520] <TB2> INFO: PixTestAlive::aliveTest() done
[09:38:11.520] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:38:11.522] <TB2> INFO: ----------------------------------------------------------------------
[09:38:11.522] <TB2> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:38:11.522] <TB2> INFO: ----------------------------------------------------------------------
[09:38:11.831] <TB2> INFO: Expecting 41600 events.
[09:38:15.160] <TB2> INFO: 41600 events read in total (2613ms).
[09:38:15.160] <TB2> INFO: Test took 3636ms.
[09:38:15.160] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:15.161] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:38:15.476] <TB2> INFO: PixTestAlive::maskTest() done
[09:38:15.476] <TB2> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:38:15.478] <TB2> INFO: ----------------------------------------------------------------------
[09:38:15.478] <TB2> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:38:15.478] <TB2> INFO: ----------------------------------------------------------------------
[09:38:15.786] <TB2> INFO: Expecting 41600 events.
[09:38:20.479] <TB2> INFO: 41600 events read in total (3977ms).
[09:38:20.479] <TB2> INFO: Test took 4999ms.
[09:38:20.486] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:20.768] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[09:38:20.768] <TB2> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:38:20.768] <TB2> INFO: PixTestAlive::doTest() done, duration: 14 seconds
[09:38:20.769] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:20.769] <TB2> INFO: Decoding statistics:
[09:38:20.769] <TB2> INFO: General information:
[09:38:20.769] <TB2> INFO: 16bit words read: 0
[09:38:20.769] <TB2> INFO: valid events total: 0
[09:38:20.769] <TB2> INFO: empty events: 0
[09:38:20.769] <TB2> INFO: valid events with pixels: 0
[09:38:20.769] <TB2> INFO: valid pixel hits: 0
[09:38:20.769] <TB2> INFO: Event errors: 0
[09:38:20.769] <TB2> INFO: start marker: 0
[09:38:20.769] <TB2> INFO: stop marker: 0
[09:38:20.769] <TB2> INFO: overflow: 0
[09:38:20.769] <TB2> INFO: invalid 5bit words: 0
[09:38:20.769] <TB2> INFO: invalid XOR eye diagram: 0
[09:38:20.769] <TB2> INFO: TBM errors: 0
[09:38:20.769] <TB2> INFO: flawed TBM headers: 0
[09:38:20.769] <TB2> INFO: flawed TBM trailers: 0
[09:38:20.769] <TB2> INFO: event ID mismatches: 0
[09:38:20.769] <TB2> INFO: ROC errors: 0
[09:38:20.769] <TB2> INFO: missing ROC header(s): 0
[09:38:20.769] <TB2> INFO: misplaced readback start: 0
[09:38:20.769] <TB2> INFO: Pixel decoding errors: 0
[09:38:20.769] <TB2> INFO: pixel data incomplete: 0
[09:38:20.769] <TB2> INFO: pixel address: 0
[09:38:20.769] <TB2> INFO: pulse height fill bit: 0
[09:38:20.769] <TB2> INFO: buffer corruption: 0
[09:38:20.783] <TB2> INFO: ######################################################################
[09:38:20.783] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[09:38:20.783] <TB2> INFO: ######################################################################
[09:38:20.786] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[09:38:20.798] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[09:38:20.799] <TB2> INFO: run 1 of 1
[09:38:21.106] <TB2> INFO: Expecting 3120000 events.
[09:39:04.510] <TB2> INFO: 828940 events read in total (42688ms).
[09:39:47.156] <TB2> INFO: 1647325 events read in total (85334ms).
[09:40:29.336] <TB2> INFO: 2475405 events read in total (127514ms).
[09:41:00.160] <TB2> INFO: 3120000 events read in total (158338ms).
[09:41:00.226] <TB2> INFO: Test took 159427ms.
[09:41:00.340] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:41:24.406] <TB2> INFO: PixTestBBMap::doTest() done, duration: 183 seconds
[09:41:24.406] <TB2> INFO: number of dead bumps (per ROC): 0 21 0 0 0 0 0 0 0 0 1 20 0 108 2 6
[09:41:24.406] <TB2> INFO: separation cut (per ROC): 75 75 72 77 77 97 90 81 75 69 75 69 73 90 72 79
[09:41:24.406] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:41:24.406] <TB2> INFO: Decoding statistics:
[09:41:24.406] <TB2> INFO: General information:
[09:41:24.406] <TB2> INFO: 16bit words read: 0
[09:41:24.406] <TB2> INFO: valid events total: 0
[09:41:24.406] <TB2> INFO: empty events: 0
[09:41:24.406] <TB2> INFO: valid events with pixels: 0
[09:41:24.406] <TB2> INFO: valid pixel hits: 0
[09:41:24.406] <TB2> INFO: Event errors: 0
[09:41:24.406] <TB2> INFO: start marker: 0
[09:41:24.406] <TB2> INFO: stop marker: 0
[09:41:24.406] <TB2> INFO: overflow: 0
[09:41:24.406] <TB2> INFO: invalid 5bit words: 0
[09:41:24.406] <TB2> INFO: invalid XOR eye diagram: 0
[09:41:24.406] <TB2> INFO: TBM errors: 0
[09:41:24.406] <TB2> INFO: flawed TBM headers: 0
[09:41:24.406] <TB2> INFO: flawed TBM trailers: 0
[09:41:24.406] <TB2> INFO: event ID mismatches: 0
[09:41:24.406] <TB2> INFO: ROC errors: 0
[09:41:24.406] <TB2> INFO: missing ROC header(s): 0
[09:41:24.406] <TB2> INFO: misplaced readback start: 0
[09:41:24.406] <TB2> INFO: Pixel decoding errors: 0
[09:41:24.406] <TB2> INFO: pixel data incomplete: 0
[09:41:24.406] <TB2> INFO: pixel address: 0
[09:41:24.406] <TB2> INFO: pulse height fill bit: 0
[09:41:24.406] <TB2> INFO: buffer corruption: 0
[09:41:24.480] <TB2> INFO: ######################################################################
[09:41:24.480] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:41:24.480] <TB2> INFO: ######################################################################
[09:41:24.480] <TB2> INFO: ----------------------------------------------------------------------
[09:41:24.480] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:41:24.480] <TB2> INFO: ----------------------------------------------------------------------
[09:41:24.481] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:41:24.489] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[09:41:24.489] <TB2> INFO: run 1 of 1
[09:41:24.805] <TB2> INFO: Expecting 26208000 events.
[09:41:57.079] <TB2> INFO: 872450 events read in total (31558ms).
[09:42:30.030] <TB2> INFO: 1731300 events read in total (64509ms).
[09:43:02.645] <TB2> INFO: 2587850 events read in total (97124ms).
[09:43:34.992] <TB2> INFO: 3442850 events read in total (129471ms).
[09:44:07.203] <TB2> INFO: 4300000 events read in total (161682ms).
[09:44:38.894] <TB2> INFO: 5155000 events read in total (193373ms).
[09:45:12.004] <TB2> INFO: 6009850 events read in total (226483ms).
[09:45:44.502] <TB2> INFO: 6864100 events read in total (258981ms).
[09:46:17.727] <TB2> INFO: 7719150 events read in total (292206ms).
[09:46:50.813] <TB2> INFO: 8572550 events read in total (325292ms).
[09:47:24.862] <TB2> INFO: 9425500 events read in total (359341ms).
[09:47:57.593] <TB2> INFO: 10279150 events read in total (392072ms).
[09:48:32.113] <TB2> INFO: 11130450 events read in total (426592ms).
[09:49:04.891] <TB2> INFO: 11980800 events read in total (459370ms).
[09:49:37.553] <TB2> INFO: 12831450 events read in total (492032ms).
[09:50:09.917] <TB2> INFO: 13676650 events read in total (524396ms).
[09:50:42.790] <TB2> INFO: 14516200 events read in total (557269ms).
[09:51:15.264] <TB2> INFO: 15358450 events read in total (589743ms).
[09:51:48.777] <TB2> INFO: 16200050 events read in total (623256ms).
[09:52:21.456] <TB2> INFO: 17036600 events read in total (655935ms).
[09:52:54.036] <TB2> INFO: 17876500 events read in total (688515ms).
[09:53:27.017] <TB2> INFO: 18716300 events read in total (721496ms).
[09:53:59.972] <TB2> INFO: 19551600 events read in total (754451ms).
[09:54:32.207] <TB2> INFO: 20390100 events read in total (786686ms).
[09:55:04.658] <TB2> INFO: 21227400 events read in total (819137ms).
[09:55:37.877] <TB2> INFO: 22063700 events read in total (852356ms).
[09:56:09.022] <TB2> INFO: 22900850 events read in total (883501ms).
[09:56:42.328] <TB2> INFO: 23741400 events read in total (916807ms).
[09:57:12.995] <TB2> INFO: 24579350 events read in total (947474ms).
[09:57:45.361] <TB2> INFO: 25420450 events read in total (979840ms).
[09:58:11.008] <TB2> INFO: 26208000 events read in total (1005487ms).
[09:58:11.042] <TB2> INFO: Test took 1006553ms.
[09:58:11.138] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:11.325] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:58:12.762] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:58:14.246] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:58:15.778] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:58:17.404] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:58:18.910] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:58:20.287] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:58:21.686] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:58:23.121] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:58:24.533] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:58:25.953] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:58:27.381] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:58:28.782] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:58:30.287] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:58:31.685] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:58:33.103] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[09:58:34.523] <TB2> INFO: PixTestScurves::scurves() done
[09:58:34.523] <TB2> INFO: Vcal mean: 84.76 83.65 73.89 73.90 82.29 96.16 79.84 80.18 80.30 77.70 78.31 81.59 79.93 90.15 81.77 91.78
[09:58:34.523] <TB2> INFO: Vcal RMS: 5.02 5.13 4.62 4.66 5.54 6.41 4.95 4.79 5.32 3.86 4.71 4.60 4.68 6.14 4.75 5.56
[09:58:34.523] <TB2> INFO: PixTestScurves::fullTest() done, duration: 1030 seconds
[09:58:34.523] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:58:34.523] <TB2> INFO: Decoding statistics:
[09:58:34.523] <TB2> INFO: General information:
[09:58:34.523] <TB2> INFO: 16bit words read: 0
[09:58:34.523] <TB2> INFO: valid events total: 0
[09:58:34.523] <TB2> INFO: empty events: 0
[09:58:34.523] <TB2> INFO: valid events with pixels: 0
[09:58:34.523] <TB2> INFO: valid pixel hits: 0
[09:58:34.523] <TB2> INFO: Event errors: 0
[09:58:34.523] <TB2> INFO: start marker: 0
[09:58:34.523] <TB2> INFO: stop marker: 0
[09:58:34.523] <TB2> INFO: overflow: 0
[09:58:34.523] <TB2> INFO: invalid 5bit words: 0
[09:58:34.523] <TB2> INFO: invalid XOR eye diagram: 0
[09:58:34.523] <TB2> INFO: TBM errors: 0
[09:58:34.523] <TB2> INFO: flawed TBM headers: 0
[09:58:34.523] <TB2> INFO: flawed TBM trailers: 0
[09:58:34.523] <TB2> INFO: event ID mismatches: 0
[09:58:34.524] <TB2> INFO: ROC errors: 0
[09:58:34.524] <TB2> INFO: missing ROC header(s): 0
[09:58:34.524] <TB2> INFO: misplaced readback start: 0
[09:58:34.524] <TB2> INFO: Pixel decoding errors: 0
[09:58:34.524] <TB2> INFO: pixel data incomplete: 0
[09:58:34.524] <TB2> INFO: pixel address: 0
[09:58:34.524] <TB2> INFO: pulse height fill bit: 0
[09:58:34.524] <TB2> INFO: buffer corruption: 0
[09:58:34.594] <TB2> INFO: ######################################################################
[09:58:34.594] <TB2> INFO: PixTestTrim::doTest()
[09:58:34.594] <TB2> INFO: ######################################################################
[09:58:34.596] <TB2> INFO: ----------------------------------------------------------------------
[09:58:34.596] <TB2> INFO: PixTestTrim::trimTest() ntrig = 8, vcal = 35
[09:58:34.596] <TB2> INFO: ----------------------------------------------------------------------
[09:58:34.679] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[09:58:34.679] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:58:34.687] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[09:58:34.687] <TB2> INFO: run 1 of 1
[09:58:35.023] <TB2> INFO: Expecting 5025280 events.
[09:59:16.324] <TB2> INFO: 1042088 events read in total (40586ms).
[09:59:58.410] <TB2> INFO: 2082248 events read in total (82672ms).
[10:00:37.539] <TB2> INFO: 3125952 events read in total (121801ms).
[10:01:19.373] <TB2> INFO: 4179296 events read in total (163635ms).
[10:01:50.954] <TB2> INFO: 5025280 events read in total (195216ms).
[10:01:50.986] <TB2> INFO: Test took 196299ms.
[10:01:51.040] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:10.961] <TB2> INFO: ROC 0 VthrComp = 89
[10:02:10.962] <TB2> INFO: ROC 1 VthrComp = 89
[10:02:10.962] <TB2> INFO: ROC 2 VthrComp = 76
[10:02:10.962] <TB2> INFO: ROC 3 VthrComp = 81
[10:02:10.962] <TB2> INFO: ROC 4 VthrComp = 90
[10:02:10.962] <TB2> INFO: ROC 5 VthrComp = 99
[10:02:10.962] <TB2> INFO: ROC 6 VthrComp = 87
[10:02:10.962] <TB2> INFO: ROC 7 VthrComp = 86
[10:02:10.962] <TB2> INFO: ROC 8 VthrComp = 84
[10:02:10.962] <TB2> INFO: ROC 9 VthrComp = 86
[10:02:10.962] <TB2> INFO: ROC 10 VthrComp = 87
[10:02:10.963] <TB2> INFO: ROC 11 VthrComp = 88
[10:02:10.963] <TB2> INFO: ROC 12 VthrComp = 85
[10:02:10.963] <TB2> INFO: ROC 13 VthrComp = 97
[10:02:10.963] <TB2> INFO: ROC 14 VthrComp = 85
[10:02:10.963] <TB2> INFO: ROC 15 VthrComp = 97
[10:02:10.963] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:02:10.963] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 8 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:02:10.974] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[10:02:10.974] <TB2> INFO: run 1 of 1
[10:02:11.311] <TB2> INFO: Expecting 5025280 events.
[10:02:49.627] <TB2> INFO: 756896 events read in total (37601ms).
[10:03:27.732] <TB2> INFO: 1511056 events read in total (75706ms).
[10:04:04.180] <TB2> INFO: 2265616 events read in total (112154ms).
[10:04:41.846] <TB2> INFO: 3015840 events read in total (149820ms).
[10:05:17.292] <TB2> INFO: 3760544 events read in total (185266ms).
[10:05:56.434] <TB2> INFO: 4503752 events read in total (224408ms).
[10:06:19.168] <TB2> INFO: 5025280 events read in total (247142ms).
[10:06:19.221] <TB2> INFO: Test took 248247ms.
[10:06:19.360] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:06:44.445] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 58.9943 for pixel 23/13 mean/min/max = 46.271/33.3532/59.1887
[10:06:44.445] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 58.7936 for pixel 4/79 mean/min/max = 45.4999/32.0945/58.9054
[10:06:44.445] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 60.1688 for pixel 24/79 mean/min/max = 47.7829/35.3258/60.24
[10:06:44.445] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 57.4172 for pixel 14/77 mean/min/max = 44.975/32.5148/57.4351
[10:06:44.446] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 60.735 for pixel 5/6 mean/min/max = 45.9224/31.1084/60.7364
[10:06:44.446] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 61.2164 for pixel 13/79 mean/min/max = 46.5628/31.7997/61.3258
[10:06:44.446] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 59.0876 for pixel 1/78 mean/min/max = 45.5344/31.9786/59.0901
[10:06:44.446] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 58.6407 for pixel 51/12 mean/min/max = 45.4656/32.165/58.7661
[10:06:44.447] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 60.7499 for pixel 0/75 mean/min/max = 46.0247/31.0929/60.9565
[10:06:44.447] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 55.3231 for pixel 45/11 mean/min/max = 44.0688/32.5525/55.5852
[10:06:44.447] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 58.2268 for pixel 22/10 mean/min/max = 44.778/31.3147/58.2413
[10:06:44.447] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 58.6553 for pixel 7/3 mean/min/max = 45.7898/32.9176/58.6621
[10:06:44.448] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 57.6159 for pixel 0/39 mean/min/max = 44.7908/31.9342/57.6474
[10:06:44.448] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 60.9633 for pixel 18/6 mean/min/max = 45.8428/30.6404/61.0452
[10:06:44.448] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 58.3141 for pixel 15/7 mean/min/max = 45.2838/32.2514/58.3162
[10:06:44.448] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 59.4179 for pixel 4/73 mean/min/max = 45.3169/31.0397/59.5941
[10:06:44.449] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:06:44.580] <TB2> INFO: Expecting 411648 events.
[10:06:54.742] <TB2> INFO: 411648 events read in total (9446ms).
[10:06:54.748] <TB2> INFO: Expecting 411648 events.
[10:07:04.833] <TB2> INFO: 411648 events read in total (9509ms).
[10:07:04.840] <TB2> INFO: Expecting 411648 events.
[10:07:14.660] <TB2> INFO: 411648 events read in total (9234ms).
[10:07:14.670] <TB2> INFO: Expecting 411648 events.
[10:07:24.824] <TB2> INFO: 411648 events read in total (9580ms).
[10:07:24.837] <TB2> INFO: Expecting 411648 events.
[10:07:34.878] <TB2> INFO: 411648 events read in total (9482ms).
[10:07:34.894] <TB2> INFO: Expecting 411648 events.
[10:07:44.799] <TB2> INFO: 411648 events read in total (9338ms).
[10:07:44.813] <TB2> INFO: Expecting 411648 events.
[10:07:55.120] <TB2> INFO: 411648 events read in total (9730ms).
[10:07:55.138] <TB2> INFO: Expecting 411648 events.
[10:08:05.139] <TB2> INFO: 411648 events read in total (9434ms).
[10:08:05.158] <TB2> INFO: Expecting 411648 events.
[10:08:15.624] <TB2> INFO: 411648 events read in total (9898ms).
[10:08:15.645] <TB2> INFO: Expecting 411648 events.
[10:08:25.881] <TB2> INFO: 411648 events read in total (9669ms).
[10:08:25.908] <TB2> INFO: Expecting 411648 events.
[10:08:36.316] <TB2> INFO: 411648 events read in total (9858ms).
[10:08:36.341] <TB2> INFO: Expecting 411648 events.
[10:08:47.125] <TB2> INFO: 411648 events read in total (10233ms).
[10:08:47.154] <TB2> INFO: Expecting 411648 events.
[10:08:57.832] <TB2> INFO: 411648 events read in total (10123ms).
[10:08:57.861] <TB2> INFO: Expecting 411648 events.
[10:09:07.929] <TB2> INFO: 411648 events read in total (9507ms).
[10:09:07.961] <TB2> INFO: Expecting 411648 events.
[10:09:17.229] <TB2> INFO: 411648 events read in total (8717ms).
[10:09:17.269] <TB2> INFO: Expecting 411648 events.
[10:09:27.679] <TB2> INFO: 411648 events read in total (9868ms).
[10:09:27.722] <TB2> INFO: Test took 163273ms.
[10:09:28.879] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 8 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:09:28.888] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[10:09:28.888] <TB2> INFO: run 1 of 1
[10:09:29.225] <TB2> INFO: Expecting 5025280 events.
[10:10:07.836] <TB2> INFO: 741688 events read in total (37895ms).
[10:10:41.982] <TB2> INFO: 1481464 events read in total (72041ms).
[10:11:19.271] <TB2> INFO: 2221136 events read in total (109330ms).
[10:11:55.201] <TB2> INFO: 2956656 events read in total (145260ms).
[10:12:32.872] <TB2> INFO: 3686512 events read in total (182931ms).
[10:13:11.629] <TB2> INFO: 4413792 events read in total (221688ms).
[10:13:38.473] <TB2> INFO: 5025280 events read in total (248532ms).
[10:13:38.524] <TB2> INFO: Test took 249636ms.
[10:13:38.672] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:14:04.122] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 0.250096 .. 255.000000
[10:14:04.197] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 8 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[10:14:04.206] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[10:14:04.206] <TB2> INFO: run 1 of 1
[10:14:04.519] <TB2> INFO: Expecting 8519680 events.
[10:14:40.887] <TB2> INFO: 712400 events read in total (35652ms).
[10:15:17.568] <TB2> INFO: 1425032 events read in total (72333ms).
[10:15:53.804] <TB2> INFO: 2137816 events read in total (108569ms).
[10:16:30.499] <TB2> INFO: 2850920 events read in total (145264ms).
[10:17:07.717] <TB2> INFO: 3563872 events read in total (182482ms).
[10:17:42.596] <TB2> INFO: 4276992 events read in total (217361ms).
[10:18:21.515] <TB2> INFO: 4989168 events read in total (256280ms).
[10:18:59.919] <TB2> INFO: 5700328 events read in total (294684ms).
[10:19:35.226] <TB2> INFO: 6411280 events read in total (329991ms).
[10:20:12.559] <TB2> INFO: 7121440 events read in total (367324ms).
[10:20:49.980] <TB2> INFO: 7831432 events read in total (404745ms).
[10:21:20.854] <TB2> INFO: 8519680 events read in total (435619ms).
[10:21:20.934] <TB2> INFO: Test took 436727ms.
[10:21:21.224] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:21:52.816] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 18.104046 .. 47.122460
[10:21:52.900] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 8 dacrange: 8 .. 57 (-1/-1) hits flags = 528 (plus default)
[10:21:52.908] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[10:21:52.908] <TB2> INFO: run 1 of 1
[10:21:53.214] <TB2> INFO: Expecting 1664000 events.
[10:22:33.797] <TB2> INFO: 907544 events read in total (39867ms).
[10:23:03.955] <TB2> INFO: 1664000 events read in total (70025ms).
[10:23:03.968] <TB2> INFO: Test took 71059ms.
[10:23:04.000] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:19.335] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 22.774166 .. 43.744915
[10:23:19.415] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 8 dacrange: 12 .. 53 (-1/-1) hits flags = 528 (plus default)
[10:23:19.424] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[10:23:19.424] <TB2> INFO: run 1 of 1
[10:23:19.748] <TB2> INFO: Expecting 1397760 events.
[10:24:00.148] <TB2> INFO: 915056 events read in total (39684ms).
[10:24:21.619] <TB2> INFO: 1397760 events read in total (61155ms).
[10:24:21.631] <TB2> INFO: Test took 62207ms.
[10:24:21.660] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:24:36.885] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 4.500000 .. 42.824996
[10:24:36.960] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 8 dacrange: 4 .. 52 (-1/-1) hits flags = 528 (plus default)
[10:24:36.968] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[10:24:36.968] <TB2> INFO: run 1 of 1
[10:24:37.270] <TB2> INFO: Expecting 1630720 events.
[10:25:18.045] <TB2> INFO: 972416 events read in total (40059ms).
[10:25:45.100] <TB2> INFO: 1630720 events read in total (67115ms).
[10:25:45.117] <TB2> INFO: Test took 68149ms.
[10:25:45.147] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:26:00.217] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[10:26:00.218] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 8 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[10:26:00.227] <TB2> INFO: dacScan split into 1 runs with ntrig = 8
[10:26:00.227] <TB2> INFO: run 1 of 1
[10:26:00.551] <TB2> INFO: Expecting 1364480 events.
[10:26:38.986] <TB2> INFO: 880272 events read in total (37719ms).
[10:27:01.973] <TB2> INFO: 1364480 events read in total (60707ms).
[10:27:01.990] <TB2> INFO: Test took 61764ms.
[10:27:02.024] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:17.610] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:27:17.610] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:27:17.610] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:27:17.610] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:27:17.610] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:27:17.610] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:27:17.611] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:27:17.611] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:27:17.611] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:27:17.611] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:27:17.611] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:27:17.611] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:27:17.611] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:27:17.611] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:27:17.611] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:27:17.612] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:27:17.612] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//trimParameters35_C0.dat
[10:27:17.621] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//trimParameters35_C1.dat
[10:27:17.627] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//trimParameters35_C2.dat
[10:27:17.633] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//trimParameters35_C3.dat
[10:27:17.639] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//trimParameters35_C4.dat
[10:27:17.645] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//trimParameters35_C5.dat
[10:27:17.652] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//trimParameters35_C6.dat
[10:27:17.659] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//trimParameters35_C7.dat
[10:27:17.665] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//trimParameters35_C8.dat
[10:27:17.672] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//trimParameters35_C9.dat
[10:27:17.678] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//trimParameters35_C10.dat
[10:27:17.684] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//trimParameters35_C11.dat
[10:27:17.690] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//trimParameters35_C12.dat
[10:27:17.696] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//trimParameters35_C13.dat
[10:27:17.703] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//trimParameters35_C14.dat
[10:27:17.709] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//trimParameters35_C15.dat
[10:27:17.715] <TB2> INFO: PixTestTrim::trimTest() done
[10:27:17.715] <TB2> INFO: vtrim: 102 87 100 116 115 111 110 81 112 103 116 108 91 113 90 99
[10:27:17.715] <TB2> INFO: vthrcomp: 89 89 76 81 90 99 87 86 84 86 87 88 85 97 85 97
[10:27:17.715] <TB2> INFO: vcal mean: 34.98 34.98 34.99 34.99 35.01 34.97 34.95 34.98 34.94 34.99 34.96 35.00 34.97 34.91 34.96 34.97
[10:27:17.715] <TB2> INFO: vcal RMS: 0.90 0.84 0.86 0.90 0.91 0.94 0.88 0.84 0.91 0.87 0.88 0.85 0.86 0.92 0.89 0.95
[10:27:17.715] <TB2> INFO: bits mean: 9.45 9.09 8.43 9.83 9.78 9.23 9.41 8.49 9.33 10.36 10.03 9.23 9.66 9.98 9.52 9.94
[10:27:17.715] <TB2> INFO: bits RMS: 2.47 2.81 2.52 2.48 2.64 2.82 2.76 3.14 2.87 2.29 2.62 2.70 2.66 2.62 2.69 2.63
[10:27:17.722] <TB2> INFO: ----------------------------------------------------------------------
[10:27:17.722] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[10:27:17.722] <TB2> INFO: ----------------------------------------------------------------------
[10:27:17.725] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[10:27:17.734] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[10:27:17.734] <TB2> INFO: run 1 of 1
[10:27:18.050] <TB2> INFO: Expecting 4160000 events.
[10:28:03.119] <TB2> INFO: 905830 events read in total (44353ms).
[10:28:45.686] <TB2> INFO: 1805815 events read in total (86920ms).
[10:29:29.872] <TB2> INFO: 2697485 events read in total (131106ms).
[10:30:11.940] <TB2> INFO: 3583850 events read in total (173174ms).
[10:30:37.773] <TB2> INFO: 4160000 events read in total (199007ms).
[10:30:37.818] <TB2> INFO: Test took 200084ms.
[10:30:37.934] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:31:09.667] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 167 (-1/-1) hits flags = 528 (plus default)
[10:31:09.677] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[10:31:09.677] <TB2> INFO: run 1 of 1
[10:31:10.017] <TB2> INFO: Expecting 3494400 events.
[10:31:57.132] <TB2> INFO: 934735 events read in total (46399ms).
[10:32:40.684] <TB2> INFO: 1860505 events read in total (89951ms).
[10:33:26.623] <TB2> INFO: 2774320 events read in total (135890ms).
[10:33:58.717] <TB2> INFO: 3494400 events read in total (167984ms).
[10:33:58.750] <TB2> INFO: Test took 169073ms.
[10:33:58.837] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:34:24.148] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 154 (-1/-1) hits flags = 528 (plus default)
[10:34:24.158] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[10:34:24.158] <TB2> INFO: run 1 of 1
[10:34:24.472] <TB2> INFO: Expecting 3224000 events.
[10:35:08.905] <TB2> INFO: 967005 events read in total (43717ms).
[10:35:50.837] <TB2> INFO: 1921220 events read in total (85650ms).
[10:36:35.118] <TB2> INFO: 2863220 events read in total (129930ms).
[10:36:50.498] <TB2> INFO: 3224000 events read in total (145310ms).
[10:36:50.528] <TB2> INFO: Test took 146370ms.
[10:36:50.599] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:37:18.468] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 153 (-1/-1) hits flags = 528 (plus default)
[10:37:18.477] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[10:37:18.477] <TB2> INFO: run 1 of 1
[10:37:18.800] <TB2> INFO: Expecting 3203200 events.
[10:38:04.635] <TB2> INFO: 968785 events read in total (45119ms).
[10:38:45.448] <TB2> INFO: 1924710 events read in total (85933ms).
[10:39:30.844] <TB2> INFO: 2868245 events read in total (131328ms).
[10:39:45.539] <TB2> INFO: 3203200 events read in total (146023ms).
[10:39:45.572] <TB2> INFO: Test took 147095ms.
[10:39:45.645] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:10.492] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 153 (-1/-1) hits flags = 528 (plus default)
[10:40:10.501] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[10:40:10.501] <TB2> INFO: run 1 of 1
[10:40:10.840] <TB2> INFO: Expecting 3203200 events.
[10:40:57.918] <TB2> INFO: 968215 events read in total (46363ms).
[10:41:42.460] <TB2> INFO: 1923275 events read in total (90905ms).
[10:42:29.318] <TB2> INFO: 2866680 events read in total (137763ms).
[10:42:46.111] <TB2> INFO: 3203200 events read in total (154556ms).
[10:42:46.141] <TB2> INFO: Test took 155640ms.
[10:42:46.212] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:10.585] <TB2> INFO: PixTestTrim::trimBitTest() done
[10:43:10.586] <TB2> INFO: PixTestTrim::doTest() done, duration: 2675 seconds
[10:43:10.586] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:10.586] <TB2> INFO: Decoding statistics:
[10:43:10.586] <TB2> INFO: General information:
[10:43:10.586] <TB2> INFO: 16bit words read: 0
[10:43:10.586] <TB2> INFO: valid events total: 0
[10:43:10.586] <TB2> INFO: empty events: 0
[10:43:10.586] <TB2> INFO: valid events with pixels: 0
[10:43:10.586] <TB2> INFO: valid pixel hits: 0
[10:43:10.586] <TB2> INFO: Event errors: 0
[10:43:10.586] <TB2> INFO: start marker: 0
[10:43:10.586] <TB2> INFO: stop marker: 0
[10:43:10.586] <TB2> INFO: overflow: 0
[10:43:10.586] <TB2> INFO: invalid 5bit words: 0
[10:43:10.586] <TB2> INFO: invalid XOR eye diagram: 0
[10:43:10.586] <TB2> INFO: TBM errors: 0
[10:43:10.586] <TB2> INFO: flawed TBM headers: 0
[10:43:10.586] <TB2> INFO: flawed TBM trailers: 0
[10:43:10.586] <TB2> INFO: event ID mismatches: 0
[10:43:10.586] <TB2> INFO: ROC errors: 0
[10:43:10.586] <TB2> INFO: missing ROC header(s): 0
[10:43:10.586] <TB2> INFO: misplaced readback start: 0
[10:43:10.586] <TB2> INFO: Pixel decoding errors: 0
[10:43:10.586] <TB2> INFO: pixel data incomplete: 0
[10:43:10.586] <TB2> INFO: pixel address: 0
[10:43:10.586] <TB2> INFO: pulse height fill bit: 0
[10:43:10.586] <TB2> INFO: buffer corruption: 0
[10:43:11.278] <TB2> INFO: ######################################################################
[10:43:11.278] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[10:43:11.278] <TB2> INFO: ######################################################################
[10:43:11.586] <TB2> INFO: Expecting 41600 events.
[10:43:15.799] <TB2> INFO: 41600 events read in total (3498ms).
[10:43:15.800] <TB2> INFO: Test took 4521ms.
[10:43:15.808] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:16.387] <TB2> INFO: Expecting 41600 events.
[10:43:20.852] <TB2> INFO: 41600 events read in total (3750ms).
[10:43:20.853] <TB2> INFO: Test took 4782ms.
[10:43:20.860] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:21.204] <TB2> INFO: Expecting 41600 events.
[10:43:25.623] <TB2> INFO: 41600 events read in total (3703ms).
[10:43:25.623] <TB2> INFO: Test took 4743ms.
[10:43:25.630] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:25.970] <TB2> INFO: Expecting 2560 events.
[10:43:27.040] <TB2> INFO: 2560 events read in total (354ms).
[10:43:27.040] <TB2> INFO: Test took 1403ms.
[10:43:27.454] <TB2> INFO: Expecting 2560 events.
[10:43:28.523] <TB2> INFO: 2560 events read in total (354ms).
[10:43:28.523] <TB2> INFO: Test took 1482ms.
[10:43:28.937] <TB2> INFO: Expecting 2560 events.
[10:43:30.006] <TB2> INFO: 2560 events read in total (352ms).
[10:43:30.006] <TB2> INFO: Test took 1482ms.
[10:43:30.420] <TB2> INFO: Expecting 2560 events.
[10:43:31.486] <TB2> INFO: 2560 events read in total (351ms).
[10:43:31.487] <TB2> INFO: Test took 1480ms.
[10:43:31.901] <TB2> INFO: Expecting 2560 events.
[10:43:32.979] <TB2> INFO: 2560 events read in total (362ms).
[10:43:32.980] <TB2> INFO: Test took 1493ms.
[10:43:33.394] <TB2> INFO: Expecting 2560 events.
[10:43:34.457] <TB2> INFO: 2560 events read in total (348ms).
[10:43:34.457] <TB2> INFO: Test took 1477ms.
[10:43:34.871] <TB2> INFO: Expecting 2560 events.
[10:43:35.941] <TB2> INFO: 2560 events read in total (354ms).
[10:43:35.942] <TB2> INFO: Test took 1485ms.
[10:43:36.357] <TB2> INFO: Expecting 2560 events.
[10:43:37.426] <TB2> INFO: 2560 events read in total (354ms).
[10:43:37.426] <TB2> INFO: Test took 1484ms.
[10:43:37.841] <TB2> INFO: Expecting 2560 events.
[10:43:38.908] <TB2> INFO: 2560 events read in total (352ms).
[10:43:38.908] <TB2> INFO: Test took 1482ms.
[10:43:39.322] <TB2> INFO: Expecting 2560 events.
[10:43:40.390] <TB2> INFO: 2560 events read in total (352ms).
[10:43:40.391] <TB2> INFO: Test took 1482ms.
[10:43:40.805] <TB2> INFO: Expecting 2560 events.
[10:43:41.868] <TB2> INFO: 2560 events read in total (347ms).
[10:43:41.868] <TB2> INFO: Test took 1477ms.
[10:43:42.283] <TB2> INFO: Expecting 2560 events.
[10:43:43.351] <TB2> INFO: 2560 events read in total (352ms).
[10:43:43.351] <TB2> INFO: Test took 1483ms.
[10:43:43.765] <TB2> INFO: Expecting 2560 events.
[10:43:44.832] <TB2> INFO: 2560 events read in total (352ms).
[10:43:44.832] <TB2> INFO: Test took 1480ms.
[10:43:45.246] <TB2> INFO: Expecting 2560 events.
[10:43:46.317] <TB2> INFO: 2560 events read in total (355ms).
[10:43:46.317] <TB2> INFO: Test took 1484ms.
[10:43:46.731] <TB2> INFO: Expecting 2560 events.
[10:43:47.799] <TB2> INFO: 2560 events read in total (352ms).
[10:43:47.800] <TB2> INFO: Test took 1483ms.
[10:43:48.214] <TB2> INFO: Expecting 2560 events.
[10:43:49.284] <TB2> INFO: 2560 events read in total (355ms).
[10:43:49.284] <TB2> INFO: Test took 1484ms.
[10:43:49.288] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:43:49.700] <TB2> INFO: Expecting 655360 events.
[10:44:05.186] <TB2> INFO: 655360 events read in total (14770ms).
[10:44:05.195] <TB2> INFO: Expecting 655360 events.
[10:44:21.131] <TB2> INFO: 655360 events read in total (15409ms).
[10:44:21.145] <TB2> INFO: Expecting 655360 events.
[10:44:37.391] <TB2> INFO: 655360 events read in total (15719ms).
[10:44:37.407] <TB2> INFO: Expecting 655360 events.
[10:44:53.122] <TB2> INFO: 655360 events read in total (15188ms).
[10:44:53.144] <TB2> INFO: Expecting 655360 events.
[10:45:09.116] <TB2> INFO: 655360 events read in total (15444ms).
[10:45:09.138] <TB2> INFO: Expecting 655360 events.
[10:45:24.840] <TB2> INFO: 655360 events read in total (15174ms).
[10:45:24.867] <TB2> INFO: Expecting 655360 events.
[10:45:40.911] <TB2> INFO: 655360 events read in total (15516ms).
[10:45:40.939] <TB2> INFO: Expecting 655360 events.
[10:45:56.765] <TB2> INFO: 655360 events read in total (15298ms).
[10:45:56.801] <TB2> INFO: Expecting 655360 events.
[10:46:12.600] <TB2> INFO: 655360 events read in total (15272ms).
[10:46:12.636] <TB2> INFO: Expecting 655360 events.
[10:46:28.551] <TB2> INFO: 655360 events read in total (15388ms).
[10:46:28.594] <TB2> INFO: Expecting 655360 events.
[10:46:44.437] <TB2> INFO: 655360 events read in total (15316ms).
[10:46:44.503] <TB2> INFO: Expecting 655360 events.
[10:47:00.264] <TB2> INFO: 655360 events read in total (15234ms).
[10:47:00.316] <TB2> INFO: Expecting 655360 events.
[10:47:16.361] <TB2> INFO: 655360 events read in total (15517ms).
[10:47:16.411] <TB2> INFO: Expecting 655360 events.
[10:47:32.252] <TB2> INFO: 655360 events read in total (15313ms).
[10:47:32.316] <TB2> INFO: Expecting 655360 events.
[10:47:48.237] <TB2> INFO: 655360 events read in total (15393ms).
[10:47:48.307] <TB2> INFO: Expecting 655360 events.
[10:48:04.442] <TB2> INFO: 655360 events read in total (15608ms).
[10:48:04.502] <TB2> INFO: Test took 255214ms.
[10:48:04.584] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:48:04.892] <TB2> INFO: Expecting 655360 events.
[10:48:20.760] <TB2> INFO: 655360 events read in total (15152ms).
[10:48:20.770] <TB2> INFO: Expecting 655360 events.
[10:48:36.628] <TB2> INFO: 655360 events read in total (15331ms).
[10:48:36.641] <TB2> INFO: Expecting 655360 events.
[10:48:52.454] <TB2> INFO: 655360 events read in total (15286ms).
[10:48:52.470] <TB2> INFO: Expecting 655360 events.
[10:49:08.161] <TB2> INFO: 655360 events read in total (15163ms).
[10:49:08.182] <TB2> INFO: Expecting 655360 events.
[10:49:23.829] <TB2> INFO: 655360 events read in total (15119ms).
[10:49:23.851] <TB2> INFO: Expecting 655360 events.
[10:49:39.637] <TB2> INFO: 655360 events read in total (15258ms).
[10:49:39.666] <TB2> INFO: Expecting 655360 events.
[10:49:55.494] <TB2> INFO: 655360 events read in total (15301ms).
[10:49:55.525] <TB2> INFO: Expecting 655360 events.
[10:50:11.237] <TB2> INFO: 655360 events read in total (15184ms).
[10:50:11.273] <TB2> INFO: Expecting 655360 events.
[10:50:26.992] <TB2> INFO: 655360 events read in total (15192ms).
[10:50:27.029] <TB2> INFO: Expecting 655360 events.
[10:50:41.839] <TB2> INFO: 655360 events read in total (14283ms).
[10:50:41.879] <TB2> INFO: Expecting 655360 events.
[10:50:56.223] <TB2> INFO: 655360 events read in total (13817ms).
[10:50:56.266] <TB2> INFO: Expecting 655360 events.
[10:51:10.234] <TB2> INFO: 655360 events read in total (13440ms).
[10:51:10.282] <TB2> INFO: Expecting 655360 events.
[10:51:25.914] <TB2> INFO: 655360 events read in total (15105ms).
[10:51:25.965] <TB2> INFO: Expecting 655360 events.
[10:51:41.696] <TB2> INFO: 655360 events read in total (15204ms).
[10:51:41.759] <TB2> INFO: Expecting 655360 events.
[10:51:57.549] <TB2> INFO: 655360 events read in total (15262ms).
[10:51:57.606] <TB2> INFO: Expecting 655360 events.
[10:52:13.510] <TB2> INFO: 655360 events read in total (15377ms).
[10:52:13.572] <TB2> INFO: Test took 248989ms.
[10:52:13.770] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:52:13.778] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:52:13.786] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:52:13.793] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:52:13.801] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:52:13.808] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:52:13.815] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[10:52:13.823] <TB2> INFO: safety margin for low PH: adding 2, margin is now 22
[10:52:13.830] <TB2> INFO: safety margin for low PH: adding 3, margin is now 23
[10:52:13.838] <TB2> INFO: safety margin for low PH: adding 4, margin is now 24
[10:52:13.846] <TB2> INFO: safety margin for low PH: adding 5, margin is now 25
[10:52:13.853] <TB2> INFO: safety margin for low PH: adding 6, margin is now 26
[10:52:13.860] <TB2> INFO: safety margin for low PH: adding 7, margin is now 27
[10:52:13.868] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:52:13.875] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:52:13.882] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:52:13.889] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:52:13.896] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:52:13.903] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:52:13.910] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:52:13.917] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:52:13.925] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:52:13.932] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[10:52:13.976] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:52:13.976] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:52:13.976] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:52:13.976] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:52:13.977] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:52:13.977] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:52:13.977] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:52:13.977] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:52:13.977] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:52:13.978] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:52:13.978] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:52:13.978] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:52:13.978] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:52:13.978] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:52:13.979] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:52:13.979] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:52:14.300] <TB2> INFO: Expecting 41600 events.
[10:52:18.517] <TB2> INFO: 41600 events read in total (3501ms).
[10:52:18.518] <TB2> INFO: Test took 4536ms.
[10:52:19.057] <TB2> INFO: Expecting 41600 events.
[10:52:23.016] <TB2> INFO: 41600 events read in total (3243ms).
[10:52:23.016] <TB2> INFO: Test took 4274ms.
[10:52:23.566] <TB2> INFO: Expecting 41600 events.
[10:52:27.596] <TB2> INFO: 41600 events read in total (3314ms).
[10:52:27.597] <TB2> INFO: Test took 4346ms.
[10:52:27.819] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:27.950] <TB2> INFO: Expecting 2560 events.
[10:52:29.020] <TB2> INFO: 2560 events read in total (354ms).
[10:52:29.020] <TB2> INFO: Test took 1201ms.
[10:52:29.023] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:29.434] <TB2> INFO: Expecting 2560 events.
[10:52:30.532] <TB2> INFO: 2560 events read in total (382ms).
[10:52:30.532] <TB2> INFO: Test took 1509ms.
[10:52:30.535] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:30.947] <TB2> INFO: Expecting 2560 events.
[10:52:32.016] <TB2> INFO: 2560 events read in total (354ms).
[10:52:32.016] <TB2> INFO: Test took 1481ms.
[10:52:32.019] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:32.431] <TB2> INFO: Expecting 2560 events.
[10:52:33.503] <TB2> INFO: 2560 events read in total (357ms).
[10:52:33.504] <TB2> INFO: Test took 1485ms.
[10:52:33.507] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:33.919] <TB2> INFO: Expecting 2560 events.
[10:52:34.988] <TB2> INFO: 2560 events read in total (354ms).
[10:52:34.988] <TB2> INFO: Test took 1481ms.
[10:52:34.990] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:35.403] <TB2> INFO: Expecting 2560 events.
[10:52:36.473] <TB2> INFO: 2560 events read in total (355ms).
[10:52:36.473] <TB2> INFO: Test took 1483ms.
[10:52:36.476] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:36.888] <TB2> INFO: Expecting 2560 events.
[10:52:37.958] <TB2> INFO: 2560 events read in total (354ms).
[10:52:37.959] <TB2> INFO: Test took 1483ms.
[10:52:37.961] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:38.373] <TB2> INFO: Expecting 2560 events.
[10:52:39.436] <TB2> INFO: 2560 events read in total (347ms).
[10:52:39.437] <TB2> INFO: Test took 1476ms.
[10:52:39.439] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:39.851] <TB2> INFO: Expecting 2560 events.
[10:52:40.919] <TB2> INFO: 2560 events read in total (352ms).
[10:52:40.919] <TB2> INFO: Test took 1480ms.
[10:52:40.922] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:41.334] <TB2> INFO: Expecting 2560 events.
[10:52:42.402] <TB2> INFO: 2560 events read in total (353ms).
[10:52:42.403] <TB2> INFO: Test took 1481ms.
[10:52:42.405] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:42.818] <TB2> INFO: Expecting 2560 events.
[10:52:43.885] <TB2> INFO: 2560 events read in total (352ms).
[10:52:43.885] <TB2> INFO: Test took 1480ms.
[10:52:43.887] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:44.301] <TB2> INFO: Expecting 2560 events.
[10:52:45.370] <TB2> INFO: 2560 events read in total (354ms).
[10:52:45.370] <TB2> INFO: Test took 1483ms.
[10:52:45.373] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:45.785] <TB2> INFO: Expecting 2560 events.
[10:52:46.867] <TB2> INFO: 2560 events read in total (366ms).
[10:52:46.868] <TB2> INFO: Test took 1495ms.
[10:52:46.871] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:47.283] <TB2> INFO: Expecting 2560 events.
[10:52:48.353] <TB2> INFO: 2560 events read in total (355ms).
[10:52:48.353] <TB2> INFO: Test took 1483ms.
[10:52:48.356] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:48.767] <TB2> INFO: Expecting 2560 events.
[10:52:49.837] <TB2> INFO: 2560 events read in total (355ms).
[10:52:49.839] <TB2> INFO: Test took 1483ms.
[10:52:49.842] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:50.252] <TB2> INFO: Expecting 2560 events.
[10:52:51.322] <TB2> INFO: 2560 events read in total (355ms).
[10:52:51.322] <TB2> INFO: Test took 1481ms.
[10:52:51.325] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:51.737] <TB2> INFO: Expecting 2560 events.
[10:52:52.806] <TB2> INFO: 2560 events read in total (353ms).
[10:52:52.806] <TB2> INFO: Test took 1481ms.
[10:52:52.809] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:53.221] <TB2> INFO: Expecting 2560 events.
[10:52:54.289] <TB2> INFO: 2560 events read in total (353ms).
[10:52:54.290] <TB2> INFO: Test took 1481ms.
[10:52:54.293] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:54.704] <TB2> INFO: Expecting 2560 events.
[10:52:55.773] <TB2> INFO: 2560 events read in total (353ms).
[10:52:55.773] <TB2> INFO: Test took 1480ms.
[10:52:55.776] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:56.188] <TB2> INFO: Expecting 2560 events.
[10:52:57.257] <TB2> INFO: 2560 events read in total (353ms).
[10:52:57.257] <TB2> INFO: Test took 1481ms.
[10:52:57.261] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:57.672] <TB2> INFO: Expecting 2560 events.
[10:52:58.740] <TB2> INFO: 2560 events read in total (353ms).
[10:52:58.740] <TB2> INFO: Test took 1480ms.
[10:52:58.742] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:52:59.155] <TB2> INFO: Expecting 2560 events.
[10:53:00.225] <TB2> INFO: 2560 events read in total (355ms).
[10:53:00.225] <TB2> INFO: Test took 1483ms.
[10:53:00.228] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:53:00.640] <TB2> INFO: Expecting 2560 events.
[10:53:01.708] <TB2> INFO: 2560 events read in total (352ms).
[10:53:01.709] <TB2> INFO: Test took 1482ms.
[10:53:01.711] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:53:02.124] <TB2> INFO: Expecting 2560 events.
[10:53:03.188] <TB2> INFO: 2560 events read in total (349ms).
[10:53:03.188] <TB2> INFO: Test took 1477ms.
[10:53:03.191] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:53:03.603] <TB2> INFO: Expecting 2560 events.
[10:53:04.673] <TB2> INFO: 2560 events read in total (354ms).
[10:53:04.673] <TB2> INFO: Test took 1483ms.
[10:53:04.676] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:53:05.088] <TB2> INFO: Expecting 2560 events.
[10:53:06.157] <TB2> INFO: 2560 events read in total (354ms).
[10:53:06.158] <TB2> INFO: Test took 1483ms.
[10:53:06.160] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:53:06.572] <TB2> INFO: Expecting 2560 events.
[10:53:07.640] <TB2> INFO: 2560 events read in total (352ms).
[10:53:07.640] <TB2> INFO: Test took 1480ms.
[10:53:07.642] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:53:08.055] <TB2> INFO: Expecting 2560 events.
[10:53:09.122] <TB2> INFO: 2560 events read in total (351ms).
[10:53:09.122] <TB2> INFO: Test took 1480ms.
[10:53:09.125] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:53:09.537] <TB2> INFO: Expecting 2560 events.
[10:53:10.605] <TB2> INFO: 2560 events read in total (352ms).
[10:53:10.606] <TB2> INFO: Test took 1482ms.
[10:53:10.608] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:53:11.021] <TB2> INFO: Expecting 2560 events.
[10:53:12.091] <TB2> INFO: 2560 events read in total (354ms).
[10:53:12.092] <TB2> INFO: Test took 1484ms.
[10:53:12.095] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:53:12.506] <TB2> INFO: Expecting 2560 events.
[10:53:13.574] <TB2> INFO: 2560 events read in total (352ms).
[10:53:13.574] <TB2> INFO: Test took 1479ms.
[10:53:13.579] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:53:13.990] <TB2> INFO: Expecting 2560 events.
[10:53:15.060] <TB2> INFO: 2560 events read in total (354ms).
[10:53:15.060] <TB2> INFO: Test took 1481ms.
[10:53:15.669] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 604 seconds
[10:53:15.669] <TB2> INFO: PH scale (per ROC): 85 87 87 91 80 82 96 91 80 93 92 86 82 84 80 84
[10:53:15.669] <TB2> INFO: PH offset (per ROC): 151 170 149 151 164 176 149 141 164 150 146 142 163 171 158 169
[10:53:15.676] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:15.676] <TB2> INFO: Decoding statistics:
[10:53:15.676] <TB2> INFO: General information:
[10:53:15.676] <TB2> INFO: 16bit words read: 91920
[10:53:15.676] <TB2> INFO: valid events total: 10240
[10:53:15.676] <TB2> INFO: empty events: 7740
[10:53:15.676] <TB2> INFO: valid events with pixels: 2500
[10:53:15.676] <TB2> INFO: valid pixel hits: 2500
[10:53:15.676] <TB2> INFO: Event errors: 0
[10:53:15.676] <TB2> INFO: start marker: 0
[10:53:15.676] <TB2> INFO: stop marker: 0
[10:53:15.676] <TB2> INFO: overflow: 0
[10:53:15.676] <TB2> INFO: invalid 5bit words: 0
[10:53:15.676] <TB2> INFO: invalid XOR eye diagram: 0
[10:53:15.676] <TB2> INFO: TBM errors: 0
[10:53:15.676] <TB2> INFO: flawed TBM headers: 0
[10:53:15.676] <TB2> INFO: flawed TBM trailers: 0
[10:53:15.676] <TB2> INFO: event ID mismatches: 0
[10:53:15.676] <TB2> INFO: ROC errors: 0
[10:53:15.676] <TB2> INFO: missing ROC header(s): 0
[10:53:15.676] <TB2> INFO: misplaced readback start: 0
[10:53:15.676] <TB2> INFO: Pixel decoding errors: 0
[10:53:15.676] <TB2> INFO: pixel data incomplete: 0
[10:53:15.676] <TB2> INFO: pixel address: 0
[10:53:15.676] <TB2> INFO: pulse height fill bit: 0
[10:53:15.676] <TB2> INFO: buffer corruption: 0
[10:53:15.847] <TB2> INFO: ######################################################################
[10:53:15.847] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[10:53:15.847] <TB2> INFO: ######################################################################
[10:53:15.856] <TB2> INFO: scanning low vcal = 10
[10:53:16.169] <TB2> INFO: Expecting 41600 events.
[10:53:19.997] <TB2> INFO: 41600 events read in total (3113ms).
[10:53:19.998] <TB2> INFO: Test took 4142ms.
[10:53:20.000] <TB2> INFO: scanning low vcal = 20
[10:53:20.412] <TB2> INFO: Expecting 41600 events.
[10:53:24.185] <TB2> INFO: 41600 events read in total (3057ms).
[10:53:24.186] <TB2> INFO: Test took 4186ms.
[10:53:24.187] <TB2> INFO: scanning low vcal = 30
[10:53:24.601] <TB2> INFO: Expecting 41600 events.
[10:53:28.457] <TB2> INFO: 41600 events read in total (3141ms).
[10:53:28.458] <TB2> INFO: Test took 4271ms.
[10:53:28.460] <TB2> INFO: scanning low vcal = 40
[10:53:28.867] <TB2> INFO: Expecting 41600 events.
[10:53:33.250] <TB2> INFO: 41600 events read in total (3668ms).
[10:53:33.251] <TB2> INFO: Test took 4791ms.
[10:53:33.254] <TB2> INFO: scanning low vcal = 50
[10:53:33.594] <TB2> INFO: Expecting 41600 events.
[10:53:37.939] <TB2> INFO: 41600 events read in total (3629ms).
[10:53:37.939] <TB2> INFO: Test took 4685ms.
[10:53:37.942] <TB2> INFO: scanning low vcal = 60
[10:53:38.297] <TB2> INFO: Expecting 41600 events.
[10:53:42.700] <TB2> INFO: 41600 events read in total (3687ms).
[10:53:42.701] <TB2> INFO: Test took 4759ms.
[10:53:42.703] <TB2> INFO: scanning low vcal = 70
[10:53:43.055] <TB2> INFO: Expecting 41600 events.
[10:53:47.471] <TB2> INFO: 41600 events read in total (3700ms).
[10:53:47.471] <TB2> INFO: Test took 4768ms.
[10:53:47.474] <TB2> INFO: scanning low vcal = 80
[10:53:47.826] <TB2> INFO: Expecting 41600 events.
[10:53:52.249] <TB2> INFO: 41600 events read in total (3708ms).
[10:53:52.249] <TB2> INFO: Test took 4775ms.
[10:53:52.252] <TB2> INFO: scanning low vcal = 90
[10:53:52.601] <TB2> INFO: Expecting 41600 events.
[10:53:57.056] <TB2> INFO: 41600 events read in total (3740ms).
[10:53:57.056] <TB2> INFO: Test took 4804ms.
[10:53:57.059] <TB2> INFO: scanning low vcal = 100
[10:53:57.401] <TB2> INFO: Expecting 41600 events.
[10:54:01.898] <TB2> INFO: 41600 events read in total (3782ms).
[10:54:01.899] <TB2> INFO: Test took 4839ms.
[10:54:01.902] <TB2> INFO: scanning low vcal = 110
[10:54:02.256] <TB2> INFO: Expecting 41600 events.
[10:54:06.559] <TB2> INFO: 41600 events read in total (3587ms).
[10:54:06.560] <TB2> INFO: Test took 4658ms.
[10:54:06.562] <TB2> INFO: scanning low vcal = 120
[10:54:06.919] <TB2> INFO: Expecting 41600 events.
[10:54:11.187] <TB2> INFO: 41600 events read in total (3552ms).
[10:54:11.187] <TB2> INFO: Test took 4625ms.
[10:54:11.190] <TB2> INFO: scanning low vcal = 130
[10:54:11.535] <TB2> INFO: Expecting 41600 events.
[10:54:15.782] <TB2> INFO: 41600 events read in total (3531ms).
[10:54:15.782] <TB2> INFO: Test took 4592ms.
[10:54:15.785] <TB2> INFO: scanning low vcal = 140
[10:54:16.142] <TB2> INFO: Expecting 41600 events.
[10:54:20.373] <TB2> INFO: 41600 events read in total (3516ms).
[10:54:20.373] <TB2> INFO: Test took 4589ms.
[10:54:20.376] <TB2> INFO: scanning low vcal = 150
[10:54:20.724] <TB2> INFO: Expecting 41600 events.
[10:54:24.944] <TB2> INFO: 41600 events read in total (3505ms).
[10:54:24.945] <TB2> INFO: Test took 4569ms.
[10:54:24.947] <TB2> INFO: scanning low vcal = 160
[10:54:25.304] <TB2> INFO: Expecting 41600 events.
[10:54:29.554] <TB2> INFO: 41600 events read in total (3534ms).
[10:54:29.554] <TB2> INFO: Test took 4607ms.
[10:54:29.557] <TB2> INFO: scanning low vcal = 170
[10:54:29.900] <TB2> INFO: Expecting 41600 events.
[10:54:34.158] <TB2> INFO: 41600 events read in total (3543ms).
[10:54:34.159] <TB2> INFO: Test took 4602ms.
[10:54:34.163] <TB2> INFO: scanning low vcal = 180
[10:54:34.516] <TB2> INFO: Expecting 41600 events.
[10:54:38.786] <TB2> INFO: 41600 events read in total (3555ms).
[10:54:38.787] <TB2> INFO: Test took 4624ms.
[10:54:38.803] <TB2> INFO: scanning low vcal = 190
[10:54:39.143] <TB2> INFO: Expecting 41600 events.
[10:54:43.402] <TB2> INFO: 41600 events read in total (3544ms).
[10:54:43.402] <TB2> INFO: Test took 4599ms.
[10:54:43.405] <TB2> INFO: scanning low vcal = 200
[10:54:43.759] <TB2> INFO: Expecting 41600 events.
[10:54:48.113] <TB2> INFO: 41600 events read in total (3639ms).
[10:54:48.113] <TB2> INFO: Test took 4708ms.
[10:54:48.116] <TB2> INFO: scanning low vcal = 210
[10:54:48.450] <TB2> INFO: Expecting 41600 events.
[10:54:52.871] <TB2> INFO: 41600 events read in total (3706ms).
[10:54:52.871] <TB2> INFO: Test took 4755ms.
[10:54:52.874] <TB2> INFO: scanning low vcal = 220
[10:54:53.227] <TB2> INFO: Expecting 41600 events.
[10:54:57.619] <TB2> INFO: 41600 events read in total (3676ms).
[10:54:57.619] <TB2> INFO: Test took 4745ms.
[10:54:57.622] <TB2> INFO: scanning low vcal = 230
[10:54:57.969] <TB2> INFO: Expecting 41600 events.
[10:55:02.299] <TB2> INFO: 41600 events read in total (3614ms).
[10:55:02.299] <TB2> INFO: Test took 4677ms.
[10:55:02.302] <TB2> INFO: scanning low vcal = 240
[10:55:02.651] <TB2> INFO: Expecting 41600 events.
[10:55:07.033] <TB2> INFO: 41600 events read in total (3666ms).
[10:55:07.033] <TB2> INFO: Test took 4731ms.
[10:55:07.036] <TB2> INFO: scanning low vcal = 250
[10:55:07.388] <TB2> INFO: Expecting 41600 events.
[10:55:11.844] <TB2> INFO: 41600 events read in total (3740ms).
[10:55:11.845] <TB2> INFO: Test took 4809ms.
[10:55:11.849] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[10:55:12.180] <TB2> INFO: Expecting 41600 events.
[10:55:16.558] <TB2> INFO: 41600 events read in total (3662ms).
[10:55:16.559] <TB2> INFO: Test took 4710ms.
[10:55:16.562] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[10:55:16.915] <TB2> INFO: Expecting 41600 events.
[10:55:21.296] <TB2> INFO: 41600 events read in total (3665ms).
[10:55:21.297] <TB2> INFO: Test took 4735ms.
[10:55:21.299] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[10:55:21.645] <TB2> INFO: Expecting 41600 events.
[10:55:26.067] <TB2> INFO: 41600 events read in total (3706ms).
[10:55:26.067] <TB2> INFO: Test took 4767ms.
[10:55:26.070] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[10:55:26.405] <TB2> INFO: Expecting 41600 events.
[10:55:30.872] <TB2> INFO: 41600 events read in total (3752ms).
[10:55:30.872] <TB2> INFO: Test took 4802ms.
[10:55:30.875] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[10:55:31.217] <TB2> INFO: Expecting 41600 events.
[10:55:35.620] <TB2> INFO: 41600 events read in total (3688ms).
[10:55:35.621] <TB2> INFO: Test took 4746ms.
[10:55:36.077] <TB2> INFO: PixTestGainPedestal::measure() done
[10:56:09.521] <TB2> INFO: PixTestGainPedestal::fit() done
[10:56:09.521] <TB2> INFO: non-linearity mean: 0.954 0.956 0.962 0.956 0.954 0.958 0.951 0.955 0.955 0.962 0.955 0.961 0.956 0.955 0.956 0.954
[10:56:09.521] <TB2> INFO: non-linearity RMS: 0.006 0.005 0.006 0.006 0.006 0.005 0.005 0.006 0.005 0.005 0.005 0.006 0.006 0.006 0.005 0.007
[10:56:09.521] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[10:56:09.540] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[10:56:09.560] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[10:56:09.581] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[10:56:09.598] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[10:56:09.616] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[10:56:09.634] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[10:56:09.651] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[10:56:09.677] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[10:56:09.707] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[10:56:09.736] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[10:56:09.764] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[10:56:09.783] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[10:56:09.813] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[10:56:09.843] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[10:56:09.862] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[10:56:09.880] <TB2> INFO: PixTestGainPedestal::fullTest() done, duration: 174 seconds
[10:56:09.880] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:09.880] <TB2> INFO: Decoding statistics:
[10:56:09.880] <TB2> INFO: General information:
[10:56:09.880] <TB2> INFO: 16bit words read: 2662400
[10:56:09.880] <TB2> INFO: valid events total: 166400
[10:56:09.880] <TB2> INFO: empty events: 0
[10:56:09.880] <TB2> INFO: valid events with pixels: 166400
[10:56:09.880] <TB2> INFO: valid pixel hits: 665592
[10:56:09.880] <TB2> INFO: Event errors: 0
[10:56:09.880] <TB2> INFO: start marker: 0
[10:56:09.880] <TB2> INFO: stop marker: 0
[10:56:09.880] <TB2> INFO: overflow: 0
[10:56:09.880] <TB2> INFO: invalid 5bit words: 0
[10:56:09.880] <TB2> INFO: invalid XOR eye diagram: 0
[10:56:09.880] <TB2> INFO: TBM errors: 0
[10:56:09.880] <TB2> INFO: flawed TBM headers: 0
[10:56:09.880] <TB2> INFO: flawed TBM trailers: 0
[10:56:09.880] <TB2> INFO: event ID mismatches: 0
[10:56:09.880] <TB2> INFO: ROC errors: 0
[10:56:09.880] <TB2> INFO: missing ROC header(s): 0
[10:56:09.880] <TB2> INFO: misplaced readback start: 0
[10:56:09.880] <TB2> INFO: Pixel decoding errors: 0
[10:56:09.880] <TB2> INFO: pixel data incomplete: 0
[10:56:09.880] <TB2> INFO: pixel address: 0
[10:56:09.880] <TB2> INFO: pulse height fill bit: 0
[10:56:09.880] <TB2> INFO: buffer corruption: 0
[10:56:09.886] <TB2> INFO: readReadbackCal: /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C15.dat
[10:56:09.887] <TB2> INFO: ######################################################################
[10:56:09.887] <TB2> INFO: PixTestReadback::doTest()
[10:56:09.887] <TB2> INFO: ######################################################################
[10:56:09.888] <TB2> INFO: PixTestReadback::RES sent once
[10:56:21.098] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C0.dat
[10:56:21.098] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C1.dat
[10:56:21.098] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C2.dat
[10:56:21.098] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C3.dat
[10:56:21.099] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C4.dat
[10:56:21.099] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C5.dat
[10:56:21.099] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C6.dat
[10:56:21.099] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C7.dat
[10:56:21.099] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C8.dat
[10:56:21.099] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C9.dat
[10:56:21.099] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C10.dat
[10:56:21.099] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C11.dat
[10:56:21.099] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C12.dat
[10:56:21.099] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C13.dat
[10:56:21.099] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C14.dat
[10:56:21.100] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C15.dat
[10:56:21.132] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[10:56:21.132] <TB2> INFO: PixTestReadback::RES sent once
[10:56:32.318] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C0.dat
[10:56:32.318] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C1.dat
[10:56:32.318] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C2.dat
[10:56:32.318] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C3.dat
[10:56:32.318] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C4.dat
[10:56:32.318] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C5.dat
[10:56:32.318] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C6.dat
[10:56:32.318] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C7.dat
[10:56:32.318] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C8.dat
[10:56:32.318] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C9.dat
[10:56:32.318] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C10.dat
[10:56:32.318] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C11.dat
[10:56:32.319] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C12.dat
[10:56:32.319] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C13.dat
[10:56:32.319] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C14.dat
[10:56:32.319] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C15.dat
[10:56:32.346] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[10:56:32.347] <TB2> INFO: PixTestReadback::RES sent once
[10:56:40.955] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[10:56:40.955] <TB2> INFO: Vbg will be calibrated using Vd calibration
[10:56:40.955] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 161.9calibrated Vbg = 1.19149 :::*/*/*/*/
[10:56:40.955] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 166.8calibrated Vbg = 1.19247 :::*/*/*/*/
[10:56:40.955] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 156.9calibrated Vbg = 1.19809 :::*/*/*/*/
[10:56:40.955] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 157.6calibrated Vbg = 1.20435 :::*/*/*/*/
[10:56:40.955] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 154.7calibrated Vbg = 1.21134 :::*/*/*/*/
[10:56:40.955] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 158calibrated Vbg = 1.20218 :::*/*/*/*/
[10:56:40.955] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 161.8calibrated Vbg = 1.2103 :::*/*/*/*/
[10:56:40.955] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 155.2calibrated Vbg = 1.21265 :::*/*/*/*/
[10:56:40.955] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 150.5calibrated Vbg = 1.21013 :::*/*/*/*/
[10:56:40.955] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 141.2calibrated Vbg = 1.21025 :::*/*/*/*/
[10:56:40.955] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 155.9calibrated Vbg = 1.20525 :::*/*/*/*/
[10:56:40.955] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 159.4calibrated Vbg = 1.20551 :::*/*/*/*/
[10:56:40.955] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 160.6calibrated Vbg = 1.20353 :::*/*/*/*/
[10:56:40.955] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 163calibrated Vbg = 1.19796 :::*/*/*/*/
[10:56:40.955] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 149.2calibrated Vbg = 1.18851 :::*/*/*/*/
[10:56:40.955] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 153.1calibrated Vbg = 1.19387 :::*/*/*/*/
[10:56:40.959] <TB2> INFO: PixTestReadback::RES sent once
[10:59:35.482] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C0.dat
[10:59:35.482] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C1.dat
[10:59:35.482] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C2.dat
[10:59:35.482] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C3.dat
[10:59:35.482] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C4.dat
[10:59:35.482] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C5.dat
[10:59:35.482] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C6.dat
[10:59:35.482] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C7.dat
[10:59:35.482] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C8.dat
[10:59:35.482] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C9.dat
[10:59:35.483] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C10.dat
[10:59:35.483] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C11.dat
[10:59:35.483] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C12.dat
[10:59:35.483] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C13.dat
[10:59:35.483] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C14.dat
[10:59:35.483] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2123_FullQualification_2015-12-07_10h25m_1449480354//000_FulltestPxar_m20//readbackCal_C15.dat
[10:59:35.513] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[10:59:35.514] <TB2> INFO: PixTestReadback::doTest() done
[10:59:35.515] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:59:35.515] <TB2> INFO: Decoding statistics:
[10:59:35.515] <TB2> INFO: General information:
[10:59:35.515] <TB2> INFO: 16bit words read: 1024
[10:59:35.515] <TB2> INFO: valid events total: 128
[10:59:35.515] <TB2> INFO: empty events: 128
[10:59:35.515] <TB2> INFO: valid events with pixels: 0
[10:59:35.515] <TB2> INFO: valid pixel hits: 0
[10:59:35.515] <TB2> INFO: Event errors: 0
[10:59:35.515] <TB2> INFO: start marker: 0
[10:59:35.515] <TB2> INFO: stop marker: 0
[10:59:35.515] <TB2> INFO: overflow: 0
[10:59:35.515] <TB2> INFO: invalid 5bit words: 0
[10:59:35.515] <TB2> INFO: invalid XOR eye diagram: 0
[10:59:35.515] <TB2> INFO: TBM errors: 0
[10:59:35.515] <TB2> INFO: flawed TBM headers: 0
[10:59:35.515] <TB2> INFO: flawed TBM trailers: 0
[10:59:35.515] <TB2> INFO: event ID mismatches: 0
[10:59:35.515] <TB2> INFO: ROC errors: 0
[10:59:35.515] <TB2> INFO: missing ROC header(s): 0
[10:59:35.515] <TB2> INFO: misplaced readback start: 0
[10:59:35.515] <TB2> INFO: Pixel decoding errors: 0
[10:59:35.515] <TB2> INFO: pixel data incomplete: 0
[10:59:35.515] <TB2> INFO: pixel address: 0
[10:59:35.515] <TB2> INFO: pulse height fill bit: 0
[10:59:35.515] <TB2> INFO: buffer corruption: 0
[10:59:35.529] <TB2> INFO: Decoding statistics:
[10:59:35.529] <TB2> INFO: General information:
[10:59:35.529] <TB2> INFO: 16bit words read: 2755344
[10:59:35.529] <TB2> INFO: valid events total: 176768
[10:59:35.529] <TB2> INFO: empty events: 7868
[10:59:35.529] <TB2> INFO: valid events with pixels: 168900
[10:59:35.529] <TB2> INFO: valid pixel hits: 668092
[10:59:35.529] <TB2> INFO: Event errors: 0
[10:59:35.529] <TB2> INFO: start marker: 0
[10:59:35.529] <TB2> INFO: stop marker: 0
[10:59:35.529] <TB2> INFO: overflow: 0
[10:59:35.529] <TB2> INFO: invalid 5bit words: 0
[10:59:35.529] <TB2> INFO: invalid XOR eye diagram: 0
[10:59:35.529] <TB2> INFO: TBM errors: 0
[10:59:35.529] <TB2> INFO: flawed TBM headers: 0
[10:59:35.529] <TB2> INFO: flawed TBM trailers: 0
[10:59:35.529] <TB2> INFO: event ID mismatches: 0
[10:59:35.529] <TB2> INFO: ROC errors: 0
[10:59:35.529] <TB2> INFO: missing ROC header(s): 0
[10:59:35.529] <TB2> INFO: misplaced readback start: 0
[10:59:35.529] <TB2> INFO: Pixel decoding errors: 0
[10:59:35.529] <TB2> INFO: pixel data incomplete: 0
[10:59:35.530] <TB2> INFO: pixel address: 0
[10:59:35.530] <TB2> INFO: pulse height fill bit: 0
[10:59:35.530] <TB2> INFO: buffer corruption: 0
[10:59:35.530] <TB2> INFO: enter test to run
[10:59:35.530] <TB2> INFO: test: exit no parameter change
[10:59:35.730] <TB2> QUIET: Connection to board 156 closed.
[10:59:35.810] <TB2> INFO: pXar: this is the end, my friend