[15:06:37.377] <TB3> INFO: *** Welcome to pxar ***
[15:06:37.377] <TB3> INFO: *** Today: 2015/09/07
[15:06:37.377] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters_C15.dat
[15:06:37.377] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//tbmParameters_C0b.dat
[15:06:37.378] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//defaultMaskFile.dat
[15:06:37.378] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//trimParameters_C15.dat
[15:06:37.446] <TB3> INFO: clk: 4
[15:06:37.446] <TB3> INFO: ctr: 4
[15:06:37.446] <TB3> INFO: sda: 19
[15:06:37.446] <TB3> INFO: tin: 9
[15:06:37.446] <TB3> INFO: level: 15
[15:06:37.446] <TB3> INFO: triggerdelay: 0
[15:06:37.446] <TB3> QUIET: Instanciating API for pxar prod-10+14~g355f077
[15:06:37.446] <TB3> INFO: Log level: INFO
[15:06:37.454] <TB3> INFO: Found DTB DTB_WZ4I6J
[15:06:37.465] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[15:06:37.468] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[15:06:37.470] <TB3> INFO: RPC call hashes of host and DTB match: 397073690
[15:06:38.995] <TB3> INFO: DUT info:
[15:06:38.995] <TB3> INFO: The DUT currently contains the following objects:
[15:06:38.995] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[15:06:38.995] <TB3> INFO: TBM Core alpha (0): 7 registers set
[15:06:38.995] <TB3> INFO: TBM Core beta (1): 7 registers set
[15:06:38.995] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:06:38.995] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:38.995] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:38.995] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:38.995] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:38.995] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:38.996] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:38.996] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:38.996] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:38.996] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:38.996] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:38.996] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:38.996] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:38.996] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:38.996] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:38.996] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:38.996] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:06:39.397] <TB3> INFO: enter 'restricted' command line mode
[15:06:39.397] <TB3> INFO: enter test to run
[15:06:39.397] <TB3> INFO: test: pretest no parameter change
[15:06:39.397] <TB3> INFO: running: pretest
[15:06:39.403] <TB3> INFO: ######################################################################
[15:06:39.403] <TB3> INFO: PixTestPretest::doTest()
[15:06:39.403] <TB3> INFO: ######################################################################
[15:06:39.405] <TB3> INFO: ----------------------------------------------------------------------
[15:06:39.405] <TB3> INFO: PixTestPretest::programROC()
[15:06:39.405] <TB3> INFO: ----------------------------------------------------------------------
[15:06:57.423] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[15:06:57.423] <TB3> INFO: IA differences per ROC: 17.7 17.7 19.3 19.3 20.1 19.3 18.5 19.3 17.7 19.3 19.3 16.9 17.7 18.5 19.3 18.5
[15:06:57.497] <TB3> INFO: ----------------------------------------------------------------------
[15:06:57.497] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[15:06:57.497] <TB3> INFO: ----------------------------------------------------------------------
[15:07:02.986] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 356.9 mA = 22.3062 mA/ROC
[15:07:02.989] <TB3> INFO: ----------------------------------------------------------------------
[15:07:02.989] <TB3> INFO: PixTestPretest::findWorkingPixel()
[15:07:02.989] <TB3> INFO: ----------------------------------------------------------------------
[15:07:03.125] <TB3> INFO: Expecting 231680 events.
[15:07:12.326] <TB3> INFO: 231680 events read in total (8482ms).
[15:07:12.395] <TB3> INFO: Test took 9403ms.
[15:07:12.666] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[15:07:12.704] <TB3> INFO: ----------------------------------------------------------------------
[15:07:12.704] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[15:07:12.704] <TB3> INFO: ----------------------------------------------------------------------
[15:07:12.841] <TB3> INFO: Expecting 231680 events.
[15:07:21.970] <TB3> INFO: 231680 events read in total (8412ms).
[15:07:21.974] <TB3> INFO: Test took 9265ms.
[15:07:22.293] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[15:07:22.293] <TB3> INFO: CalDel: 140 137 125 140 146 133 118 145 119 124 148 144 116 131 131 143
[15:07:22.293] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[15:07:22.296] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters_C0.dat
[15:07:22.296] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters_C1.dat
[15:07:22.297] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters_C2.dat
[15:07:22.297] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters_C3.dat
[15:07:22.297] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters_C4.dat
[15:07:22.297] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters_C5.dat
[15:07:22.298] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters_C6.dat
[15:07:22.298] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters_C7.dat
[15:07:22.298] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters_C8.dat
[15:07:22.298] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters_C9.dat
[15:07:22.298] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters_C10.dat
[15:07:22.299] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters_C11.dat
[15:07:22.299] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters_C12.dat
[15:07:22.299] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters_C13.dat
[15:07:22.299] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters_C14.dat
[15:07:22.300] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters_C15.dat
[15:07:22.300] <TB3> INFO: write tbm parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//tbmParameters_C0a.dat
[15:07:22.300] <TB3> INFO: write tbm parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//tbmParameters_C0b.dat
[15:07:22.300] <TB3> INFO: PixTestPretest::doTest() done, duration: 42 seconds
[15:07:22.390] <TB3> INFO: enter test to run
[15:07:22.391] <TB3> INFO: test: fulltest no parameter change
[15:07:22.391] <TB3> INFO: running: fulltest
[15:07:22.391] <TB3> INFO: ######################################################################
[15:07:22.391] <TB3> INFO: PixTestFullTest::doTest()
[15:07:22.391] <TB3> INFO: ######################################################################
[15:07:22.392] <TB3> INFO: ######################################################################
[15:07:22.392] <TB3> INFO: PixTestAlive::doTest()
[15:07:22.392] <TB3> INFO: ######################################################################
[15:07:22.393] <TB3> INFO: ----------------------------------------------------------------------
[15:07:22.394] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:07:22.394] <TB3> INFO: ----------------------------------------------------------------------
[15:07:22.703] <TB3> INFO: Expecting 41600 events.
[15:07:27.481] <TB3> INFO: 41600 events read in total (4062ms).
[15:07:27.481] <TB3> INFO: Test took 5086ms.
[15:07:27.487] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:27.778] <TB3> INFO: PixTestAlive::aliveTest() done
[15:07:27.778] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0
[15:07:27.780] <TB3> INFO: ----------------------------------------------------------------------
[15:07:27.780] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:07:27.780] <TB3> INFO: ----------------------------------------------------------------------
[15:07:28.092] <TB3> INFO: Expecting 41600 events.
[15:07:31.534] <TB3> INFO: 41600 events read in total (2726ms).
[15:07:31.534] <TB3> INFO: Test took 3752ms.
[15:07:31.534] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:31.535] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:07:31.853] <TB3> INFO: PixTestAlive::maskTest() done
[15:07:31.853] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:07:31.855] <TB3> INFO: ----------------------------------------------------------------------
[15:07:31.855] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:07:31.855] <TB3> INFO: ----------------------------------------------------------------------
[15:07:32.179] <TB3> INFO: Expecting 41600 events.
[15:07:36.936] <TB3> INFO: 41600 events read in total (4040ms).
[15:07:36.937] <TB3> INFO: Test took 5079ms.
[15:07:36.943] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:37.236] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[15:07:37.236] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:07:37.236] <TB3> INFO: PixTestAlive::doTest() done, duration: 14 seconds
[15:07:37.236] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:37.236] <TB3> INFO: Decoding statistics:
[15:07:37.236] <TB3> INFO: General information:
[15:07:37.236] <TB3> INFO: 16bit words read: 0
[15:07:37.236] <TB3> INFO: valid events total: 0
[15:07:37.236] <TB3> INFO: empty events: 0
[15:07:37.236] <TB3> INFO: valid events with pixels: 0
[15:07:37.236] <TB3> INFO: valid pixel hits: 0
[15:07:37.236] <TB3> INFO: Event errors: 0
[15:07:37.236] <TB3> INFO: start marker: 0
[15:07:37.236] <TB3> INFO: stop marker: 0
[15:07:37.236] <TB3> INFO: overflow: 0
[15:07:37.236] <TB3> INFO: invalid 5bit words: 0
[15:07:37.236] <TB3> INFO: invalid XOR eye diagram: 0
[15:07:37.236] <TB3> INFO: TBM errors: 0
[15:07:37.237] <TB3> INFO: flawed TBM headers: 0
[15:07:37.237] <TB3> INFO: flawed TBM trailers: 0
[15:07:37.237] <TB3> INFO: event ID mismatches: 0
[15:07:37.237] <TB3> INFO: ROC errors: 0
[15:07:37.237] <TB3> INFO: missing ROC header(s): 0
[15:07:37.237] <TB3> INFO: misplaced readback start: 0
[15:07:37.237] <TB3> INFO: Pixel decoding errors: 0
[15:07:37.237] <TB3> INFO: pixel data incomplete: 0
[15:07:37.237] <TB3> INFO: pixel address: 0
[15:07:37.237] <TB3> INFO: pulse height fill bit: 0
[15:07:37.237] <TB3> INFO: buffer corruption: 0
[15:07:37.249] <TB3> INFO: ######################################################################
[15:07:37.249] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[15:07:37.249] <TB3> INFO: ######################################################################
[15:07:37.252] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[15:07:37.267] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[15:07:37.267] <TB3> INFO: run 1 of 1
[15:07:37.600] <TB3> INFO: Expecting 3120000 events.
[15:08:19.020] <TB3> INFO: 847585 events read in total (40704ms).
[15:08:59.476] <TB3> INFO: 1685895 events read in total (81160ms).
[15:09:40.055] <TB3> INFO: 2533105 events read in total (121739ms).
[15:10:08.113] <TB3> INFO: 3120000 events read in total (149797ms).
[15:10:08.164] <TB3> INFO: Test took 150897ms.
[15:10:08.254] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:29.793] <TB3> INFO: PixTestBBMap::doTest() done, duration: 172 seconds
[15:10:29.793] <TB3> INFO: number of dead bumps (per ROC): 1 0 13 1 0 0 0 0 0 0 3 0 3 4 1 2
[15:10:29.793] <TB3> INFO: separation cut (per ROC): 79 71 80 85 94 67 65 59 62 66 65 88 95 88 96 72
[15:10:29.793] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:29.793] <TB3> INFO: Decoding statistics:
[15:10:29.793] <TB3> INFO: General information:
[15:10:29.793] <TB3> INFO: 16bit words read: 0
[15:10:29.793] <TB3> INFO: valid events total: 0
[15:10:29.793] <TB3> INFO: empty events: 0
[15:10:29.793] <TB3> INFO: valid events with pixels: 0
[15:10:29.793] <TB3> INFO: valid pixel hits: 0
[15:10:29.793] <TB3> INFO: Event errors: 0
[15:10:29.793] <TB3> INFO: start marker: 0
[15:10:29.793] <TB3> INFO: stop marker: 0
[15:10:29.793] <TB3> INFO: overflow: 0
[15:10:29.793] <TB3> INFO: invalid 5bit words: 0
[15:10:29.793] <TB3> INFO: invalid XOR eye diagram: 0
[15:10:29.793] <TB3> INFO: TBM errors: 0
[15:10:29.793] <TB3> INFO: flawed TBM headers: 0
[15:10:29.793] <TB3> INFO: flawed TBM trailers: 0
[15:10:29.793] <TB3> INFO: event ID mismatches: 0
[15:10:29.793] <TB3> INFO: ROC errors: 0
[15:10:29.793] <TB3> INFO: missing ROC header(s): 0
[15:10:29.793] <TB3> INFO: misplaced readback start: 0
[15:10:29.793] <TB3> INFO: Pixel decoding errors: 0
[15:10:29.793] <TB3> INFO: pixel data incomplete: 0
[15:10:29.793] <TB3> INFO: pixel address: 0
[15:10:29.793] <TB3> INFO: pulse height fill bit: 0
[15:10:29.793] <TB3> INFO: buffer corruption: 0
[15:10:29.867] <TB3> INFO: ######################################################################
[15:10:29.867] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:10:29.867] <TB3> INFO: ######################################################################
[15:10:29.867] <TB3> INFO: ----------------------------------------------------------------------
[15:10:29.867] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:10:29.867] <TB3> INFO: ----------------------------------------------------------------------
[15:10:29.867] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:10:29.876] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[15:10:29.876] <TB3> INFO: run 1 of 1
[15:10:30.183] <TB3> INFO: Expecting 26208000 events.
[15:10:59.011] <TB3> INFO: 856850 events read in total (28112ms).
[15:11:28.361] <TB3> INFO: 1704550 events read in total (57462ms).
[15:11:57.799] <TB3> INFO: 2549150 events read in total (86900ms).
[15:12:27.295] <TB3> INFO: 3390100 events read in total (116396ms).
[15:12:56.719] <TB3> INFO: 4231150 events read in total (145820ms).
[15:13:25.668] <TB3> INFO: 5073800 events read in total (174769ms).
[15:13:54.871] <TB3> INFO: 5913700 events read in total (203972ms).
[15:14:24.051] <TB3> INFO: 6753350 events read in total (233152ms).
[15:14:53.372] <TB3> INFO: 7591550 events read in total (262473ms).
[15:15:22.393] <TB3> INFO: 8428450 events read in total (291494ms).
[15:15:51.429] <TB3> INFO: 9267100 events read in total (320530ms).
[15:16:20.051] <TB3> INFO: 10104500 events read in total (349152ms).
[15:16:48.850] <TB3> INFO: 10940300 events read in total (377951ms).
[15:17:17.549] <TB3> INFO: 11776550 events read in total (406650ms).
[15:17:45.438] <TB3> INFO: 12612350 events read in total (434539ms).
[15:18:13.318] <TB3> INFO: 13440900 events read in total (462419ms).
[15:18:42.448] <TB3> INFO: 14266300 events read in total (491549ms).
[15:19:10.854] <TB3> INFO: 15088200 events read in total (519955ms).
[15:19:38.991] <TB3> INFO: 15910800 events read in total (548092ms).
[15:20:08.362] <TB3> INFO: 16732700 events read in total (577463ms).
[15:20:37.686] <TB3> INFO: 17553150 events read in total (606787ms).
[15:21:07.074] <TB3> INFO: 18374800 events read in total (636175ms).
[15:21:36.712] <TB3> INFO: 19196000 events read in total (665813ms).
[15:22:05.544] <TB3> INFO: 20013550 events read in total (694645ms).
[15:22:34.645] <TB3> INFO: 20831650 events read in total (723746ms).
[15:23:03.262] <TB3> INFO: 21649050 events read in total (752363ms).
[15:23:32.323] <TB3> INFO: 22462250 events read in total (781424ms).
[15:24:01.812] <TB3> INFO: 23281500 events read in total (810913ms).
[15:24:30.948] <TB3> INFO: 24096100 events read in total (840049ms).
[15:24:59.550] <TB3> INFO: 24914500 events read in total (868651ms).
[15:25:25.780] <TB3> INFO: 25734250 events read in total (894881ms).
[15:25:41.103] <TB3> INFO: 26208000 events read in total (910204ms).
[15:25:41.145] <TB3> INFO: Test took 911269ms.
[15:25:41.233] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:41.380] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:25:42.795] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:25:44.217] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:25:45.618] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:25:47.088] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:25:48.723] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:25:50.456] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:25:52.057] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:25:53.671] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:25:55.236] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:25:56.672] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:25:58.139] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:25:59.529] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:26:00.934] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:26:02.322] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:26:03.687] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[15:26:05.179] <TB3> INFO: PixTestScurves::scurves() done
[15:26:05.179] <TB3> INFO: Vcal mean: 92.76 89.22 85.11 89.98 97.85 75.24 73.40 55.36 66.94 81.71 77.56 101.30 90.75 99.51 98.63 81.14
[15:26:05.179] <TB3> INFO: Vcal RMS: 5.11 5.29 4.42 5.51 6.18 4.79 5.36 5.45 5.67 5.94 4.26 5.58 5.15 5.86 5.74 4.74
[15:26:05.179] <TB3> INFO: PixTestScurves::fullTest() done, duration: 935 seconds
[15:26:05.179] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:26:05.179] <TB3> INFO: Decoding statistics:
[15:26:05.179] <TB3> INFO: General information:
[15:26:05.179] <TB3> INFO: 16bit words read: 0
[15:26:05.179] <TB3> INFO: valid events total: 0
[15:26:05.179] <TB3> INFO: empty events: 0
[15:26:05.179] <TB3> INFO: valid events with pixels: 0
[15:26:05.179] <TB3> INFO: valid pixel hits: 0
[15:26:05.179] <TB3> INFO: Event errors: 0
[15:26:05.179] <TB3> INFO: start marker: 0
[15:26:05.179] <TB3> INFO: stop marker: 0
[15:26:05.179] <TB3> INFO: overflow: 0
[15:26:05.179] <TB3> INFO: invalid 5bit words: 0
[15:26:05.180] <TB3> INFO: invalid XOR eye diagram: 0
[15:26:05.180] <TB3> INFO: TBM errors: 0
[15:26:05.180] <TB3> INFO: flawed TBM headers: 0
[15:26:05.180] <TB3> INFO: flawed TBM trailers: 0
[15:26:05.180] <TB3> INFO: event ID mismatches: 0
[15:26:05.180] <TB3> INFO: ROC errors: 0
[15:26:05.180] <TB3> INFO: missing ROC header(s): 0
[15:26:05.180] <TB3> INFO: misplaced readback start: 0
[15:26:05.180] <TB3> INFO: Pixel decoding errors: 0
[15:26:05.180] <TB3> INFO: pixel data incomplete: 0
[15:26:05.180] <TB3> INFO: pixel address: 0
[15:26:05.180] <TB3> INFO: pulse height fill bit: 0
[15:26:05.180] <TB3> INFO: buffer corruption: 0
[15:26:05.256] <TB3> INFO: ######################################################################
[15:26:05.256] <TB3> INFO: PixTestTrim::doTest()
[15:26:05.256] <TB3> INFO: ######################################################################
[15:26:05.257] <TB3> INFO: ----------------------------------------------------------------------
[15:26:05.257] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[15:26:05.257] <TB3> INFO: ----------------------------------------------------------------------
[15:26:05.338] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[15:26:05.338] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:26:05.347] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:26:05.347] <TB3> INFO: run 1 of 1
[15:26:05.651] <TB3> INFO: Expecting 6281600 events.
[15:26:44.302] <TB3> INFO: 1069460 events read in total (37934ms).
[15:27:22.428] <TB3> INFO: 2128780 events read in total (76060ms).
[15:28:00.159] <TB3> INFO: 3177740 events read in total (113791ms).
[15:28:38.006] <TB3> INFO: 4233690 events read in total (151638ms).
[15:29:16.014] <TB3> INFO: 5292830 events read in total (189646ms).
[15:29:48.356] <TB3> INFO: 6281600 events read in total (221988ms).
[15:29:48.398] <TB3> INFO: Test took 223051ms.
[15:29:48.454] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:07.379] <TB3> INFO: ROC 0 VthrComp = 96
[15:30:07.379] <TB3> INFO: ROC 1 VthrComp = 89
[15:30:07.380] <TB3> INFO: ROC 2 VthrComp = 91
[15:30:07.380] <TB3> INFO: ROC 3 VthrComp = 93
[15:30:07.380] <TB3> INFO: ROC 4 VthrComp = 96
[15:30:07.380] <TB3> INFO: ROC 5 VthrComp = 77
[15:30:07.380] <TB3> INFO: ROC 6 VthrComp = 71
[15:30:07.380] <TB3> INFO: ROC 7 VthrComp = 52
[15:30:07.380] <TB3> INFO: ROC 8 VthrComp = 28
[15:30:07.380] <TB3> INFO: ROC 9 VthrComp = 72
[15:30:07.381] <TB3> INFO: ROC 10 VthrComp = 76
[15:30:07.382] <TB3> INFO: ROC 11 VthrComp = 97
[15:30:07.382] <TB3> INFO: ROC 12 VthrComp = 94
[15:30:07.382] <TB3> INFO: ROC 13 VthrComp = 97
[15:30:07.382] <TB3> INFO: ROC 14 VthrComp = 102
[15:30:07.382] <TB3> INFO: ROC 15 VthrComp = 83
[15:30:07.382] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[15:30:07.382] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:30:07.393] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:30:07.393] <TB3> INFO: run 1 of 1
[15:30:07.732] <TB3> INFO: Expecting 6281600 events.
[15:30:41.280] <TB3> INFO: 757800 events read in total (32831ms).
[15:31:14.625] <TB3> INFO: 1514100 events read in total (66176ms).
[15:31:47.644] <TB3> INFO: 2271150 events read in total (99195ms).
[15:32:20.080] <TB3> INFO: 3028900 events read in total (131631ms).
[15:32:53.468] <TB3> INFO: 3780580 events read in total (165019ms).
[15:33:26.327] <TB3> INFO: 4529620 events read in total (197878ms).
[15:33:58.603] <TB3> INFO: 5276390 events read in total (230154ms).
[15:34:30.334] <TB3> INFO: 6023380 events read in total (261885ms).
[15:34:41.006] <TB3> INFO: 6281600 events read in total (272557ms).
[15:34:41.050] <TB3> INFO: Test took 273657ms.
[15:34:41.175] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:35:04.128] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 57.1735 for pixel 51/66 mean/min/max = 44.6881/32.1857/57.1906
[15:35:04.128] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 59.2802 for pixel 22/0 mean/min/max = 46.8027/34.1609/59.4445
[15:35:04.129] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 55.5192 for pixel 0/75 mean/min/max = 44.7908/33.988/55.5936
[15:35:04.129] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 57.1406 for pixel 17/79 mean/min/max = 45.0297/32.8831/57.1763
[15:35:04.129] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 60.6685 for pixel 21/75 mean/min/max = 46.4264/32.1793/60.6735
[15:35:04.129] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 59.7808 for pixel 0/21 mean/min/max = 47.4492/35.0822/59.8163
[15:35:04.130] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 60.6829 for pixel 10/65 mean/min/max = 46.2912/31.792/60.7904
[15:35:04.130] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 68.2178 for pixel 3/6 mean/min/max = 52.7943/36.8397/68.749
[15:35:04.130] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 139.422 for pixel 10/35 mean/min/max = 118.56/97.0749/140.044
[15:35:04.130] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 70.8954 for pixel 0/67 mean/min/max = 54.0232/37.1368/70.9097
[15:35:04.131] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 60.235 for pixel 6/1 mean/min/max = 48.1666/36.0827/60.2504
[15:35:04.131] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 59.1586 for pixel 4/16 mean/min/max = 45.9942/32.7621/59.2264
[15:35:04.131] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 57.5836 for pixel 6/63 mean/min/max = 45.1853/32.7068/57.6638
[15:35:04.131] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 60.8216 for pixel 24/79 mean/min/max = 46.2197/31.5524/60.8871
[15:35:04.132] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 59.7695 for pixel 23/0 mean/min/max = 46.3519/32.69/60.0138
[15:35:04.132] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 58.1948 for pixel 1/55 mean/min/max = 45.3442/32.4509/58.2376
[15:35:04.132] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:35:04.264] <TB3> INFO: Expecting 514560 events.
[15:35:17.176] <TB3> INFO: 514560 events read in total (12196ms).
[15:35:17.181] <TB3> INFO: Expecting 514560 events.
[15:35:29.914] <TB3> INFO: 514560 events read in total (12156ms).
[15:35:29.921] <TB3> INFO: Expecting 514560 events.
[15:35:42.629] <TB3> INFO: 514560 events read in total (12136ms).
[15:35:42.638] <TB3> INFO: Expecting 514560 events.
[15:35:55.317] <TB3> INFO: 514560 events read in total (12105ms).
[15:35:55.327] <TB3> INFO: Expecting 514560 events.
[15:36:07.977] <TB3> INFO: 514560 events read in total (12077ms).
[15:36:07.991] <TB3> INFO: Expecting 514560 events.
[15:36:20.403] <TB3> INFO: 514560 events read in total (11847ms).
[15:36:20.418] <TB3> INFO: Expecting 514560 events.
[15:36:33.016] <TB3> INFO: 514560 events read in total (12025ms).
[15:36:33.032] <TB3> INFO: Expecting 514560 events.
[15:36:45.619] <TB3> INFO: 514560 events read in total (12021ms).
[15:36:45.635] <TB3> INFO: Expecting 514560 events.
[15:36:58.292] <TB3> INFO: 514560 events read in total (12079ms).
[15:36:58.312] <TB3> INFO: Expecting 514560 events.
[15:37:10.989] <TB3> INFO: 514560 events read in total (12111ms).
[15:37:11.013] <TB3> INFO: Expecting 514560 events.
[15:37:23.724] <TB3> INFO: 514560 events read in total (12146ms).
[15:37:23.745] <TB3> INFO: Expecting 514560 events.
[15:37:36.367] <TB3> INFO: 514560 events read in total (12056ms).
[15:37:36.391] <TB3> INFO: Expecting 514560 events.
[15:37:49.140] <TB3> INFO: 514560 events read in total (12191ms).
[15:37:49.165] <TB3> INFO: Expecting 514560 events.
[15:38:01.829] <TB3> INFO: 514560 events read in total (12105ms).
[15:38:01.859] <TB3> INFO: Expecting 514560 events.
[15:38:14.400] <TB3> INFO: 514560 events read in total (11991ms).
[15:38:14.429] <TB3> INFO: Expecting 514560 events.
[15:38:26.556] <TB3> INFO: 514560 events read in total (11577ms).
[15:38:26.585] <TB3> INFO: Test took 202453ms.
[15:38:27.455] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:38:27.464] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:38:27.464] <TB3> INFO: run 1 of 1
[15:38:27.769] <TB3> INFO: Expecting 6281600 events.
[15:39:01.439] <TB3> INFO: 745070 events read in total (32954ms).
[15:39:33.938] <TB3> INFO: 1488550 events read in total (65453ms).
[15:40:06.343] <TB3> INFO: 2232700 events read in total (97858ms).
[15:40:38.808] <TB3> INFO: 2977060 events read in total (130323ms).
[15:41:11.571] <TB3> INFO: 3714940 events read in total (163086ms).
[15:41:43.706] <TB3> INFO: 4449440 events read in total (195221ms).
[15:42:15.649] <TB3> INFO: 5181560 events read in total (227164ms).
[15:42:47.298] <TB3> INFO: 5912570 events read in total (258813ms).
[15:43:03.429] <TB3> INFO: 6281600 events read in total (274944ms).
[15:43:03.481] <TB3> INFO: Test took 276017ms.
[15:43:03.632] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:26.907] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.057281 .. 255.000000
[15:43:26.983] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[15:43:26.992] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:43:26.992] <TB3> INFO: run 1 of 1
[15:43:27.306] <TB3> INFO: Expecting 10649600 events.
[15:43:59.180] <TB3> INFO: 712140 events read in total (31157ms).
[15:44:30.203] <TB3> INFO: 1424280 events read in total (62180ms).
[15:45:01.739] <TB3> INFO: 2136720 events read in total (93717ms).
[15:45:32.604] <TB3> INFO: 2849460 events read in total (124581ms).
[15:46:04.553] <TB3> INFO: 3562160 events read in total (156530ms).
[15:46:35.909] <TB3> INFO: 4274780 events read in total (187886ms).
[15:47:08.353] <TB3> INFO: 4987630 events read in total (220330ms).
[15:47:39.601] <TB3> INFO: 5700390 events read in total (251578ms).
[15:48:10.681] <TB3> INFO: 6412440 events read in total (282658ms).
[15:48:42.315] <TB3> INFO: 7124300 events read in total (314292ms).
[15:49:15.420] <TB3> INFO: 7836050 events read in total (347397ms).
[15:49:48.979] <TB3> INFO: 8547280 events read in total (380956ms).
[15:50:21.945] <TB3> INFO: 9258580 events read in total (413922ms).
[15:50:55.811] <TB3> INFO: 9970060 events read in total (447789ms).
[15:51:26.972] <TB3> INFO: 10649600 events read in total (478949ms).
[15:51:27.076] <TB3> INFO: Test took 480084ms.
[15:51:27.367] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:57.286] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 16.938862 .. 58.154054
[15:51:57.365] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 6 .. 68 (-1/-1) hits flags = 528 (plus default)
[15:51:57.373] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:51:57.373] <TB3> INFO: run 1 of 1
[15:51:57.717] <TB3> INFO: Expecting 2620800 events.
[15:52:32.689] <TB3> INFO: 855510 events read in total (34256ms).
[15:53:07.630] <TB3> INFO: 1711540 events read in total (69197ms).
[15:53:40.661] <TB3> INFO: 2567740 events read in total (102229ms).
[15:53:43.205] <TB3> INFO: 2620800 events read in total (104772ms).
[15:53:43.222] <TB3> INFO: Test took 105848ms.
[15:53:43.269] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:58.367] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 1.988368 .. 48.183109
[15:53:58.473] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 1 .. 58 (-1/-1) hits flags = 528 (plus default)
[15:53:58.482] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:53:58.482] <TB3> INFO: run 1 of 1
[15:53:58.790] <TB3> INFO: Expecting 2412800 events.
[15:54:35.768] <TB3> INFO: 938390 events read in total (36262ms).
[15:55:10.089] <TB3> INFO: 1875900 events read in total (70583ms).
[15:55:30.187] <TB3> INFO: 2412800 events read in total (90681ms).
[15:55:30.210] <TB3> INFO: Test took 91728ms.
[15:55:30.247] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:44.621] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 1.988368 .. 48.183109
[15:55:44.696] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 1 .. 58 (-1/-1) hits flags = 528 (plus default)
[15:55:44.704] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:55:44.704] <TB3> INFO: run 1 of 1
[15:55:45.008] <TB3> INFO: Expecting 2412800 events.
[15:56:21.866] <TB3> INFO: 937350 events read in total (36142ms).
[15:56:57.238] <TB3> INFO: 1874940 events read in total (71514ms).
[15:57:19.180] <TB3> INFO: 2412800 events read in total (93456ms).
[15:57:19.194] <TB3> INFO: Test took 94491ms.
[15:57:19.230] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:57:33.686] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[15:57:33.686] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[15:57:33.695] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:57:33.695] <TB3> INFO: run 1 of 1
[15:57:33.998] <TB3> INFO: Expecting 1705600 events.
[15:58:10.905] <TB3> INFO: 877460 events read in total (36191ms).
[15:58:45.794] <TB3> INFO: 1705600 events read in total (71080ms).
[15:58:45.815] <TB3> INFO: Test took 72121ms.
[15:58:45.849] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:59:01.148] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C0.dat
[15:59:01.148] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C1.dat
[15:59:01.148] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C2.dat
[15:59:01.148] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C3.dat
[15:59:01.148] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C4.dat
[15:59:01.148] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C5.dat
[15:59:01.148] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C6.dat
[15:59:01.148] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C7.dat
[15:59:01.149] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C8.dat
[15:59:01.149] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C9.dat
[15:59:01.150] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C10.dat
[15:59:01.150] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C11.dat
[15:59:01.150] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C12.dat
[15:59:01.150] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C13.dat
[15:59:01.150] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C14.dat
[15:59:01.150] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C15.dat
[15:59:01.151] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//trimParameters35_C0.dat
[15:59:01.159] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//trimParameters35_C1.dat
[15:59:01.169] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//trimParameters35_C2.dat
[15:59:01.177] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//trimParameters35_C3.dat
[15:59:01.186] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//trimParameters35_C4.dat
[15:59:01.194] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//trimParameters35_C5.dat
[15:59:01.202] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//trimParameters35_C6.dat
[15:59:01.213] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//trimParameters35_C7.dat
[15:59:01.220] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//trimParameters35_C8.dat
[15:59:01.231] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//trimParameters35_C9.dat
[15:59:01.241] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//trimParameters35_C10.dat
[15:59:01.252] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//trimParameters35_C11.dat
[15:59:01.263] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//trimParameters35_C12.dat
[15:59:01.273] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//trimParameters35_C13.dat
[15:59:01.283] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//trimParameters35_C14.dat
[15:59:01.292] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//trimParameters35_C15.dat
[15:59:01.301] <TB3> INFO: PixTestTrim::trimTest() done
[15:59:01.301] <TB3> INFO: vtrim: 85 93 88 93 94 90 83 86 154 101 92 102 96 96 113 95
[15:59:01.301] <TB3> INFO: vthrcomp: 96 89 91 93 96 77 71 52 28 72 76 97 94 97 102 83
[15:59:01.301] <TB3> INFO: vcal mean: 34.96 34.99 35.03 34.95 34.95 35.02 34.99 35.04 35.16 34.94 35.02 34.94 34.92 34.96 34.95 34.98
[15:59:01.301] <TB3> INFO: vcal RMS: 0.85 0.84 0.78 0.78 1.23 0.99 3.58 1.12 1.91 1.32 0.87 0.88 0.83 0.89 0.85 0.86
[15:59:01.301] <TB3> INFO: bits mean: 9.36 9.02 9.28 9.16 9.04 7.94 9.47 8.38 2.65 7.63 8.60 9.69 9.14 9.24 9.59 9.68
[15:59:01.301] <TB3> INFO: bits RMS: 2.84 2.58 2.59 2.78 2.79 2.79 2.66 2.07 1.05 2.24 2.37 2.51 2.79 2.86 2.55 2.61
[15:59:01.310] <TB3> INFO: ----------------------------------------------------------------------
[15:59:01.310] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[15:59:01.310] <TB3> INFO: ----------------------------------------------------------------------
[15:59:01.313] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[15:59:01.325] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[15:59:01.325] <TB3> INFO: run 1 of 1
[15:59:01.650] <TB3> INFO: Expecting 8320000 events.
[15:59:38.743] <TB3> INFO: 914840 events read in total (36377ms).
[16:00:14.386] <TB3> INFO: 1822140 events read in total (72020ms).
[16:00:49.998] <TB3> INFO: 2726880 events read in total (107632ms).
[16:01:24.980] <TB3> INFO: 3627810 events read in total (142614ms).
[16:02:00.800] <TB3> INFO: 4523720 events read in total (178434ms).
[16:02:36.368] <TB3> INFO: 5413490 events read in total (214002ms).
[16:03:11.462] <TB3> INFO: 6302240 events read in total (249096ms).
[16:03:45.277] <TB3> INFO: 7188370 events read in total (282911ms).
[16:04:18.201] <TB3> INFO: 8075560 events read in total (315835ms).
[16:04:27.368] <TB3> INFO: 8320000 events read in total (325002ms).
[16:04:27.414] <TB3> INFO: Test took 326089ms.
[16:04:27.531] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:52.707] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 170 (-1/-1) hits flags = 528 (plus default)
[16:04:52.715] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:04:52.715] <TB3> INFO: run 1 of 1
[16:04:53.023] <TB3> INFO: Expecting 7113600 events.
[16:05:29.681] <TB3> INFO: 932020 events read in total (35941ms).
[16:06:05.644] <TB3> INFO: 1856510 events read in total (71904ms).
[16:06:41.679] <TB3> INFO: 2777030 events read in total (107939ms).
[16:07:17.011] <TB3> INFO: 3693240 events read in total (143271ms).
[16:07:52.481] <TB3> INFO: 4599570 events read in total (178741ms).
[16:08:26.311] <TB3> INFO: 5505600 events read in total (212571ms).
[16:09:02.063] <TB3> INFO: 6407130 events read in total (248323ms).
[16:09:27.558] <TB3> INFO: 7113600 events read in total (273818ms).
[16:09:27.595] <TB3> INFO: Test took 274880ms.
[16:09:27.682] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:09:50.990] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 156 (-1/-1) hits flags = 528 (plus default)
[16:09:50.999] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:09:50.999] <TB3> INFO: run 1 of 1
[16:09:51.302] <TB3> INFO: Expecting 6531200 events.
[16:10:28.521] <TB3> INFO: 966010 events read in total (36503ms).
[16:11:04.627] <TB3> INFO: 1922910 events read in total (72609ms).
[16:11:40.516] <TB3> INFO: 2875450 events read in total (108499ms).
[16:12:16.126] <TB3> INFO: 3817690 events read in total (144108ms).
[16:12:51.107] <TB3> INFO: 4754080 events read in total (179089ms).
[16:13:25.553] <TB3> INFO: 5687190 events read in total (213535ms).
[16:13:57.372] <TB3> INFO: 6531200 events read in total (245354ms).
[16:13:57.405] <TB3> INFO: Test took 246406ms.
[16:13:57.481] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:14:19.290] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 156 (-1/-1) hits flags = 528 (plus default)
[16:14:19.300] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:14:19.300] <TB3> INFO: run 1 of 1
[16:14:19.609] <TB3> INFO: Expecting 6531200 events.
[16:14:56.895] <TB3> INFO: 965450 events read in total (36570ms).
[16:15:33.214] <TB3> INFO: 1922060 events read in total (72889ms).
[16:16:09.562] <TB3> INFO: 2873910 events read in total (109237ms).
[16:16:45.080] <TB3> INFO: 3815630 events read in total (144755ms).
[16:17:20.083] <TB3> INFO: 4751970 events read in total (179759ms).
[16:17:54.351] <TB3> INFO: 5684340 events read in total (214026ms).
[16:18:25.767] <TB3> INFO: 6531200 events read in total (245442ms).
[16:18:25.810] <TB3> INFO: Test took 246510ms.
[16:18:25.893] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:18:48.448] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 156 (-1/-1) hits flags = 528 (plus default)
[16:18:48.457] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[16:18:48.457] <TB3> INFO: run 1 of 1
[16:18:48.763] <TB3> INFO: Expecting 6531200 events.
[16:19:27.379] <TB3> INFO: 965030 events read in total (37900ms).
[16:20:03.737] <TB3> INFO: 1921130 events read in total (74258ms).
[16:20:40.097] <TB3> INFO: 2872550 events read in total (110619ms).
[16:21:17.335] <TB3> INFO: 3813600 events read in total (147856ms).
[16:21:54.445] <TB3> INFO: 4749140 events read in total (184966ms).
[16:22:29.107] <TB3> INFO: 5681380 events read in total (219628ms).
[16:23:03.018] <TB3> INFO: 6531200 events read in total (253539ms).
[16:23:03.055] <TB3> INFO: Test took 254598ms.
[16:23:03.126] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:25.889] <TB3> INFO: PixTestTrim::trimBitTest() done
[16:23:25.890] <TB3> INFO: PixTestTrim::doTest() done, duration: 3440 seconds
[16:23:25.890] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:25.890] <TB3> INFO: Decoding statistics:
[16:23:25.890] <TB3> INFO: General information:
[16:23:25.890] <TB3> INFO: 16bit words read: 0
[16:23:25.890] <TB3> INFO: valid events total: 0
[16:23:25.890] <TB3> INFO: empty events: 0
[16:23:25.890] <TB3> INFO: valid events with pixels: 0
[16:23:25.890] <TB3> INFO: valid pixel hits: 0
[16:23:25.890] <TB3> INFO: Event errors: 0
[16:23:25.890] <TB3> INFO: start marker: 0
[16:23:25.890] <TB3> INFO: stop marker: 0
[16:23:25.890] <TB3> INFO: overflow: 0
[16:23:25.890] <TB3> INFO: invalid 5bit words: 0
[16:23:25.890] <TB3> INFO: invalid XOR eye diagram: 0
[16:23:25.890] <TB3> INFO: TBM errors: 0
[16:23:25.890] <TB3> INFO: flawed TBM headers: 0
[16:23:25.890] <TB3> INFO: flawed TBM trailers: 0
[16:23:25.890] <TB3> INFO: event ID mismatches: 0
[16:23:25.890] <TB3> INFO: ROC errors: 0
[16:23:25.890] <TB3> INFO: missing ROC header(s): 0
[16:23:25.890] <TB3> INFO: misplaced readback start: 0
[16:23:25.890] <TB3> INFO: Pixel decoding errors: 0
[16:23:25.890] <TB3> INFO: pixel data incomplete: 0
[16:23:25.890] <TB3> INFO: pixel address: 0
[16:23:25.890] <TB3> INFO: pulse height fill bit: 0
[16:23:25.890] <TB3> INFO: buffer corruption: 0
[16:23:26.557] <TB3> INFO: ######################################################################
[16:23:26.557] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[16:23:26.557] <TB3> INFO: ######################################################################
[16:23:26.906] <TB3> INFO: Expecting 41600 events.
[16:23:31.501] <TB3> INFO: 41600 events read in total (3878ms).
[16:23:31.502] <TB3> INFO: Test took 4944ms.
[16:23:31.508] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:32.076] <TB3> INFO: Expecting 41600 events.
[16:23:36.993] <TB3> INFO: 41600 events read in total (4200ms).
[16:23:36.993] <TB3> INFO: Test took 5219ms.
[16:23:36.999] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:37.362] <TB3> INFO: Expecting 41600 events.
[16:23:42.369] <TB3> INFO: 41600 events read in total (4291ms).
[16:23:42.369] <TB3> INFO: Test took 5341ms.
[16:23:42.376] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:42.721] <TB3> INFO: Expecting 2560 events.
[16:23:43.789] <TB3> INFO: 2560 events read in total (351ms).
[16:23:43.790] <TB3> INFO: Test took 1405ms.
[16:23:44.204] <TB3> INFO: Expecting 2560 events.
[16:23:45.272] <TB3> INFO: 2560 events read in total (351ms).
[16:23:45.272] <TB3> INFO: Test took 1482ms.
[16:23:45.687] <TB3> INFO: Expecting 2560 events.
[16:23:46.756] <TB3> INFO: 2560 events read in total (353ms).
[16:23:46.757] <TB3> INFO: Test took 1484ms.
[16:23:47.172] <TB3> INFO: Expecting 2560 events.
[16:23:48.240] <TB3> INFO: 2560 events read in total (352ms).
[16:23:48.240] <TB3> INFO: Test took 1483ms.
[16:23:48.655] <TB3> INFO: Expecting 2560 events.
[16:23:49.724] <TB3> INFO: 2560 events read in total (352ms).
[16:23:49.725] <TB3> INFO: Test took 1484ms.
[16:23:50.140] <TB3> INFO: Expecting 2560 events.
[16:23:51.208] <TB3> INFO: 2560 events read in total (351ms).
[16:23:51.208] <TB3> INFO: Test took 1483ms.
[16:23:51.623] <TB3> INFO: Expecting 2560 events.
[16:23:52.707] <TB3> INFO: 2560 events read in total (368ms).
[16:23:52.707] <TB3> INFO: Test took 1499ms.
[16:23:53.122] <TB3> INFO: Expecting 2560 events.
[16:23:54.191] <TB3> INFO: 2560 events read in total (352ms).
[16:23:54.191] <TB3> INFO: Test took 1483ms.
[16:23:54.606] <TB3> INFO: Expecting 2560 events.
[16:23:55.673] <TB3> INFO: 2560 events read in total (351ms).
[16:23:55.674] <TB3> INFO: Test took 1482ms.
[16:23:56.088] <TB3> INFO: Expecting 2560 events.
[16:23:57.157] <TB3> INFO: 2560 events read in total (352ms).
[16:23:57.157] <TB3> INFO: Test took 1483ms.
[16:23:57.572] <TB3> INFO: Expecting 2560 events.
[16:23:58.656] <TB3> INFO: 2560 events read in total (368ms).
[16:23:58.657] <TB3> INFO: Test took 1499ms.
[16:23:59.072] <TB3> INFO: Expecting 2560 events.
[16:24:00.139] <TB3> INFO: 2560 events read in total (351ms).
[16:24:00.139] <TB3> INFO: Test took 1473ms.
[16:24:00.554] <TB3> INFO: Expecting 2560 events.
[16:24:01.622] <TB3> INFO: 2560 events read in total (352ms).
[16:24:01.622] <TB3> INFO: Test took 1482ms.
[16:24:02.037] <TB3> INFO: Expecting 2560 events.
[16:24:03.106] <TB3> INFO: 2560 events read in total (353ms).
[16:24:03.106] <TB3> INFO: Test took 1483ms.
[16:24:03.521] <TB3> INFO: Expecting 2560 events.
[16:24:04.589] <TB3> INFO: 2560 events read in total (351ms).
[16:24:04.589] <TB3> INFO: Test took 1482ms.
[16:24:05.004] <TB3> INFO: Expecting 2560 events.
[16:24:06.071] <TB3> INFO: 2560 events read in total (351ms).
[16:24:06.072] <TB3> INFO: Test took 1483ms.
[16:24:06.076] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:24:06.488] <TB3> INFO: Expecting 655360 events.
[16:24:23.365] <TB3> INFO: 655360 events read in total (16160ms).
[16:24:23.378] <TB3> INFO: Expecting 655360 events.
[16:24:39.895] <TB3> INFO: 655360 events read in total (15990ms).
[16:24:39.910] <TB3> INFO: Expecting 655360 events.
[16:24:56.498] <TB3> INFO: 655360 events read in total (16060ms).
[16:24:56.518] <TB3> INFO: Expecting 655360 events.
[16:25:13.049] <TB3> INFO: 655360 events read in total (16004ms).
[16:25:13.069] <TB3> INFO: Expecting 655360 events.
[16:25:29.650] <TB3> INFO: 655360 events read in total (16054ms).
[16:25:29.679] <TB3> INFO: Expecting 655360 events.
[16:25:46.379] <TB3> INFO: 655360 events read in total (16172ms).
[16:25:46.411] <TB3> INFO: Expecting 655360 events.
[16:26:03.119] <TB3> INFO: 655360 events read in total (16180ms).
[16:26:03.150] <TB3> INFO: Expecting 655360 events.
[16:26:19.558] <TB3> INFO: 655360 events read in total (15880ms).
[16:26:19.590] <TB3> INFO: Expecting 655360 events.
[16:26:36.327] <TB3> INFO: 655360 events read in total (16209ms).
[16:26:36.372] <TB3> INFO: Expecting 655360 events.
[16:26:52.845] <TB3> INFO: 655360 events read in total (15945ms).
[16:26:52.883] <TB3> INFO: Expecting 655360 events.
[16:27:09.324] <TB3> INFO: 655360 events read in total (15914ms).
[16:27:09.369] <TB3> INFO: Expecting 655360 events.
[16:27:26.086] <TB3> INFO: 655360 events read in total (16189ms).
[16:27:26.143] <TB3> INFO: Expecting 655360 events.
[16:27:42.493] <TB3> INFO: 655360 events read in total (15822ms).
[16:27:42.541] <TB3> INFO: Expecting 655360 events.
[16:27:59.269] <TB3> INFO: 655360 events read in total (16201ms).
[16:27:59.336] <TB3> INFO: Expecting 655360 events.
[16:28:15.863] <TB3> INFO: 655360 events read in total (15999ms).
[16:28:15.923] <TB3> INFO: Expecting 655360 events.
[16:28:32.659] <TB3> INFO: 655360 events read in total (16208ms).
[16:28:32.721] <TB3> INFO: Test took 266645ms.
[16:28:32.816] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:28:33.123] <TB3> INFO: Expecting 655360 events.
[16:28:49.899] <TB3> INFO: 655360 events read in total (16060ms).
[16:28:49.907] <TB3> INFO: Expecting 655360 events.
[16:29:06.468] <TB3> INFO: 655360 events read in total (16033ms).
[16:29:06.480] <TB3> INFO: Expecting 655360 events.
[16:29:23.005] <TB3> INFO: 655360 events read in total (15997ms).
[16:29:23.020] <TB3> INFO: Expecting 655360 events.
[16:29:39.400] <TB3> INFO: 655360 events read in total (15853ms).
[16:29:39.419] <TB3> INFO: Expecting 655360 events.
[16:29:55.813] <TB3> INFO: 655360 events read in total (15866ms).
[16:29:55.837] <TB3> INFO: Expecting 655360 events.
[16:30:12.154] <TB3> INFO: 655360 events read in total (15789ms).
[16:30:12.179] <TB3> INFO: Expecting 655360 events.
[16:30:28.609] <TB3> INFO: 655360 events read in total (15902ms).
[16:30:28.645] <TB3> INFO: Expecting 655360 events.
[16:30:44.928] <TB3> INFO: 655360 events read in total (15755ms).
[16:30:44.969] <TB3> INFO: Expecting 655360 events.
[16:31:01.081] <TB3> INFO: 655360 events read in total (15584ms).
[16:31:01.118] <TB3> INFO: Expecting 655360 events.
[16:31:17.519] <TB3> INFO: 655360 events read in total (15874ms).
[16:31:17.557] <TB3> INFO: Expecting 655360 events.
[16:31:34.035] <TB3> INFO: 655360 events read in total (15950ms).
[16:31:34.089] <TB3> INFO: Expecting 655360 events.
[16:31:50.564] <TB3> INFO: 655360 events read in total (15947ms).
[16:31:50.616] <TB3> INFO: Expecting 655360 events.
[16:32:07.016] <TB3> INFO: 655360 events read in total (15872ms).
[16:32:07.069] <TB3> INFO: Expecting 655360 events.
[16:32:23.510] <TB3> INFO: 655360 events read in total (15914ms).
[16:32:23.562] <TB3> INFO: Expecting 655360 events.
[16:32:39.953] <TB3> INFO: 655360 events read in total (15863ms).
[16:32:40.017] <TB3> INFO: Expecting 655360 events.
[16:32:56.423] <TB3> INFO: 655360 events read in total (15879ms).
[16:32:56.484] <TB3> INFO: Test took 263669ms.
[16:32:56.681] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:56.688] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:56.695] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:56.702] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:56.709] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:56.716] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:56.723] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:56.730] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:56.737] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:56.744] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:56.751] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:56.758] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:56.765] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:32:56.772] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:56.779] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:56.786] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:56.793] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[16:32:56.800] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[16:32:56.807] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[16:32:56.814] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[16:32:56.820] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[16:32:56.827] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[16:32:56.834] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[16:32:56.841] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[16:32:56.848] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[16:32:56.889] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:32:56.890] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:32:56.890] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:32:56.890] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:32:56.891] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:32:56.891] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:32:56.891] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:32:56.891] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:32:56.891] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:32:56.892] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:32:56.892] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:32:56.892] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:32:56.892] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:32:56.893] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:32:56.893] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:32:56.893] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:32:57.206] <TB3> INFO: Expecting 41600 events.
[16:33:01.699] <TB3> INFO: 41600 events read in total (3777ms).
[16:33:01.699] <TB3> INFO: Test took 4803ms.
[16:33:02.285] <TB3> INFO: Expecting 41600 events.
[16:33:06.787] <TB3> INFO: 41600 events read in total (3785ms).
[16:33:06.788] <TB3> INFO: Test took 4851ms.
[16:33:07.342] <TB3> INFO: Expecting 41600 events.
[16:33:11.788] <TB3> INFO: 41600 events read in total (3730ms).
[16:33:11.789] <TB3> INFO: Test took 4772ms.
[16:33:12.027] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:12.159] <TB3> INFO: Expecting 2560 events.
[16:33:13.230] <TB3> INFO: 2560 events read in total (354ms).
[16:33:13.231] <TB3> INFO: Test took 1205ms.
[16:33:13.233] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:13.646] <TB3> INFO: Expecting 2560 events.
[16:33:14.714] <TB3> INFO: 2560 events read in total (351ms).
[16:33:14.715] <TB3> INFO: Test took 1482ms.
[16:33:14.717] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:15.130] <TB3> INFO: Expecting 2560 events.
[16:33:16.193] <TB3> INFO: 2560 events read in total (347ms).
[16:33:16.193] <TB3> INFO: Test took 1476ms.
[16:33:16.195] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:16.609] <TB3> INFO: Expecting 2560 events.
[16:33:17.679] <TB3> INFO: 2560 events read in total (354ms).
[16:33:17.679] <TB3> INFO: Test took 1484ms.
[16:33:17.682] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:18.094] <TB3> INFO: Expecting 2560 events.
[16:33:19.166] <TB3> INFO: 2560 events read in total (356ms).
[16:33:19.166] <TB3> INFO: Test took 1484ms.
[16:33:19.170] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:19.582] <TB3> INFO: Expecting 2560 events.
[16:33:20.650] <TB3> INFO: 2560 events read in total (352ms).
[16:33:20.651] <TB3> INFO: Test took 1481ms.
[16:33:20.653] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:21.065] <TB3> INFO: Expecting 2560 events.
[16:33:22.133] <TB3> INFO: 2560 events read in total (352ms).
[16:33:22.134] <TB3> INFO: Test took 1481ms.
[16:33:22.136] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:22.549] <TB3> INFO: Expecting 2560 events.
[16:33:23.621] <TB3> INFO: 2560 events read in total (355ms).
[16:33:23.622] <TB3> INFO: Test took 1486ms.
[16:33:23.624] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:24.038] <TB3> INFO: Expecting 2560 events.
[16:33:25.109] <TB3> INFO: 2560 events read in total (355ms).
[16:33:25.110] <TB3> INFO: Test took 1486ms.
[16:33:25.113] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:25.526] <TB3> INFO: Expecting 2560 events.
[16:33:26.597] <TB3> INFO: 2560 events read in total (355ms).
[16:33:26.597] <TB3> INFO: Test took 1484ms.
[16:33:26.600] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:27.013] <TB3> INFO: Expecting 2560 events.
[16:33:28.085] <TB3> INFO: 2560 events read in total (356ms).
[16:33:28.086] <TB3> INFO: Test took 1486ms.
[16:33:28.089] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:28.502] <TB3> INFO: Expecting 2560 events.
[16:33:29.572] <TB3> INFO: 2560 events read in total (354ms).
[16:33:29.573] <TB3> INFO: Test took 1484ms.
[16:33:29.576] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:29.988] <TB3> INFO: Expecting 2560 events.
[16:33:31.057] <TB3> INFO: 2560 events read in total (352ms).
[16:33:31.058] <TB3> INFO: Test took 1482ms.
[16:33:31.060] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:31.474] <TB3> INFO: Expecting 2560 events.
[16:33:32.545] <TB3> INFO: 2560 events read in total (355ms).
[16:33:32.546] <TB3> INFO: Test took 1486ms.
[16:33:32.549] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:32.961] <TB3> INFO: Expecting 2560 events.
[16:33:34.034] <TB3> INFO: 2560 events read in total (356ms).
[16:33:34.034] <TB3> INFO: Test took 1486ms.
[16:33:34.037] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:34.450] <TB3> INFO: Expecting 2560 events.
[16:33:35.521] <TB3> INFO: 2560 events read in total (355ms).
[16:33:35.521] <TB3> INFO: Test took 1484ms.
[16:33:35.524] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:35.937] <TB3> INFO: Expecting 2560 events.
[16:33:37.008] <TB3> INFO: 2560 events read in total (354ms).
[16:33:37.008] <TB3> INFO: Test took 1484ms.
[16:33:37.013] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:37.424] <TB3> INFO: Expecting 2560 events.
[16:33:38.511] <TB3> INFO: 2560 events read in total (370ms).
[16:33:38.511] <TB3> INFO: Test took 1498ms.
[16:33:38.514] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:38.927] <TB3> INFO: Expecting 2560 events.
[16:33:39.997] <TB3> INFO: 2560 events read in total (354ms).
[16:33:39.997] <TB3> INFO: Test took 1483ms.
[16:33:40.000] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:40.413] <TB3> INFO: Expecting 2560 events.
[16:33:41.482] <TB3> INFO: 2560 events read in total (353ms).
[16:33:41.483] <TB3> INFO: Test took 1483ms.
[16:33:41.485] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:41.899] <TB3> INFO: Expecting 2560 events.
[16:33:42.969] <TB3> INFO: 2560 events read in total (354ms).
[16:33:42.969] <TB3> INFO: Test took 1484ms.
[16:33:42.974] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:43.385] <TB3> INFO: Expecting 2560 events.
[16:33:44.449] <TB3> INFO: 2560 events read in total (348ms).
[16:33:44.449] <TB3> INFO: Test took 1475ms.
[16:33:44.452] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:44.865] <TB3> INFO: Expecting 2560 events.
[16:33:45.936] <TB3> INFO: 2560 events read in total (354ms).
[16:33:45.936] <TB3> INFO: Test took 1484ms.
[16:33:45.939] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:46.353] <TB3> INFO: Expecting 2560 events.
[16:33:47.425] <TB3> INFO: 2560 events read in total (356ms).
[16:33:47.425] <TB3> INFO: Test took 1486ms.
[16:33:47.428] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:47.841] <TB3> INFO: Expecting 2560 events.
[16:33:48.906] <TB3> INFO: 2560 events read in total (348ms).
[16:33:48.906] <TB3> INFO: Test took 1478ms.
[16:33:48.908] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:49.322] <TB3> INFO: Expecting 2560 events.
[16:33:50.393] <TB3> INFO: 2560 events read in total (354ms).
[16:33:50.394] <TB3> INFO: Test took 1486ms.
[16:33:50.397] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:50.810] <TB3> INFO: Expecting 2560 events.
[16:33:51.879] <TB3> INFO: 2560 events read in total (353ms).
[16:33:51.879] <TB3> INFO: Test took 1483ms.
[16:33:51.882] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:52.295] <TB3> INFO: Expecting 2560 events.
[16:33:53.362] <TB3> INFO: 2560 events read in total (350ms).
[16:33:53.362] <TB3> INFO: Test took 1480ms.
[16:33:53.365] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:53.778] <TB3> INFO: Expecting 2560 events.
[16:33:54.846] <TB3> INFO: 2560 events read in total (352ms).
[16:33:54.846] <TB3> INFO: Test took 1481ms.
[16:33:54.849] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:55.262] <TB3> INFO: Expecting 2560 events.
[16:33:56.330] <TB3> INFO: 2560 events read in total (352ms).
[16:33:56.330] <TB3> INFO: Test took 1481ms.
[16:33:56.335] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:56.746] <TB3> INFO: Expecting 2560 events.
[16:33:57.813] <TB3> INFO: 2560 events read in total (350ms).
[16:33:57.814] <TB3> INFO: Test took 1480ms.
[16:33:57.815] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:33:58.230] <TB3> INFO: Expecting 2560 events.
[16:33:59.299] <TB3> INFO: 2560 events read in total (352ms).
[16:33:59.299] <TB3> INFO: Test took 1484ms.
[16:33:59.922] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 633 seconds
[16:33:59.922] <TB3> INFO: PH scale (per ROC): 65 74 77 78 74 74 77 67 66 68 71 80 76 65 80 76
[16:33:59.923] <TB3> INFO: PH offset (per ROC): 182 173 174 171 175 170 184 179 173 172 172 184 167 178 176 170
[16:33:59.932] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:33:59.932] <TB3> INFO: Decoding statistics:
[16:33:59.932] <TB3> INFO: General information:
[16:33:59.932] <TB3> INFO: 16bit words read: 91932
[16:33:59.932] <TB3> INFO: valid events total: 10240
[16:33:59.932] <TB3> INFO: empty events: 7737
[16:33:59.932] <TB3> INFO: valid events with pixels: 2503
[16:33:59.932] <TB3> INFO: valid pixel hits: 2503
[16:33:59.932] <TB3> INFO: Event errors: 0
[16:33:59.932] <TB3> INFO: start marker: 0
[16:33:59.932] <TB3> INFO: stop marker: 0
[16:33:59.932] <TB3> INFO: overflow: 0
[16:33:59.932] <TB3> INFO: invalid 5bit words: 0
[16:33:59.932] <TB3> INFO: invalid XOR eye diagram: 0
[16:33:59.933] <TB3> INFO: TBM errors: 0
[16:33:59.933] <TB3> INFO: flawed TBM headers: 0
[16:33:59.933] <TB3> INFO: flawed TBM trailers: 0
[16:33:59.933] <TB3> INFO: event ID mismatches: 0
[16:33:59.933] <TB3> INFO: ROC errors: 0
[16:33:59.933] <TB3> INFO: missing ROC header(s): 0
[16:33:59.933] <TB3> INFO: misplaced readback start: 0
[16:33:59.933] <TB3> INFO: Pixel decoding errors: 0
[16:33:59.933] <TB3> INFO: pixel data incomplete: 0
[16:33:59.933] <TB3> INFO: pixel address: 0
[16:33:59.933] <TB3> INFO: pulse height fill bit: 0
[16:33:59.933] <TB3> INFO: buffer corruption: 0
[16:34:00.154] <TB3> INFO: ######################################################################
[16:34:00.154] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[16:34:00.154] <TB3> INFO: ######################################################################
[16:34:00.165] <TB3> INFO: scanning low vcal = 10
[16:34:00.509] <TB3> INFO: Expecting 41600 events.
[16:34:04.112] <TB3> INFO: 41600 events read in total (2886ms).
[16:34:04.112] <TB3> INFO: Test took 3947ms.
[16:34:04.114] <TB3> INFO: scanning low vcal = 20
[16:34:04.526] <TB3> INFO: Expecting 41600 events.
[16:34:08.175] <TB3> INFO: 41600 events read in total (2932ms).
[16:34:08.175] <TB3> INFO: Test took 4061ms.
[16:34:08.177] <TB3> INFO: scanning low vcal = 30
[16:34:08.590] <TB3> INFO: Expecting 41600 events.
[16:34:12.199] <TB3> INFO: 41600 events read in total (2893ms).
[16:34:12.200] <TB3> INFO: Test took 4023ms.
[16:34:12.202] <TB3> INFO: scanning low vcal = 40
[16:34:12.606] <TB3> INFO: Expecting 41600 events.
[16:34:16.751] <TB3> INFO: 41600 events read in total (3428ms).
[16:34:16.752] <TB3> INFO: Test took 4550ms.
[16:34:16.755] <TB3> INFO: scanning low vcal = 50
[16:34:17.106] <TB3> INFO: Expecting 41600 events.
[16:34:21.271] <TB3> INFO: 41600 events read in total (3449ms).
[16:34:21.272] <TB3> INFO: Test took 4517ms.
[16:34:21.275] <TB3> INFO: scanning low vcal = 60
[16:34:21.630] <TB3> INFO: Expecting 41600 events.
[16:34:25.793] <TB3> INFO: 41600 events read in total (3447ms).
[16:34:25.794] <TB3> INFO: Test took 4519ms.
[16:34:25.797] <TB3> INFO: scanning low vcal = 70
[16:34:26.143] <TB3> INFO: Expecting 41600 events.
[16:34:30.351] <TB3> INFO: 41600 events read in total (3492ms).
[16:34:30.351] <TB3> INFO: Test took 4554ms.
[16:34:30.354] <TB3> INFO: scanning low vcal = 80
[16:34:30.702] <TB3> INFO: Expecting 41600 events.
[16:34:34.916] <TB3> INFO: 41600 events read in total (3497ms).
[16:34:34.916] <TB3> INFO: Test took 4562ms.
[16:34:34.920] <TB3> INFO: scanning low vcal = 90
[16:34:35.240] <TB3> INFO: Expecting 41600 events.
[16:34:39.582] <TB3> INFO: 41600 events read in total (3625ms).
[16:34:39.582] <TB3> INFO: Test took 4662ms.
[16:34:39.585] <TB3> INFO: scanning low vcal = 100
[16:34:39.940] <TB3> INFO: Expecting 41600 events.
[16:34:44.140] <TB3> INFO: 41600 events read in total (3484ms).
[16:34:44.141] <TB3> INFO: Test took 4556ms.
[16:34:44.143] <TB3> INFO: scanning low vcal = 110
[16:34:44.495] <TB3> INFO: Expecting 41600 events.
[16:34:48.713] <TB3> INFO: 41600 events read in total (3501ms).
[16:34:48.713] <TB3> INFO: Test took 4570ms.
[16:34:48.716] <TB3> INFO: scanning low vcal = 120
[16:34:49.052] <TB3> INFO: Expecting 41600 events.
[16:34:53.289] <TB3> INFO: 41600 events read in total (3521ms).
[16:34:53.290] <TB3> INFO: Test took 4574ms.
[16:34:53.293] <TB3> INFO: scanning low vcal = 130
[16:34:53.623] <TB3> INFO: Expecting 41600 events.
[16:34:57.830] <TB3> INFO: 41600 events read in total (3491ms).
[16:34:57.831] <TB3> INFO: Test took 4538ms.
[16:34:57.834] <TB3> INFO: scanning low vcal = 140
[16:34:58.165] <TB3> INFO: Expecting 41600 events.
[16:35:02.362] <TB3> INFO: 41600 events read in total (3481ms).
[16:35:02.363] <TB3> INFO: Test took 4529ms.
[16:35:02.366] <TB3> INFO: scanning low vcal = 150
[16:35:02.710] <TB3> INFO: Expecting 41600 events.
[16:35:06.949] <TB3> INFO: 41600 events read in total (3523ms).
[16:35:06.950] <TB3> INFO: Test took 4584ms.
[16:35:06.953] <TB3> INFO: scanning low vcal = 160
[16:35:07.287] <TB3> INFO: Expecting 41600 events.
[16:35:11.514] <TB3> INFO: 41600 events read in total (3510ms).
[16:35:11.514] <TB3> INFO: Test took 4561ms.
[16:35:11.517] <TB3> INFO: scanning low vcal = 170
[16:35:11.859] <TB3> INFO: Expecting 41600 events.
[16:35:16.044] <TB3> INFO: 41600 events read in total (3468ms).
[16:35:16.044] <TB3> INFO: Test took 4527ms.
[16:35:16.048] <TB3> INFO: scanning low vcal = 180
[16:35:16.397] <TB3> INFO: Expecting 41600 events.
[16:35:20.589] <TB3> INFO: 41600 events read in total (3475ms).
[16:35:20.590] <TB3> INFO: Test took 4542ms.
[16:35:20.592] <TB3> INFO: scanning low vcal = 190
[16:35:20.944] <TB3> INFO: Expecting 41600 events.
[16:35:25.171] <TB3> INFO: 41600 events read in total (3511ms).
[16:35:25.171] <TB3> INFO: Test took 4579ms.
[16:35:25.174] <TB3> INFO: scanning low vcal = 200
[16:35:25.532] <TB3> INFO: Expecting 41600 events.
[16:35:29.728] <TB3> INFO: 41600 events read in total (3480ms).
[16:35:29.729] <TB3> INFO: Test took 4555ms.
[16:35:29.732] <TB3> INFO: scanning low vcal = 210
[16:35:30.088] <TB3> INFO: Expecting 41600 events.
[16:35:34.331] <TB3> INFO: 41600 events read in total (3527ms).
[16:35:34.332] <TB3> INFO: Test took 4600ms.
[16:35:34.335] <TB3> INFO: scanning low vcal = 220
[16:35:34.671] <TB3> INFO: Expecting 41600 events.
[16:35:38.712] <TB3> INFO: 41600 events read in total (3325ms).
[16:35:38.713] <TB3> INFO: Test took 4378ms.
[16:35:38.716] <TB3> INFO: scanning low vcal = 230
[16:35:39.050] <TB3> INFO: Expecting 41600 events.
[16:35:43.129] <TB3> INFO: 41600 events read in total (3363ms).
[16:35:43.129] <TB3> INFO: Test took 4413ms.
[16:35:43.132] <TB3> INFO: scanning low vcal = 240
[16:35:43.467] <TB3> INFO: Expecting 41600 events.
[16:35:47.547] <TB3> INFO: 41600 events read in total (3364ms).
[16:35:47.548] <TB3> INFO: Test took 4416ms.
[16:35:47.550] <TB3> INFO: scanning low vcal = 250
[16:35:47.903] <TB3> INFO: Expecting 41600 events.
[16:35:52.029] <TB3> INFO: 41600 events read in total (3410ms).
[16:35:52.030] <TB3> INFO: Test took 4480ms.
[16:35:52.034] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[16:35:52.404] <TB3> INFO: Expecting 41600 events.
[16:35:56.479] <TB3> INFO: 41600 events read in total (3359ms).
[16:35:56.479] <TB3> INFO: Test took 4445ms.
[16:35:56.482] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[16:35:56.836] <TB3> INFO: Expecting 41600 events.
[16:36:00.916] <TB3> INFO: 41600 events read in total (3364ms).
[16:36:00.916] <TB3> INFO: Test took 4434ms.
[16:36:00.919] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[16:36:01.255] <TB3> INFO: Expecting 41600 events.
[16:36:05.318] <TB3> INFO: 41600 events read in total (3347ms).
[16:36:05.319] <TB3> INFO: Test took 4400ms.
[16:36:05.322] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[16:36:05.657] <TB3> INFO: Expecting 41600 events.
[16:36:09.900] <TB3> INFO: 41600 events read in total (3527ms).
[16:36:09.901] <TB3> INFO: Test took 4579ms.
[16:36:09.904] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:36:10.241] <TB3> INFO: Expecting 41600 events.
[16:36:14.301] <TB3> INFO: 41600 events read in total (3344ms).
[16:36:14.301] <TB3> INFO: Test took 4397ms.
[16:36:14.831] <TB3> INFO: PixTestGainPedestal::measure() done
[16:36:50.133] <TB3> INFO: PixTestGainPedestal::fit() done
[16:36:50.133] <TB3> INFO: non-linearity mean: 0.958 0.963 0.958 0.958 0.965 0.953 0.965 0.952 0.951 0.954 0.961 0.959 0.956 0.954 0.969 0.957
[16:36:50.133] <TB3> INFO: non-linearity RMS: 0.005 0.005 0.006 0.006 0.007 0.006 0.006 0.007 0.008 0.008 0.007 0.007 0.006 0.008 0.004 0.006
[16:36:50.133] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[16:36:50.153] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[16:36:50.171] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[16:36:50.189] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[16:36:50.208] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[16:36:50.229] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[16:36:50.248] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[16:36:50.267] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[16:36:50.291] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[16:36:50.311] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[16:36:50.329] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[16:36:50.348] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[16:36:50.367] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[16:36:50.390] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[16:36:50.409] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[16:36:50.435] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[16:36:50.466] <TB3> INFO: PixTestGainPedestal::doTest() done, duration: 170 seconds
[16:36:50.467] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:50.467] <TB3> INFO: Decoding statistics:
[16:36:50.467] <TB3> INFO: General information:
[16:36:50.467] <TB3> INFO: 16bit words read: 2662400
[16:36:50.467] <TB3> INFO: valid events total: 166400
[16:36:50.467] <TB3> INFO: empty events: 0
[16:36:50.467] <TB3> INFO: valid events with pixels: 166400
[16:36:50.467] <TB3> INFO: valid pixel hits: 665472
[16:36:50.467] <TB3> INFO: Event errors: 0
[16:36:50.467] <TB3> INFO: start marker: 0
[16:36:50.467] <TB3> INFO: stop marker: 0
[16:36:50.467] <TB3> INFO: overflow: 0
[16:36:50.467] <TB3> INFO: invalid 5bit words: 0
[16:36:50.467] <TB3> INFO: invalid XOR eye diagram: 0
[16:36:50.467] <TB3> INFO: TBM errors: 0
[16:36:50.467] <TB3> INFO: flawed TBM headers: 0
[16:36:50.467] <TB3> INFO: flawed TBM trailers: 0
[16:36:50.467] <TB3> INFO: event ID mismatches: 0
[16:36:50.467] <TB3> INFO: ROC errors: 0
[16:36:50.467] <TB3> INFO: missing ROC header(s): 0
[16:36:50.467] <TB3> INFO: misplaced readback start: 0
[16:36:50.467] <TB3> INFO: Pixel decoding errors: 0
[16:36:50.467] <TB3> INFO: pixel data incomplete: 0
[16:36:50.467] <TB3> INFO: pixel address: 0
[16:36:50.467] <TB3> INFO: pulse height fill bit: 0
[16:36:50.467] <TB3> INFO: buffer corruption: 0
[16:36:50.473] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C15.dat
[16:36:50.475] <TB3> INFO: ######################################################################
[16:36:50.475] <TB3> INFO: PixTestTrim::doTest()
[16:36:50.475] <TB3> INFO: ######################################################################
[16:36:50.475] <TB3> INFO: PixTestReadback::RES sent once
[16:37:12.262] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C0.dat
[16:37:12.262] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C1.dat
[16:37:12.262] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C2.dat
[16:37:12.262] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C3.dat
[16:37:12.262] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C4.dat
[16:37:12.262] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C5.dat
[16:37:12.263] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C6.dat
[16:37:12.263] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C7.dat
[16:37:12.263] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C8.dat
[16:37:12.263] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C9.dat
[16:37:12.263] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C10.dat
[16:37:12.263] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C11.dat
[16:37:12.263] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C12.dat
[16:37:12.263] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C13.dat
[16:37:12.264] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C14.dat
[16:37:12.264] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C15.dat
[16:37:12.300] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:37:12.301] <TB3> INFO: PixTestReadback::RES sent once
[16:37:34.032] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C0.dat
[16:37:34.032] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C1.dat
[16:37:34.032] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C2.dat
[16:37:34.033] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C3.dat
[16:37:34.033] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C4.dat
[16:37:34.033] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C5.dat
[16:37:34.033] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C6.dat
[16:37:34.033] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C7.dat
[16:37:34.033] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C8.dat
[16:37:34.034] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C9.dat
[16:37:34.034] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C10.dat
[16:37:34.034] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C11.dat
[16:37:34.034] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C12.dat
[16:37:34.035] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C13.dat
[16:37:34.035] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C14.dat
[16:37:34.035] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C15.dat
[16:37:34.072] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:37:34.073] <TB3> INFO: PixTestReadback::RES sent once
[16:37:50.957] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:37:50.957] <TB3> INFO: Vbg will be calibrated using Vd calibration
[16:37:50.957] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 152.3calibrated Vbg = 1.23339 :::*/*/*/*/
[16:37:50.958] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 153.3calibrated Vbg = 1.21425 :::*/*/*/*/
[16:37:50.958] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 147.3calibrated Vbg = 1.26524 :::*/*/*/*/
[16:37:50.958] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 255calibrated Vbg = 1.1825 :::*/*/*/*/
[16:37:50.958] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 157.7calibrated Vbg = 1.37773 :::*/*/*/*/
[16:37:50.958] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 151.5calibrated Vbg = 1.40315 :::*/*/*/*/
[16:37:50.958] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 158.3calibrated Vbg = 1.4205 :::*/*/*/*/
[16:37:50.958] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 180.4calibrated Vbg = 1.23815 :::*/*/*/*/
[16:37:50.958] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 151.1calibrated Vbg = 1.39259 :::*/*/*/*/
[16:37:50.958] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 151.3calibrated Vbg = 1.37096 :::*/*/*/*/
[16:37:50.958] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 142.2calibrated Vbg = 1.3354 :::*/*/*/*/
[16:37:50.958] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 255calibrated Vbg = 1.1825 :::*/*/*/*/
[16:37:50.958] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 150.9calibrated Vbg = 1.22242 :::*/*/*/*/
[16:37:50.958] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 159.8calibrated Vbg = 1.21866 :::*/*/*/*/
[16:37:50.958] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 149.1calibrated Vbg = 1.25178 :::*/*/*/*/
[16:37:50.958] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 255calibrated Vbg = 1.1825 :::*/*/*/*/
[16:37:50.962] <TB3> INFO: PixTestReadback::RES sent once
[16:42:31.422] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C0.dat
[16:42:31.423] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C1.dat
[16:42:31.423] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C2.dat
[16:42:31.423] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C3.dat
[16:42:31.423] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C4.dat
[16:42:31.423] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C5.dat
[16:42:31.424] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C6.dat
[16:42:31.424] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C7.dat
[16:42:31.424] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C8.dat
[16:42:31.424] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C9.dat
[16:42:31.424] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C10.dat
[16:42:31.424] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C11.dat
[16:42:31.424] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C12.dat
[16:42:31.424] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C13.dat
[16:42:31.424] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C14.dat
[16:42:31.425] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2107_FullQualification_2015-09-07_11h21m_1441617691//004_FulltestPxar_p17//readbackCal_C15.dat
[16:42:31.456] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[16:42:31.457] <TB3> INFO: PixTestReadback::doTest() done
[16:42:31.457] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:31.457] <TB3> INFO: Decoding statistics:
[16:42:31.457] <TB3> INFO: General information:
[16:42:31.457] <TB3> INFO: 16bit words read: 1024
[16:42:31.457] <TB3> INFO: valid events total: 128
[16:42:31.457] <TB3> INFO: empty events: 128
[16:42:31.458] <TB3> INFO: valid events with pixels: 0
[16:42:31.458] <TB3> INFO: valid pixel hits: 0
[16:42:31.458] <TB3> INFO: Event errors: 0
[16:42:31.458] <TB3> INFO: start marker: 0
[16:42:31.458] <TB3> INFO: stop marker: 0
[16:42:31.458] <TB3> INFO: overflow: 0
[16:42:31.458] <TB3> INFO: invalid 5bit words: 0
[16:42:31.458] <TB3> INFO: invalid XOR eye diagram: 0
[16:42:31.458] <TB3> INFO: TBM errors: 0
[16:42:31.458] <TB3> INFO: flawed TBM headers: 0
[16:42:31.458] <TB3> INFO: flawed TBM trailers: 0
[16:42:31.458] <TB3> INFO: event ID mismatches: 0
[16:42:31.458] <TB3> INFO: ROC errors: 0
[16:42:31.458] <TB3> INFO: missing ROC header(s): 0
[16:42:31.458] <TB3> INFO: misplaced readback start: 0
[16:42:31.458] <TB3> INFO: Pixel decoding errors: 0
[16:42:31.458] <TB3> INFO: pixel data incomplete: 0
[16:42:31.458] <TB3> INFO: pixel address: 0
[16:42:31.458] <TB3> INFO: pulse height fill bit: 0
[16:42:31.458] <TB3> INFO: buffer corruption: 0
[16:42:31.473] <TB3> INFO: Decoding statistics:
[16:42:31.473] <TB3> INFO: General information:
[16:42:31.473] <TB3> INFO: 16bit words read: 2755356
[16:42:31.473] <TB3> INFO: valid events total: 176768
[16:42:31.473] <TB3> INFO: empty events: 7865
[16:42:31.473] <TB3> INFO: valid events with pixels: 168903
[16:42:31.473] <TB3> INFO: valid pixel hits: 667975
[16:42:31.473] <TB3> INFO: Event errors: 0
[16:42:31.473] <TB3> INFO: start marker: 0
[16:42:31.473] <TB3> INFO: stop marker: 0
[16:42:31.473] <TB3> INFO: overflow: 0
[16:42:31.473] <TB3> INFO: invalid 5bit words: 0
[16:42:31.473] <TB3> INFO: invalid XOR eye diagram: 0
[16:42:31.473] <TB3> INFO: TBM errors: 0
[16:42:31.473] <TB3> INFO: flawed TBM headers: 0
[16:42:31.473] <TB3> INFO: flawed TBM trailers: 0
[16:42:31.473] <TB3> INFO: event ID mismatches: 0
[16:42:31.473] <TB3> INFO: ROC errors: 0
[16:42:31.473] <TB3> INFO: missing ROC header(s): 0
[16:42:31.473] <TB3> INFO: misplaced readback start: 0
[16:42:31.473] <TB3> INFO: Pixel decoding errors: 0
[16:42:31.473] <TB3> INFO: pixel data incomplete: 0
[16:42:31.473] <TB3> INFO: pixel address: 0
[16:42:31.473] <TB3> INFO: pulse height fill bit: 0
[16:42:31.473] <TB3> INFO: buffer corruption: 0
[16:42:31.473] <TB3> INFO: enter test to run
[16:42:31.473] <TB3> INFO: test: exit no parameter change
[16:42:32.088] <TB3> QUIET: Connection to board 170 closed.
[16:42:32.168] <TB3> INFO: pXar: this is the end, my friend