[14:58:14.159] <TB1> INFO: *** Welcome to pxar ***
[14:58:14.159] <TB1> INFO: *** Today: 2016/09/07
[14:58:14.253] <TB1> INFO: *** Version: fe94
[14:58:14.253] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters_C15.dat
[14:58:14.254] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//tbmParameters_C0b.dat
[14:58:14.254] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//defaultMaskFile.dat
[14:58:14.254] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//trimParameters_C15.dat
[14:58:14.330] <TB1> INFO: clk: 4
[14:58:14.330] <TB1> INFO: ctr: 4
[14:58:14.330] <TB1> INFO: sda: 19
[14:58:14.330] <TB1> INFO: tin: 9
[14:58:14.330] <TB1> INFO: level: 15
[14:58:14.330] <TB1> INFO: triggerdelay: 0
[14:58:14.330] <TB1> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[14:58:14.330] <TB1> INFO: Log level: INFO
[14:58:14.337] <TB1> INFO: Found DTB DTB_WXBYFL
[14:58:14.348] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[14:58:14.350] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[14:58:14.351] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[14:58:15.885] <TB1> INFO: DUT info:
[14:58:15.885] <TB1> INFO: The DUT currently contains the following objects:
[14:58:15.885] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[14:58:15.885] <TB1> INFO: TBM Core alpha (0): 7 registers set
[14:58:15.885] <TB1> INFO: TBM Core beta (1): 7 registers set
[14:58:15.885] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:58:15.885] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:15.885] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:15.885] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:15.885] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:15.885] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:15.885] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:15.885] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:15.885] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:15.885] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:15.885] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:15.885] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:15.885] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:15.885] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:15.885] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:15.885] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:15.885] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:58:16.286] <TB1> INFO: enter 'restricted' command line mode
[14:58:16.286] <TB1> INFO: enter test to run
[14:58:16.286] <TB1> INFO: test: pretest no parameter change
[14:58:16.286] <TB1> INFO: running: pretest
[14:58:16.290] <TB1> INFO: ----------------------------------------------------------------------
[14:58:16.290] <TB1> INFO: PixTestPretest::programROC()
[14:58:16.290] <TB1> INFO: ----------------------------------------------------------------------
[14:58:34.304] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[14:58:34.304] <TB1> INFO: IA differences per ROC: 19.3 18.5 18.5 20.1 20.1 19.3 19.3 18.5 17.7 19.3 19.3 19.3 20.1 18.5 18.5 18.5
[14:58:34.403] <TB1> INFO: enter test to run
[14:58:34.403] <TB1> INFO: test: pretest no parameter change
[14:58:34.403] <TB1> INFO: running: pretest
[14:58:34.404] <TB1> INFO: ----------------------------------------------------------------------
[14:58:34.404] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[14:58:34.404] <TB1> INFO: ----------------------------------------------------------------------
[14:58:39.900] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 382.7 mA = 23.9187 mA/ROC
[14:58:39.900] <TB1> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 19.3 19.3 19.3 19.3 18.5 19.3 18.5 18.5 19.3 20.1 18.5 18.5 18.5
[14:58:39.933] <TB1> INFO: enter test to run
[14:58:39.933] <TB1> INFO: test: pretest no parameter change
[14:58:39.933] <TB1> INFO: running: pretest
[14:58:39.933] <TB1> INFO: ----------------------------------------------------------------------
[14:58:39.933] <TB1> INFO: PixTestPretest::findTiming()
[14:58:39.933] <TB1> INFO: ----------------------------------------------------------------------
[14:58:39.933] <TB1> INFO: PixTestCmd::init()
[14:58:40.457] <TB1> WARNING: Not unmasking DUT, not setting Calibrate bits!
[15:00:08.413] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[15:00:08.413] <TB1> INFO: (success/tries = 100/100), width = 4
[15:00:08.416] <TB1> INFO: enter test to run
[15:00:08.416] <TB1> INFO: test: pretest no parameter change
[15:00:08.416] <TB1> INFO: running: pretest
[15:00:08.418] <TB1> INFO: ----------------------------------------------------------------------
[15:00:08.418] <TB1> INFO: PixTestPretest::findWorkingPixel()
[15:00:08.418] <TB1> INFO: ----------------------------------------------------------------------
[15:00:08.510] <TB1> INFO: Expecting 231680 events.
[15:00:14.288] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L497> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[15:00:14.369] <TB1> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[15:00:18.828] <TB1> INFO: 231680 events read in total (9762ms).
[15:00:18.832] <TB1> INFO: Test took 10411ms.
[15:00:19.051] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[15:00:19.108] <TB1> INFO: enter test to run
[15:00:19.108] <TB1> INFO: test: pretest no parameter change
[15:00:19.108] <TB1> INFO: running: pretest
[15:00:19.109] <TB1> INFO: ----------------------------------------------------------------------
[15:00:19.109] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[15:00:19.109] <TB1> INFO: ----------------------------------------------------------------------
[15:00:19.201] <TB1> INFO: Expecting 231680 events.
[15:00:29.512] <TB1> INFO: 231680 events read in total (9755ms).
[15:00:29.516] <TB1> INFO: Test took 10403ms.
[15:00:29.758] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[15:00:29.758] <TB1> INFO: CalDel: 144 131 122 143 143 151 146 138 148 137 143 156 153 124 158 134
[15:00:29.758] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[15:00:29.792] <TB1> INFO: enter test to run
[15:00:29.792] <TB1> INFO: test: pretest no parameter change
[15:00:29.792] <TB1> INFO: running: pretest
[15:00:29.793] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters_C0.dat
[15:00:29.793] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters_C1.dat
[15:00:29.793] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters_C2.dat
[15:00:29.793] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters_C3.dat
[15:00:29.793] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters_C4.dat
[15:00:29.793] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters_C5.dat
[15:00:29.793] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters_C6.dat
[15:00:29.793] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters_C7.dat
[15:00:29.794] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters_C8.dat
[15:00:29.794] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters_C9.dat
[15:00:29.794] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters_C10.dat
[15:00:29.794] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters_C11.dat
[15:00:29.794] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters_C12.dat
[15:00:29.794] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters_C13.dat
[15:00:29.794] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters_C14.dat
[15:00:29.794] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters_C15.dat
[15:00:29.794] <TB1> INFO: enter test to run
[15:00:29.794] <TB1> INFO: test: fulltest no parameter change
[15:00:29.794] <TB1> INFO: running: fulltest
[15:00:29.794] <TB1> INFO: ######################################################################
[15:00:29.794] <TB1> INFO: PixTestFullTest::doTest()
[15:00:29.795] <TB1> INFO: ######################################################################
[15:00:29.797] <TB1> INFO: ######################################################################
[15:00:29.797] <TB1> INFO: PixTestAlive::doTest()
[15:00:29.797] <TB1> INFO: ######################################################################
[15:00:29.798] <TB1> INFO: ----------------------------------------------------------------------
[15:00:29.798] <TB1> 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:00:29.798] <TB1> INFO: ----------------------------------------------------------------------
[15:00:30.065] <TB1> INFO: Expecting 41600 events.
[15:00:33.833] <TB1> INFO: 41600 events read in total (3211ms).
[15:00:33.834] <TB1> INFO: Test took 4033ms.
[15:00:34.057] <TB1> INFO: PixTestAlive::aliveTest() done
[15:00:34.057] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0
[15:00:34.058] <TB1> INFO: ----------------------------------------------------------------------
[15:00:34.058] <TB1> 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:00:34.058] <TB1> INFO: ----------------------------------------------------------------------
[15:00:34.352] <TB1> INFO: Expecting 41600 events.
[15:00:37.367] <TB1> INFO: 41600 events read in total (2459ms).
[15:00:37.367] <TB1> INFO: Test took 3308ms.
[15:00:37.368] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:00:37.621] <TB1> INFO: PixTestAlive::maskTest() done
[15:00:37.621] <TB1> 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:00:37.624] <TB1> INFO: ----------------------------------------------------------------------
[15:00:37.624] <TB1> 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:00:37.624] <TB1> INFO: ----------------------------------------------------------------------
[15:00:37.907] <TB1> INFO: Expecting 41600 events.
[15:00:41.669] <TB1> INFO: 41600 events read in total (3206ms).
[15:00:41.670] <TB1> INFO: Test took 4044ms.
[15:00:41.903] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[15:00:41.903] <TB1> 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:00:41.903] <TB1> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[15:00:41.903] <TB1> INFO: Decoding statistics:
[15:00:41.903] <TB1> INFO: General information:
[15:00:41.903] <TB1> INFO: 16bit words read: 0
[15:00:41.903] <TB1> INFO: valid events total: 0
[15:00:41.903] <TB1> INFO: empty events: 0
[15:00:41.903] <TB1> INFO: valid events with pixels: 0
[15:00:41.903] <TB1> INFO: valid pixel hits: 0
[15:00:41.903] <TB1> INFO: Event errors: 0
[15:00:41.903] <TB1> INFO: start marker: 0
[15:00:41.903] <TB1> INFO: stop marker: 0
[15:00:41.903] <TB1> INFO: overflow: 0
[15:00:41.903] <TB1> INFO: invalid 5bit words: 0
[15:00:41.903] <TB1> INFO: invalid XOR eye diagram: 0
[15:00:41.903] <TB1> INFO: frame (failed synchr.): 0
[15:00:41.903] <TB1> INFO: idle data (no TBM trl): 0
[15:00:41.903] <TB1> INFO: no data (only TBM hdr): 0
[15:00:41.903] <TB1> INFO: TBM errors: 0
[15:00:41.903] <TB1> INFO: flawed TBM headers: 0
[15:00:41.903] <TB1> INFO: flawed TBM trailers: 0
[15:00:41.903] <TB1> INFO: event ID mismatches: 0
[15:00:41.903] <TB1> INFO: ROC errors: 0
[15:00:41.904] <TB1> INFO: missing ROC header(s): 0
[15:00:41.904] <TB1> INFO: misplaced readback start: 0
[15:00:41.904] <TB1> INFO: Pixel decoding errors: 0
[15:00:41.904] <TB1> INFO: pixel data incomplete: 0
[15:00:41.904] <TB1> INFO: pixel address: 0
[15:00:41.904] <TB1> INFO: pulse height fill bit: 0
[15:00:41.904] <TB1> INFO: buffer corruption: 0
[15:00:41.912] <TB1> INFO: ######################################################################
[15:00:41.912] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[15:00:41.912] <TB1> INFO: ######################################################################
[15:00:41.916] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[15:00:41.927] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[15:00:41.927] <TB1> INFO: run 1 of 1
[15:00:42.191] <TB1> INFO: Expecting 3120000 events.
[15:01:23.563] <TB1> INFO: 852025 events read in total (40816ms).
[15:02:03.835] <TB1> INFO: 1691070 events read in total (81088ms).
[15:02:44.447] <TB1> INFO: 2543600 events read in total (121701ms).
[15:03:11.687] <TB1> INFO: 3120000 events read in total (148940ms).
[15:03:11.745] <TB1> INFO: Test took 149818ms.
[15:03:35.866] <TB1> INFO: PixTestBBMap::doTest() done, duration: 173 seconds
[15:03:35.866] <TB1> INFO: number of dead bumps (per ROC): 1 0 0 1 0 0 0 0 0 0 0 2 0 0 0 1
[15:03:35.866] <TB1> INFO: separation cut (per ROC): 84 88 95 96 90 84 84 82 68 79 87 79 94 90 89 88
[15:03:35.866] <TB1> INFO: Decoding statistics:
[15:03:35.866] <TB1> INFO: General information:
[15:03:35.866] <TB1> INFO: 16bit words read: 0
[15:03:35.866] <TB1> INFO: valid events total: 0
[15:03:35.866] <TB1> INFO: empty events: 0
[15:03:35.866] <TB1> INFO: valid events with pixels: 0
[15:03:35.866] <TB1> INFO: valid pixel hits: 0
[15:03:35.866] <TB1> INFO: Event errors: 0
[15:03:35.866] <TB1> INFO: start marker: 0
[15:03:35.866] <TB1> INFO: stop marker: 0
[15:03:35.866] <TB1> INFO: overflow: 0
[15:03:35.866] <TB1> INFO: invalid 5bit words: 0
[15:03:35.866] <TB1> INFO: invalid XOR eye diagram: 0
[15:03:35.866] <TB1> INFO: frame (failed synchr.): 0
[15:03:35.866] <TB1> INFO: idle data (no TBM trl): 0
[15:03:35.866] <TB1> INFO: no data (only TBM hdr): 0
[15:03:35.866] <TB1> INFO: TBM errors: 0
[15:03:35.866] <TB1> INFO: flawed TBM headers: 0
[15:03:35.866] <TB1> INFO: flawed TBM trailers: 0
[15:03:35.866] <TB1> INFO: event ID mismatches: 0
[15:03:35.866] <TB1> INFO: ROC errors: 0
[15:03:35.866] <TB1> INFO: missing ROC header(s): 0
[15:03:35.866] <TB1> INFO: misplaced readback start: 0
[15:03:35.866] <TB1> INFO: Pixel decoding errors: 0
[15:03:35.866] <TB1> INFO: pixel data incomplete: 0
[15:03:35.866] <TB1> INFO: pixel address: 0
[15:03:35.866] <TB1> INFO: pulse height fill bit: 0
[15:03:35.866] <TB1> INFO: buffer corruption: 0
[15:03:35.959] <TB1> INFO: ######################################################################
[15:03:35.959] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:03:35.959] <TB1> INFO: ######################################################################
[15:03:35.959] <TB1> INFO: ----------------------------------------------------------------------
[15:03:35.959] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[15:03:35.959] <TB1> INFO: ----------------------------------------------------------------------
[15:03:35.959] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:03:35.969] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[15:03:35.969] <TB1> INFO: run 1 of 1
[15:03:36.309] <TB1> INFO: Expecting 26208000 events.
[15:04:11.581] <TB1> INFO: 916200 events read in total (34715ms).
[15:04:45.082] <TB1> INFO: 1817300 events read in total (68216ms).
[15:05:18.292] <TB1> INFO: 2715650 events read in total (101426ms).
[15:05:51.094] <TB1> INFO: 3614550 events read in total (134228ms).
[15:06:24.303] <TB1> INFO: 4509750 events read in total (167437ms).
[15:06:57.380] <TB1> INFO: 5403650 events read in total (200514ms).
[15:07:30.558] <TB1> INFO: 6299700 events read in total (233692ms).
[15:08:03.910] <TB1> INFO: 7193400 events read in total (267044ms).
[15:08:37.396] <TB1> INFO: 8086650 events read in total (300530ms).
[15:09:10.558] <TB1> INFO: 8977450 events read in total (333692ms).
[15:09:43.772] <TB1> INFO: 9870650 events read in total (366906ms).
[15:10:16.963] <TB1> INFO: 10763900 events read in total (400097ms).
[15:10:50.003] <TB1> INFO: 11654600 events read in total (433137ms).
[15:11:23.415] <TB1> INFO: 12544700 events read in total (466549ms).
[15:11:56.607] <TB1> INFO: 13428000 events read in total (499741ms).
[15:12:30.441] <TB1> INFO: 14306800 events read in total (533575ms).
[15:13:04.148] <TB1> INFO: 15185650 events read in total (567283ms).
[15:13:38.015] <TB1> INFO: 16061700 events read in total (601149ms).
[15:14:11.394] <TB1> INFO: 16937900 events read in total (634528ms).
[15:14:44.054] <TB1> INFO: 17812250 events read in total (667189ms).
[15:15:17.124] <TB1> INFO: 18686400 events read in total (700258ms).
[15:15:50.185] <TB1> INFO: 19559100 events read in total (733319ms).
[15:16:23.073] <TB1> INFO: 20434000 events read in total (766207ms).
[15:16:56.493] <TB1> INFO: 21307600 events read in total (799627ms).
[15:17:29.685] <TB1> INFO: 22181350 events read in total (832819ms).
[15:18:02.719] <TB1> INFO: 23055450 events read in total (865853ms).
[15:18:35.752] <TB1> INFO: 23928700 events read in total (898886ms).
[15:19:08.747] <TB1> INFO: 24803400 events read in total (931881ms).
[15:19:41.734] <TB1> INFO: 25677600 events read in total (964868ms).
[15:20:01.570] <TB1> INFO: 26208000 events read in total (984704ms).
[15:20:01.595] <TB1> INFO: Test took 985626ms.
[15:20:01.870] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:20:03.468] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:20:04.919] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:20:06.483] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:20:08.042] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:20:09.437] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:20:10.838] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:20:12.233] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:20:13.615] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:20:15.056] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:20:16.739] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:20:18.228] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:20:19.898] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:20:21.592] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:20:23.285] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:20:24.695] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[15:20:26.174] <TB1> INFO: PixTestScurves::scurves() done
[15:20:26.174] <TB1> INFO: Vcal mean: 83.38 88.77 91.89 92.31 98.12 87.14 83.52 88.40 87.55 89.12 92.23 89.92 90.26 89.91 92.91 88.69
[15:20:26.174] <TB1> INFO: Vcal RMS: 4.18 4.98 4.89 5.44 5.34 5.17 4.02 4.47 6.19 5.17 4.36 5.10 4.64 4.86 5.13 4.70
[15:20:26.174] <TB1> INFO: PixTestScurves::fullTest() done, duration: 1010 seconds
[15:20:26.174] <TB1> INFO: Decoding statistics:
[15:20:26.174] <TB1> INFO: General information:
[15:20:26.174] <TB1> INFO: 16bit words read: 0
[15:20:26.174] <TB1> INFO: valid events total: 0
[15:20:26.174] <TB1> INFO: empty events: 0
[15:20:26.174] <TB1> INFO: valid events with pixels: 0
[15:20:26.174] <TB1> INFO: valid pixel hits: 0
[15:20:26.174] <TB1> INFO: Event errors: 0
[15:20:26.174] <TB1> INFO: start marker: 0
[15:20:26.174] <TB1> INFO: stop marker: 0
[15:20:26.174] <TB1> INFO: overflow: 0
[15:20:26.174] <TB1> INFO: invalid 5bit words: 0
[15:20:26.174] <TB1> INFO: invalid XOR eye diagram: 0
[15:20:26.174] <TB1> INFO: frame (failed synchr.): 0
[15:20:26.174] <TB1> INFO: idle data (no TBM trl): 0
[15:20:26.174] <TB1> INFO: no data (only TBM hdr): 0
[15:20:26.174] <TB1> INFO: TBM errors: 0
[15:20:26.174] <TB1> INFO: flawed TBM headers: 0
[15:20:26.174] <TB1> INFO: flawed TBM trailers: 0
[15:20:26.174] <TB1> INFO: event ID mismatches: 0
[15:20:26.174] <TB1> INFO: ROC errors: 0
[15:20:26.174] <TB1> INFO: missing ROC header(s): 0
[15:20:26.174] <TB1> INFO: misplaced readback start: 0
[15:20:26.174] <TB1> INFO: Pixel decoding errors: 0
[15:20:26.174] <TB1> INFO: pixel data incomplete: 0
[15:20:26.174] <TB1> INFO: pixel address: 0
[15:20:26.174] <TB1> INFO: pulse height fill bit: 0
[15:20:26.174] <TB1> INFO: buffer corruption: 0
[15:20:26.248] <TB1> INFO: ######################################################################
[15:20:26.248] <TB1> INFO: PixTestTrim::doTest()
[15:20:26.248] <TB1> INFO: ######################################################################
[15:20:26.249] <TB1> INFO: ----------------------------------------------------------------------
[15:20:26.249] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[15:20:26.249] <TB1> INFO: ----------------------------------------------------------------------
[15:20:26.343] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[15:20:26.343] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:20:26.354] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:20:26.354] <TB1> INFO: run 1 of 1
[15:20:26.630] <TB1> INFO: Expecting 6281600 events.
[15:21:05.479] <TB1> INFO: 1064350 events read in total (38292ms).
[15:21:44.334] <TB1> INFO: 2124330 events read in total (77147ms).
[15:22:23.952] <TB1> INFO: 3179010 events read in total (116765ms).
[15:23:02.924] <TB1> INFO: 4242170 events read in total (155737ms).
[15:23:41.933] <TB1> INFO: 5311590 events read in total (194746ms).
[15:24:17.140] <TB1> INFO: 6281600 events read in total (229953ms).
[15:24:17.181] <TB1> INFO: Test took 230827ms.
[15:24:37.400] <TB1> INFO: ROC 0 VthrComp = 92
[15:24:37.400] <TB1> INFO: ROC 1 VthrComp = 95
[15:24:37.401] <TB1> INFO: ROC 2 VthrComp = 98
[15:24:37.401] <TB1> INFO: ROC 3 VthrComp = 98
[15:24:37.401] <TB1> INFO: ROC 4 VthrComp = 101
[15:24:37.401] <TB1> INFO: ROC 5 VthrComp = 90
[15:24:37.401] <TB1> INFO: ROC 6 VthrComp = 88
[15:24:37.401] <TB1> INFO: ROC 7 VthrComp = 92
[15:24:37.402] <TB1> INFO: ROC 8 VthrComp = 85
[15:24:37.402] <TB1> INFO: ROC 9 VthrComp = 92
[15:24:37.402] <TB1> INFO: ROC 10 VthrComp = 95
[15:24:37.402] <TB1> INFO: ROC 11 VthrComp = 90
[15:24:37.402] <TB1> INFO: ROC 12 VthrComp = 98
[15:24:37.402] <TB1> INFO: ROC 13 VthrComp = 94
[15:24:37.402] <TB1> INFO: ROC 14 VthrComp = 93
[15:24:37.402] <TB1> INFO: ROC 15 VthrComp = 95
[15:24:37.402] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[15:24:37.403] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:24:37.412] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:24:37.412] <TB1> INFO: run 1 of 1
[15:24:37.686] <TB1> INFO: Expecting 6281600 events.
[15:25:13.435] <TB1> INFO: 756490 events read in total (35193ms).
[15:25:48.564] <TB1> INFO: 1510940 events read in total (70322ms).
[15:26:23.368] <TB1> INFO: 2265410 events read in total (105126ms).
[15:26:58.115] <TB1> INFO: 3020110 events read in total (139873ms).
[15:27:32.831] <TB1> INFO: 3769290 events read in total (174589ms).
[15:28:07.830] <TB1> INFO: 4514340 events read in total (209588ms).
[15:28:42.872] <TB1> INFO: 5257580 events read in total (244630ms).
[15:29:17.698] <TB1> INFO: 5999990 events read in total (279456ms).
[15:29:30.963] <TB1> INFO: 6281600 events read in total (292721ms).
[15:29:31.006] <TB1> INFO: Test took 293594ms.
[15:29:55.608] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 55.6 for pixel 17/69 mean/min/max = 44.7133/33.8208/55.6057
[15:29:55.608] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 57.3603 for pixel 1/79 mean/min/max = 44.8634/32.3281/57.3988
[15:29:55.658] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 56.6894 for pixel 9/5 mean/min/max = 44.5056/31.9902/57.0211
[15:29:55.659] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 57.217 for pixel 4/13 mean/min/max = 44.3671/31.374/57.3602
[15:29:55.659] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 60.0889 for pixel 16/79 mean/min/max = 46.0717/32.0541/60.0893
[15:29:55.659] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 57.932 for pixel 0/60 mean/min/max = 46.157/33.5652/58.7488
[15:29:55.660] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 55.2669 for pixel 13/17 mean/min/max = 44.9442/34.6011/55.2872
[15:29:55.660] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 55.9964 for pixel 46/3 mean/min/max = 45.1171/34.1731/56.061
[15:29:55.660] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 65.9678 for pixel 0/1 mean/min/max = 49.0308/31.935/66.1267
[15:29:55.661] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 59.4161 for pixel 0/17 mean/min/max = 46.3066/32.967/59.6462
[15:29:55.661] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 57.1648 for pixel 11/1 mean/min/max = 45.0551/32.8099/57.3004
[15:29:55.662] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 60.615 for pixel 2/18 mean/min/max = 47.151/33.6308/60.6711
[15:29:55.662] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 54.8274 for pixel 0/47 mean/min/max = 43.5993/32.2594/54.9392
[15:29:55.662] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 56.9927 for pixel 8/5 mean/min/max = 45.1123/33.0731/57.1515
[15:29:55.663] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 58.9529 for pixel 27/39 mean/min/max = 46.3903/33.8177/58.963
[15:29:55.663] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 55.5187 for pixel 18/1 mean/min/max = 43.9236/32.056/55.7912
[15:29:55.664] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:29:55.751] <TB1> INFO: Expecting 514560 events.
[15:30:08.714] <TB1> INFO: 514560 events read in total (12406ms).
[15:30:08.722] <TB1> INFO: Expecting 514560 events.
[15:30:21.529] <TB1> INFO: 514560 events read in total (12405ms).
[15:30:21.537] <TB1> INFO: Expecting 514560 events.
[15:30:34.464] <TB1> INFO: 514560 events read in total (12510ms).
[15:30:34.474] <TB1> INFO: Expecting 514560 events.
[15:30:47.443] <TB1> INFO: 514560 events read in total (12561ms).
[15:30:47.456] <TB1> INFO: Expecting 514560 events.
[15:31:00.329] <TB1> INFO: 514560 events read in total (12471ms).
[15:31:00.343] <TB1> INFO: Expecting 514560 events.
[15:31:13.216] <TB1> INFO: 514560 events read in total (12468ms).
[15:31:13.232] <TB1> INFO: Expecting 514560 events.
[15:31:26.133] <TB1> INFO: 514560 events read in total (12489ms).
[15:31:26.152] <TB1> INFO: Expecting 514560 events.
[15:31:38.967] <TB1> INFO: 514560 events read in total (12409ms).
[15:31:38.987] <TB1> INFO: Expecting 514560 events.
[15:31:51.829] <TB1> INFO: 514560 events read in total (12435ms).
[15:31:51.851] <TB1> INFO: Expecting 514560 events.
[15:32:04.628] <TB1> INFO: 514560 events read in total (12375ms).
[15:32:04.650] <TB1> INFO: Expecting 514560 events.
[15:32:17.525] <TB1> INFO: 514560 events read in total (12473ms).
[15:32:17.550] <TB1> INFO: Expecting 514560 events.
[15:32:30.256] <TB1> INFO: 514560 events read in total (12304ms).
[15:32:30.283] <TB1> INFO: Expecting 514560 events.
[15:32:43.051] <TB1> INFO: 514560 events read in total (12364ms).
[15:32:43.093] <TB1> INFO: Expecting 514560 events.
[15:32:55.929] <TB1> INFO: 514560 events read in total (12434ms).
[15:32:55.960] <TB1> INFO: Expecting 514560 events.
[15:33:08.857] <TB1> INFO: 514560 events read in total (12495ms).
[15:33:08.898] <TB1> INFO: Expecting 514560 events.
[15:33:21.923] <TB1> INFO: 514560 events read in total (12623ms).
[15:33:21.963] <TB1> INFO: Test took 206300ms.
[15:33:22.976] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:33:22.985] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:33:22.985] <TB1> INFO: run 1 of 1
[15:33:23.262] <TB1> INFO: Expecting 6281600 events.
[15:33:58.602] <TB1> INFO: 744420 events read in total (34784ms).
[15:34:33.548] <TB1> INFO: 1487250 events read in total (69730ms).
[15:35:07.972] <TB1> INFO: 2230160 events read in total (104154ms).
[15:35:42.779] <TB1> INFO: 2973480 events read in total (138961ms).
[15:36:17.463] <TB1> INFO: 3710590 events read in total (173645ms).
[15:36:52.265] <TB1> INFO: 4442680 events read in total (208447ms).
[15:37:26.971] <TB1> INFO: 5172330 events read in total (243153ms).
[15:38:01.689] <TB1> INFO: 5900370 events read in total (277871ms).
[15:38:19.991] <TB1> INFO: 6281600 events read in total (296173ms).
[15:38:20.034] <TB1> INFO: Test took 297049ms.
[15:38:44.691] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.230883 .. 255.000000
[15:38:44.768] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[15:38:44.776] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:38:44.776] <TB1> INFO: run 1 of 1
[15:38:45.040] <TB1> INFO: Expecting 10649600 events.
[15:39:21.173] <TB1> INFO: 712450 events read in total (35577ms).
[15:39:55.659] <TB1> INFO: 1425160 events read in total (70063ms).
[15:40:30.056] <TB1> INFO: 2138000 events read in total (104460ms).
[15:41:04.821] <TB1> INFO: 2851010 events read in total (139225ms).
[15:41:39.164] <TB1> INFO: 3564120 events read in total (173568ms).
[15:42:13.987] <TB1> INFO: 4277110 events read in total (208391ms).
[15:42:49.612] <TB1> INFO: 4990250 events read in total (244016ms).
[15:43:24.428] <TB1> INFO: 5703460 events read in total (278832ms).
[15:43:58.883] <TB1> INFO: 6416300 events read in total (313287ms).
[15:44:33.326] <TB1> INFO: 7128320 events read in total (347730ms).
[15:45:07.728] <TB1> INFO: 7839920 events read in total (382132ms).
[15:45:42.178] <TB1> INFO: 8550870 events read in total (416582ms).
[15:46:16.265] <TB1> INFO: 9261480 events read in total (450669ms).
[15:46:51.258] <TB1> INFO: 9972060 events read in total (485662ms).
[15:47:23.921] <TB1> INFO: 10649600 events read in total (518325ms).
[15:47:24.044] <TB1> INFO: Test took 519267ms.
[15:47:55.257] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 16.198413 .. 45.885898
[15:47:55.339] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 6 .. 55 (-1/-1) hits flags = 528 (plus default)
[15:47:55.347] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:47:55.347] <TB1> INFO: run 1 of 1
[15:47:55.625] <TB1> INFO: Expecting 2080000 events.
[15:48:34.312] <TB1> INFO: 933410 events read in total (38131ms).
[15:49:12.209] <TB1> INFO: 1865300 events read in total (76029ms).
[15:49:21.427] <TB1> INFO: 2080000 events read in total (85248ms).
[15:49:21.446] <TB1> INFO: Test took 86099ms.
[15:49:35.166] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 20.932049 .. 43.759059
[15:49:35.241] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 10 .. 53 (-1/-1) hits flags = 528 (plus default)
[15:49:35.250] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:49:35.250] <TB1> INFO: run 1 of 1
[15:49:35.510] <TB1> INFO: Expecting 1830400 events.
[15:50:14.040] <TB1> INFO: 928230 events read in total (37973ms).
[15:50:51.193] <TB1> INFO: 1830400 events read in total (75126ms).
[15:50:51.215] <TB1> INFO: Test took 75966ms.
[15:51:04.623] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 4.318439 .. 43.074104
[15:51:04.703] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 4 .. 53 (-1/-1) hits flags = 528 (plus default)
[15:51:04.711] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:51:04.711] <TB1> INFO: run 1 of 1
[15:51:04.973] <TB1> INFO: Expecting 2080000 events.
[15:51:43.982] <TB1> INFO: 962980 events read in total (38453ms).
[15:52:22.521] <TB1> INFO: 1925840 events read in total (76993ms).
[15:52:29.099] <TB1> INFO: 2080000 events read in total (83571ms).
[15:52:29.116] <TB1> INFO: Test took 84406ms.
[15:52:43.620] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[15:52:43.620] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[15:52:43.628] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[15:52:43.628] <TB1> INFO: run 1 of 1
[15:52:43.890] <TB1> INFO: Expecting 1705600 events.
[15:53:21.928] <TB1> INFO: 879250 events read in total (37482ms).
[15:53:57.499] <TB1> INFO: 1705600 events read in total (73053ms).
[15:53:57.520] <TB1> INFO: Test took 73894ms.
[15:54:11.179] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C0.dat
[15:54:11.179] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C1.dat
[15:54:11.180] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C2.dat
[15:54:11.180] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C3.dat
[15:54:11.180] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C4.dat
[15:54:11.180] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C5.dat
[15:54:11.180] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C6.dat
[15:54:11.180] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C7.dat
[15:54:11.180] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C8.dat
[15:54:11.180] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C9.dat
[15:54:11.180] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C10.dat
[15:54:11.180] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C11.dat
[15:54:11.180] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C12.dat
[15:54:11.180] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C13.dat
[15:54:11.181] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C14.dat
[15:54:11.181] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C15.dat
[15:54:11.181] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//trimParameters35_C0.dat
[15:54:11.187] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//trimParameters35_C1.dat
[15:54:11.193] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//trimParameters35_C2.dat
[15:54:11.199] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//trimParameters35_C3.dat
[15:54:11.205] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//trimParameters35_C4.dat
[15:54:11.211] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//trimParameters35_C5.dat
[15:54:11.218] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//trimParameters35_C6.dat
[15:54:11.224] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//trimParameters35_C7.dat
[15:54:11.230] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//trimParameters35_C8.dat
[15:54:11.237] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//trimParameters35_C9.dat
[15:54:11.242] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//trimParameters35_C10.dat
[15:54:11.249] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//trimParameters35_C11.dat
[15:54:11.255] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//trimParameters35_C12.dat
[15:54:11.261] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//trimParameters35_C13.dat
[15:54:11.267] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//trimParameters35_C14.dat
[15:54:11.274] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//trimParameters35_C15.dat
[15:54:11.280] <TB1> INFO: PixTestTrim::trimTest() done
[15:54:11.280] <TB1> INFO: vtrim: 97 102 112 112 118 93 92 95 117 96 106 88 90 93 104 110
[15:54:11.280] <TB1> INFO: vthrcomp: 92 95 98 98 101 90 88 92 85 92 95 90 98 94 93 95
[15:54:11.280] <TB1> INFO: vcal mean: 34.95 34.96 34.97 34.98 34.99 34.99 35.00 35.00 34.96 34.99 34.99 35.00 34.97 34.98 35.01 34.94
[15:54:11.280] <TB1> INFO: vcal RMS: 0.80 0.80 0.85 0.83 0.87 0.82 0.74 0.76 1.00 0.83 0.82 0.83 0.77 0.81 1.01 0.87
[15:54:11.280] <TB1> INFO: bits mean: 9.79 9.39 10.15 10.09 9.50 8.61 9.29 9.20 9.19 8.99 9.98 8.64 9.98 9.48 8.91 10.07
[15:54:11.280] <TB1> INFO: bits RMS: 2.35 2.81 2.51 2.59 2.70 2.81 2.40 2.55 2.67 2.80 2.43 2.73 2.57 2.63 2.69 2.57
[15:54:11.287] <TB1> INFO: ----------------------------------------------------------------------
[15:54:11.287] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[15:54:11.287] <TB1> INFO: ----------------------------------------------------------------------
[15:54:11.289] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[15:54:11.301] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[15:54:11.301] <TB1> INFO: run 1 of 1
[15:54:11.576] <TB1> INFO: Expecting 4160000 events.
[15:54:54.558] <TB1> INFO: 937785 events read in total (42426ms).
[15:55:36.234] <TB1> INFO: 1867655 events read in total (84103ms).
[15:56:17.597] <TB1> INFO: 2788240 events read in total (125466ms).
[15:56:59.031] <TB1> INFO: 3705725 events read in total (166899ms).
[15:57:19.549] <TB1> INFO: 4160000 events read in total (187417ms).
[15:57:19.586] <TB1> INFO: Test took 188285ms.
[15:57:48.684] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 162 (-1/-1) hits flags = 528 (plus default)
[15:57:48.692] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[15:57:48.692] <TB1> INFO: run 1 of 1
[15:57:48.955] <TB1> INFO: Expecting 3390400 events.
[15:58:32.824] <TB1> INFO: 989445 events read in total (43312ms).
[15:59:15.175] <TB1> INFO: 1966440 events read in total (85664ms).
[15:59:57.444] <TB1> INFO: 2931475 events read in total (127933ms).
[16:00:17.631] <TB1> INFO: 3390400 events read in total (148119ms).
[16:00:17.662] <TB1> INFO: Test took 148970ms.
[16:00:43.033] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 154 (-1/-1) hits flags = 528 (plus default)
[16:00:43.042] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[16:00:43.042] <TB1> INFO: run 1 of 1
[16:00:43.306] <TB1> INFO: Expecting 3224000 events.
[16:01:27.424] <TB1> INFO: 1014625 events read in total (43562ms).
[16:02:10.105] <TB1> INFO: 2013750 events read in total (86243ms).
[16:02:52.741] <TB1> INFO: 3001645 events read in total (128880ms).
[16:03:02.635] <TB1> INFO: 3224000 events read in total (138773ms).
[16:03:02.671] <TB1> INFO: Test took 139629ms.
[16:03:29.206] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 153 (-1/-1) hits flags = 528 (plus default)
[16:03:29.215] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[16:03:29.215] <TB1> INFO: run 1 of 1
[16:03:29.505] <TB1> INFO: Expecting 3203200 events.
[16:04:13.187] <TB1> INFO: 1017345 events read in total (43125ms).
[16:04:56.695] <TB1> INFO: 2019390 events read in total (86633ms).
[16:05:39.669] <TB1> INFO: 3010125 events read in total (129607ms).
[16:05:48.329] <TB1> INFO: 3203200 events read in total (138267ms).
[16:05:48.358] <TB1> INFO: Test took 139143ms.
[16:06:12.489] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 152 (-1/-1) hits flags = 528 (plus default)
[16:06:12.498] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[16:06:12.498] <TB1> INFO: run 1 of 1
[16:06:12.759] <TB1> INFO: Expecting 3182400 events.
[16:06:56.543] <TB1> INFO: 1020335 events read in total (43228ms).
[16:07:39.805] <TB1> INFO: 2024315 events read in total (86489ms).
[16:08:22.950] <TB1> INFO: 3017150 events read in total (129634ms).
[16:08:30.445] <TB1> INFO: 3182400 events read in total (137129ms).
[16:08:30.470] <TB1> INFO: Test took 137972ms.
[16:08:56.067] <TB1> INFO: PixTestTrim::trimBitTest() done
[16:08:56.069] <TB1> INFO: PixTestTrim::doTest() done, duration: 2909 seconds
[16:08:56.069] <TB1> INFO: Decoding statistics:
[16:08:56.069] <TB1> INFO: General information:
[16:08:56.069] <TB1> INFO: 16bit words read: 0
[16:08:56.069] <TB1> INFO: valid events total: 0
[16:08:56.069] <TB1> INFO: empty events: 0
[16:08:56.069] <TB1> INFO: valid events with pixels: 0
[16:08:56.069] <TB1> INFO: valid pixel hits: 0
[16:08:56.069] <TB1> INFO: Event errors: 0
[16:08:56.069] <TB1> INFO: start marker: 0
[16:08:56.069] <TB1> INFO: stop marker: 0
[16:08:56.069] <TB1> INFO: overflow: 0
[16:08:56.069] <TB1> INFO: invalid 5bit words: 0
[16:08:56.069] <TB1> INFO: invalid XOR eye diagram: 0
[16:08:56.069] <TB1> INFO: frame (failed synchr.): 0
[16:08:56.069] <TB1> INFO: idle data (no TBM trl): 0
[16:08:56.069] <TB1> INFO: no data (only TBM hdr): 0
[16:08:56.069] <TB1> INFO: TBM errors: 0
[16:08:56.069] <TB1> INFO: flawed TBM headers: 0
[16:08:56.069] <TB1> INFO: flawed TBM trailers: 0
[16:08:56.069] <TB1> INFO: event ID mismatches: 0
[16:08:56.069] <TB1> INFO: ROC errors: 0
[16:08:56.069] <TB1> INFO: missing ROC header(s): 0
[16:08:56.069] <TB1> INFO: misplaced readback start: 0
[16:08:56.069] <TB1> INFO: Pixel decoding errors: 0
[16:08:56.069] <TB1> INFO: pixel data incomplete: 0
[16:08:56.069] <TB1> INFO: pixel address: 0
[16:08:56.069] <TB1> INFO: pulse height fill bit: 0
[16:08:56.069] <TB1> INFO: buffer corruption: 0
[16:08:56.778] <TB1> INFO: ######################################################################
[16:08:56.778] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[16:08:56.778] <TB1> INFO: ######################################################################
[16:08:57.057] <TB1> INFO: Expecting 41600 events.
[16:09:00.833] <TB1> INFO: 41600 events read in total (3219ms).
[16:09:00.833] <TB1> INFO: Test took 4054ms.
[16:09:01.314] <TB1> INFO: Expecting 41600 events.
[16:09:05.127] <TB1> INFO: 41600 events read in total (3257ms).
[16:09:05.127] <TB1> INFO: Test took 4090ms.
[16:09:05.428] <TB1> INFO: Expecting 41600 events.
[16:09:09.214] <TB1> INFO: 41600 events read in total (3230ms).
[16:09:09.214] <TB1> INFO: Test took 4063ms.
[16:09:09.470] <TB1> INFO: Expecting 2560 events.
[16:09:10.328] <TB1> INFO: 2560 events read in total (302ms).
[16:09:10.328] <TB1> INFO: Test took 1101ms.
[16:09:10.634] <TB1> INFO: Expecting 2560 events.
[16:09:11.488] <TB1> INFO: 2560 events read in total (298ms).
[16:09:11.488] <TB1> INFO: Test took 1159ms.
[16:09:11.793] <TB1> INFO: Expecting 2560 events.
[16:09:12.645] <TB1> INFO: 2560 events read in total (296ms).
[16:09:12.645] <TB1> INFO: Test took 1157ms.
[16:09:12.951] <TB1> INFO: Expecting 2560 events.
[16:09:13.804] <TB1> INFO: 2560 events read in total (296ms).
[16:09:13.804] <TB1> INFO: Test took 1158ms.
[16:09:14.109] <TB1> INFO: Expecting 2560 events.
[16:09:14.963] <TB1> INFO: 2560 events read in total (297ms).
[16:09:14.963] <TB1> INFO: Test took 1159ms.
[16:09:15.268] <TB1> INFO: Expecting 2560 events.
[16:09:16.122] <TB1> INFO: 2560 events read in total (298ms).
[16:09:16.122] <TB1> INFO: Test took 1159ms.
[16:09:16.427] <TB1> INFO: Expecting 2560 events.
[16:09:17.280] <TB1> INFO: 2560 events read in total (297ms).
[16:09:17.280] <TB1> INFO: Test took 1157ms.
[16:09:17.585] <TB1> INFO: Expecting 2560 events.
[16:09:18.438] <TB1> INFO: 2560 events read in total (297ms).
[16:09:18.438] <TB1> INFO: Test took 1158ms.
[16:09:18.743] <TB1> INFO: Expecting 2560 events.
[16:09:19.597] <TB1> INFO: 2560 events read in total (298ms).
[16:09:19.597] <TB1> INFO: Test took 1159ms.
[16:09:19.902] <TB1> INFO: Expecting 2560 events.
[16:09:20.754] <TB1> INFO: 2560 events read in total (296ms).
[16:09:20.755] <TB1> INFO: Test took 1158ms.
[16:09:21.060] <TB1> INFO: Expecting 2560 events.
[16:09:21.917] <TB1> INFO: 2560 events read in total (301ms).
[16:09:21.917] <TB1> INFO: Test took 1162ms.
[16:09:22.222] <TB1> INFO: Expecting 2560 events.
[16:09:23.075] <TB1> INFO: 2560 events read in total (296ms).
[16:09:23.075] <TB1> INFO: Test took 1157ms.
[16:09:23.381] <TB1> INFO: Expecting 2560 events.
[16:09:24.234] <TB1> INFO: 2560 events read in total (297ms).
[16:09:24.234] <TB1> INFO: Test took 1158ms.
[16:09:24.539] <TB1> INFO: Expecting 2560 events.
[16:09:25.395] <TB1> INFO: 2560 events read in total (299ms).
[16:09:25.395] <TB1> INFO: Test took 1161ms.
[16:09:25.700] <TB1> INFO: Expecting 2560 events.
[16:09:26.553] <TB1> INFO: 2560 events read in total (297ms).
[16:09:26.553] <TB1> INFO: Test took 1157ms.
[16:09:26.859] <TB1> INFO: Expecting 2560 events.
[16:09:27.714] <TB1> INFO: 2560 events read in total (299ms).
[16:09:27.715] <TB1> INFO: Test took 1161ms.
[16:09:27.723] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:09:28.021] <TB1> INFO: Expecting 655360 events.
[16:09:44.785] <TB1> INFO: 655360 events read in total (16207ms).
[16:09:44.794] <TB1> INFO: Expecting 655360 events.
[16:10:01.483] <TB1> INFO: 655360 events read in total (16287ms).
[16:10:01.498] <TB1> INFO: Expecting 655360 events.
[16:10:18.297] <TB1> INFO: 655360 events read in total (16398ms).
[16:10:18.314] <TB1> INFO: Expecting 655360 events.
[16:10:34.966] <TB1> INFO: 655360 events read in total (16250ms).
[16:10:34.986] <TB1> INFO: Expecting 655360 events.
[16:10:51.929] <TB1> INFO: 655360 events read in total (16541ms).
[16:10:51.951] <TB1> INFO: Expecting 655360 events.
[16:11:08.738] <TB1> INFO: 655360 events read in total (16385ms).
[16:11:08.763] <TB1> INFO: Expecting 655360 events.
[16:11:25.507] <TB1> INFO: 655360 events read in total (16342ms).
[16:11:25.535] <TB1> INFO: Expecting 655360 events.
[16:11:42.320] <TB1> INFO: 655360 events read in total (16383ms).
[16:11:42.353] <TB1> INFO: Expecting 655360 events.
[16:11:59.148] <TB1> INFO: 655360 events read in total (16393ms).
[16:11:59.183] <TB1> INFO: Expecting 655360 events.
[16:12:15.757] <TB1> INFO: 655360 events read in total (16172ms).
[16:12:15.797] <TB1> INFO: Expecting 655360 events.
[16:12:32.371] <TB1> INFO: 655360 events read in total (16172ms).
[16:12:32.414] <TB1> INFO: Expecting 655360 events.
[16:12:49.031] <TB1> INFO: 655360 events read in total (16215ms).
[16:12:49.084] <TB1> INFO: Expecting 655360 events.
[16:13:05.698] <TB1> INFO: 655360 events read in total (16212ms).
[16:13:05.751] <TB1> INFO: Expecting 655360 events.
[16:13:22.405] <TB1> INFO: 655360 events read in total (16252ms).
[16:13:22.465] <TB1> INFO: Expecting 655360 events.
[16:13:39.120] <TB1> INFO: 655360 events read in total (16253ms).
[16:13:39.181] <TB1> INFO: Expecting 655360 events.
[16:13:55.716] <TB1> INFO: 655360 events read in total (16133ms).
[16:13:55.779] <TB1> INFO: Test took 268056ms.
[16:13:55.858] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:13:56.090] <TB1> INFO: Expecting 655360 events.
[16:14:13.157] <TB1> INFO: 655360 events read in total (16511ms).
[16:14:13.166] <TB1> INFO: Expecting 655360 events.
[16:14:29.997] <TB1> INFO: 655360 events read in total (16429ms).
[16:14:30.009] <TB1> INFO: Expecting 655360 events.
[16:14:46.495] <TB1> INFO: 655360 events read in total (16084ms).
[16:14:46.511] <TB1> INFO: Expecting 655360 events.
[16:15:03.043] <TB1> INFO: 655360 events read in total (16130ms).
[16:15:03.070] <TB1> INFO: Expecting 655360 events.
[16:15:19.625] <TB1> INFO: 655360 events read in total (16153ms).
[16:15:19.647] <TB1> INFO: Expecting 655360 events.
[16:15:36.086] <TB1> INFO: 655360 events read in total (16037ms).
[16:15:36.111] <TB1> INFO: Expecting 655360 events.
[16:15:52.516] <TB1> INFO: 655360 events read in total (16003ms).
[16:15:52.544] <TB1> INFO: Expecting 655360 events.
[16:16:09.415] <TB1> INFO: 655360 events read in total (16469ms).
[16:16:09.446] <TB1> INFO: Expecting 655360 events.
[16:16:26.150] <TB1> INFO: 655360 events read in total (16302ms).
[16:16:26.186] <TB1> INFO: Expecting 655360 events.
[16:16:43.037] <TB1> INFO: 655360 events read in total (16449ms).
[16:16:43.081] <TB1> INFO: Expecting 655360 events.
[16:17:00.098] <TB1> INFO: 655360 events read in total (16615ms).
[16:17:00.142] <TB1> INFO: Expecting 655360 events.
[16:17:16.929] <TB1> INFO: 655360 events read in total (16385ms).
[16:17:16.979] <TB1> INFO: Expecting 655360 events.
[16:17:33.817] <TB1> INFO: 655360 events read in total (16425ms).
[16:17:33.885] <TB1> INFO: Expecting 655360 events.
[16:17:50.786] <TB1> INFO: 655360 events read in total (16499ms).
[16:17:50.841] <TB1> INFO: Expecting 655360 events.
[16:18:07.853] <TB1> INFO: 655360 events read in total (16610ms).
[16:18:07.912] <TB1> INFO: Expecting 655360 events.
[16:18:24.640] <TB1> INFO: 655360 events read in total (16325ms).
[16:18:24.708] <TB1> INFO: Test took 268850ms.
[16:18:24.911] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:24.918] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:24.925] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:24.931] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:24.938] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:24.944] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:24.951] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:24.958] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:24.964] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:24.971] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:24.978] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:24.987] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:24.995] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:25.003] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:25.010] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[16:18:25.017] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[16:18:25.024] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[16:18:25.031] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:25.040] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[16:18:25.093] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C0.dat
[16:18:25.093] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C1.dat
[16:18:25.093] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C2.dat
[16:18:25.093] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C3.dat
[16:18:25.093] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C4.dat
[16:18:25.093] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C5.dat
[16:18:25.093] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C6.dat
[16:18:25.093] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C7.dat
[16:18:25.093] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C8.dat
[16:18:25.093] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C9.dat
[16:18:25.094] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C10.dat
[16:18:25.094] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C11.dat
[16:18:25.094] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C12.dat
[16:18:25.094] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C13.dat
[16:18:25.094] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C14.dat
[16:18:25.094] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//dacParameters35_C15.dat
[16:18:25.392] <TB1> INFO: Expecting 41600 events.
[16:18:28.879] <TB1> INFO: 41600 events read in total (2931ms).
[16:18:28.880] <TB1> INFO: Test took 3783ms.
[16:18:29.330] <TB1> INFO: Expecting 41600 events.
[16:18:32.802] <TB1> INFO: 41600 events read in total (2916ms).
[16:18:32.803] <TB1> INFO: Test took 3740ms.
[16:18:33.245] <TB1> INFO: Expecting 41600 events.
[16:18:36.743] <TB1> INFO: 41600 events read in total (2942ms).
[16:18:36.743] <TB1> INFO: Test took 3761ms.
[16:18:36.935] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:37.025] <TB1> INFO: Expecting 2560 events.
[16:18:37.882] <TB1> INFO: 2560 events read in total (301ms).
[16:18:37.883] <TB1> INFO: Test took 948ms.
[16:18:37.885] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:38.189] <TB1> INFO: Expecting 2560 events.
[16:18:39.041] <TB1> INFO: 2560 events read in total (296ms).
[16:18:39.042] <TB1> INFO: Test took 1157ms.
[16:18:39.044] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:39.347] <TB1> INFO: Expecting 2560 events.
[16:18:40.206] <TB1> INFO: 2560 events read in total (302ms).
[16:18:40.206] <TB1> INFO: Test took 1162ms.
[16:18:40.208] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:40.512] <TB1> INFO: Expecting 2560 events.
[16:18:41.370] <TB1> INFO: 2560 events read in total (301ms).
[16:18:41.370] <TB1> INFO: Test took 1162ms.
[16:18:41.373] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:41.676] <TB1> INFO: Expecting 2560 events.
[16:18:42.537] <TB1> INFO: 2560 events read in total (304ms).
[16:18:42.538] <TB1> INFO: Test took 1165ms.
[16:18:42.541] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:42.844] <TB1> INFO: Expecting 2560 events.
[16:18:43.698] <TB1> INFO: 2560 events read in total (298ms).
[16:18:43.698] <TB1> INFO: Test took 1157ms.
[16:18:43.700] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:44.005] <TB1> INFO: Expecting 2560 events.
[16:18:44.863] <TB1> INFO: 2560 events read in total (301ms).
[16:18:44.863] <TB1> INFO: Test took 1163ms.
[16:18:44.866] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:45.169] <TB1> INFO: Expecting 2560 events.
[16:18:46.026] <TB1> INFO: 2560 events read in total (300ms).
[16:18:46.027] <TB1> INFO: Test took 1161ms.
[16:18:46.028] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:46.332] <TB1> INFO: Expecting 2560 events.
[16:18:47.195] <TB1> INFO: 2560 events read in total (306ms).
[16:18:47.196] <TB1> INFO: Test took 1168ms.
[16:18:47.199] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:47.502] <TB1> INFO: Expecting 2560 events.
[16:18:48.360] <TB1> INFO: 2560 events read in total (301ms).
[16:18:48.361] <TB1> INFO: Test took 1162ms.
[16:18:48.363] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:48.667] <TB1> INFO: Expecting 2560 events.
[16:18:49.526] <TB1> INFO: 2560 events read in total (302ms).
[16:18:49.526] <TB1> INFO: Test took 1163ms.
[16:18:49.528] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:49.831] <TB1> INFO: Expecting 2560 events.
[16:18:50.685] <TB1> INFO: 2560 events read in total (298ms).
[16:18:50.686] <TB1> INFO: Test took 1158ms.
[16:18:50.689] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:50.991] <TB1> INFO: Expecting 2560 events.
[16:18:51.845] <TB1> INFO: 2560 events read in total (297ms).
[16:18:51.846] <TB1> INFO: Test took 1158ms.
[16:18:51.848] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:52.151] <TB1> INFO: Expecting 2560 events.
[16:18:53.003] <TB1> INFO: 2560 events read in total (296ms).
[16:18:53.003] <TB1> INFO: Test took 1155ms.
[16:18:53.005] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:53.308] <TB1> INFO: Expecting 2560 events.
[16:18:54.161] <TB1> INFO: 2560 events read in total (296ms).
[16:18:54.161] <TB1> INFO: Test took 1156ms.
[16:18:54.163] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:54.466] <TB1> INFO: Expecting 2560 events.
[16:18:55.320] <TB1> INFO: 2560 events read in total (297ms).
[16:18:55.320] <TB1> INFO: Test took 1157ms.
[16:18:55.322] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:55.625] <TB1> INFO: Expecting 2560 events.
[16:18:56.478] <TB1> INFO: 2560 events read in total (296ms).
[16:18:56.479] <TB1> INFO: Test took 1157ms.
[16:18:56.480] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:56.784] <TB1> INFO: Expecting 2560 events.
[16:18:57.637] <TB1> INFO: 2560 events read in total (296ms).
[16:18:57.638] <TB1> INFO: Test took 1158ms.
[16:18:57.644] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:57.943] <TB1> INFO: Expecting 2560 events.
[16:18:58.795] <TB1> INFO: 2560 events read in total (295ms).
[16:18:58.796] <TB1> INFO: Test took 1152ms.
[16:18:58.798] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:18:59.101] <TB1> INFO: Expecting 2560 events.
[16:18:59.954] <TB1> INFO: 2560 events read in total (296ms).
[16:18:59.955] <TB1> INFO: Test took 1158ms.
[16:18:59.957] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:00.261] <TB1> INFO: Expecting 2560 events.
[16:19:01.114] <TB1> INFO: 2560 events read in total (297ms).
[16:19:01.115] <TB1> INFO: Test took 1159ms.
[16:19:01.116] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:01.420] <TB1> INFO: Expecting 2560 events.
[16:19:02.273] <TB1> INFO: 2560 events read in total (296ms).
[16:19:02.274] <TB1> INFO: Test took 1158ms.
[16:19:02.275] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:02.580] <TB1> INFO: Expecting 2560 events.
[16:19:03.435] <TB1> INFO: 2560 events read in total (299ms).
[16:19:03.435] <TB1> INFO: Test took 1160ms.
[16:19:03.437] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:03.741] <TB1> INFO: Expecting 2560 events.
[16:19:04.596] <TB1> INFO: 2560 events read in total (299ms).
[16:19:04.596] <TB1> INFO: Test took 1159ms.
[16:19:04.598] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:04.902] <TB1> INFO: Expecting 2560 events.
[16:19:05.755] <TB1> INFO: 2560 events read in total (296ms).
[16:19:05.755] <TB1> INFO: Test took 1157ms.
[16:19:05.757] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:06.061] <TB1> INFO: Expecting 2560 events.
[16:19:06.914] <TB1> INFO: 2560 events read in total (296ms).
[16:19:06.914] <TB1> INFO: Test took 1157ms.
[16:19:06.916] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:07.221] <TB1> INFO: Expecting 2560 events.
[16:19:08.074] <TB1> INFO: 2560 events read in total (297ms).
[16:19:08.074] <TB1> INFO: Test took 1158ms.
[16:19:08.076] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:08.380] <TB1> INFO: Expecting 2560 events.
[16:19:09.233] <TB1> INFO: 2560 events read in total (296ms).
[16:19:09.233] <TB1> INFO: Test took 1157ms.
[16:19:09.235] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:09.539] <TB1> INFO: Expecting 2560 events.
[16:19:10.395] <TB1> INFO: 2560 events read in total (299ms).
[16:19:10.396] <TB1> INFO: Test took 1161ms.
[16:19:10.398] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:10.702] <TB1> INFO: Expecting 2560 events.
[16:19:11.557] <TB1> INFO: 2560 events read in total (299ms).
[16:19:11.558] <TB1> INFO: Test took 1160ms.
[16:19:11.560] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:11.863] <TB1> INFO: Expecting 2560 events.
[16:19:12.718] <TB1> INFO: 2560 events read in total (298ms).
[16:19:12.718] <TB1> INFO: Test took 1158ms.
[16:19:12.721] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[16:19:13.025] <TB1> INFO: Expecting 2560 events.
[16:19:13.880] <TB1> INFO: 2560 events read in total (299ms).
[16:19:13.880] <TB1> INFO: Test took 1160ms.
[16:19:14.362] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 617 seconds
[16:19:14.362] <TB1> INFO: PH scale (per ROC): 86 80 79 78 82 74 78 74 76 78 76 67 83 78 75 79
[16:19:14.362] <TB1> INFO: PH offset (per ROC): 155 174 170 171 162 166 157 170 184 173 174 176 158 176 177 180
[16:19:14.367] <TB1> INFO: Decoding statistics:
[16:19:14.368] <TB1> INFO: General information:
[16:19:14.368] <TB1> INFO: 16bit words read: 91920
[16:19:14.368] <TB1> INFO: valid events total: 10240
[16:19:14.368] <TB1> INFO: empty events: 7740
[16:19:14.368] <TB1> INFO: valid events with pixels: 2500
[16:19:14.368] <TB1> INFO: valid pixel hits: 2500
[16:19:14.368] <TB1> INFO: Event errors: 0
[16:19:14.368] <TB1> INFO: start marker: 0
[16:19:14.368] <TB1> INFO: stop marker: 0
[16:19:14.368] <TB1> INFO: overflow: 0
[16:19:14.368] <TB1> INFO: invalid 5bit words: 0
[16:19:14.368] <TB1> INFO: invalid XOR eye diagram: 0
[16:19:14.368] <TB1> INFO: frame (failed synchr.): 0
[16:19:14.368] <TB1> INFO: idle data (no TBM trl): 0
[16:19:14.368] <TB1> INFO: no data (only TBM hdr): 0
[16:19:14.368] <TB1> INFO: TBM errors: 0
[16:19:14.368] <TB1> INFO: flawed TBM headers: 0
[16:19:14.368] <TB1> INFO: flawed TBM trailers: 0
[16:19:14.368] <TB1> INFO: event ID mismatches: 0
[16:19:14.368] <TB1> INFO: ROC errors: 0
[16:19:14.368] <TB1> INFO: missing ROC header(s): 0
[16:19:14.368] <TB1> INFO: misplaced readback start: 0
[16:19:14.368] <TB1> INFO: Pixel decoding errors: 0
[16:19:14.368] <TB1> INFO: pixel data incomplete: 0
[16:19:14.368] <TB1> INFO: pixel address: 0
[16:19:14.368] <TB1> INFO: pulse height fill bit: 0
[16:19:14.368] <TB1> INFO: buffer corruption: 0
[16:19:14.547] <TB1> INFO: ######################################################################
[16:19:14.547] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[16:19:14.547] <TB1> INFO: ######################################################################
[16:19:14.556] <TB1> INFO: scanning low vcal = 10
[16:19:14.811] <TB1> INFO: Expecting 41600 events.
[16:19:18.332] <TB1> INFO: 41600 events read in total (2965ms).
[16:19:18.333] <TB1> INFO: Test took 3777ms.
[16:19:18.334] <TB1> INFO: scanning low vcal = 20
[16:19:18.638] <TB1> INFO: Expecting 41600 events.
[16:19:22.171] <TB1> INFO: 41600 events read in total (2977ms).
[16:19:22.171] <TB1> INFO: Test took 3837ms.
[16:19:22.174] <TB1> INFO: scanning low vcal = 30
[16:19:22.477] <TB1> INFO: Expecting 41600 events.
[16:19:26.009] <TB1> INFO: 41600 events read in total (2976ms).
[16:19:26.009] <TB1> INFO: Test took 3835ms.
[16:19:26.011] <TB1> INFO: scanning low vcal = 40
[16:19:26.310] <TB1> INFO: Expecting 41600 events.
[16:19:30.462] <TB1> INFO: 41600 events read in total (3596ms).
[16:19:30.463] <TB1> INFO: Test took 4452ms.
[16:19:30.466] <TB1> INFO: scanning low vcal = 50
[16:19:30.721] <TB1> INFO: Expecting 41600 events.
[16:19:34.872] <TB1> INFO: 41600 events read in total (3595ms).
[16:19:34.873] <TB1> INFO: Test took 4407ms.
[16:19:34.876] <TB1> INFO: scanning low vcal = 60
[16:19:35.131] <TB1> INFO: Expecting 41600 events.
[16:19:39.284] <TB1> INFO: 41600 events read in total (3596ms).
[16:19:39.285] <TB1> INFO: Test took 4409ms.
[16:19:39.287] <TB1> INFO: scanning low vcal = 70
[16:19:39.543] <TB1> INFO: Expecting 41600 events.
[16:19:43.694] <TB1> INFO: 41600 events read in total (3595ms).
[16:19:43.695] <TB1> INFO: Test took 4408ms.
[16:19:43.697] <TB1> INFO: scanning low vcal = 80
[16:19:43.952] <TB1> INFO: Expecting 41600 events.
[16:19:48.103] <TB1> INFO: 41600 events read in total (3595ms).
[16:19:48.103] <TB1> INFO: Test took 4406ms.
[16:19:48.106] <TB1> INFO: scanning low vcal = 90
[16:19:48.360] <TB1> INFO: Expecting 41600 events.
[16:19:52.511] <TB1> INFO: 41600 events read in total (3595ms).
[16:19:52.512] <TB1> INFO: Test took 4406ms.
[16:19:52.515] <TB1> INFO: scanning low vcal = 100
[16:19:52.770] <TB1> INFO: Expecting 41600 events.
[16:19:56.921] <TB1> INFO: 41600 events read in total (3595ms).
[16:19:56.922] <TB1> INFO: Test took 4407ms.
[16:19:56.924] <TB1> INFO: scanning low vcal = 110
[16:19:57.179] <TB1> INFO: Expecting 41600 events.
[16:20:01.330] <TB1> INFO: 41600 events read in total (3595ms).
[16:20:01.330] <TB1> INFO: Test took 4406ms.
[16:20:01.333] <TB1> INFO: scanning low vcal = 120
[16:20:01.587] <TB1> INFO: Expecting 41600 events.
[16:20:05.736] <TB1> INFO: 41600 events read in total (3593ms).
[16:20:05.737] <TB1> INFO: Test took 4404ms.
[16:20:05.740] <TB1> INFO: scanning low vcal = 130
[16:20:05.993] <TB1> INFO: Expecting 41600 events.
[16:20:10.145] <TB1> INFO: 41600 events read in total (3596ms).
[16:20:10.145] <TB1> INFO: Test took 4405ms.
[16:20:10.148] <TB1> INFO: scanning low vcal = 140
[16:20:10.401] <TB1> INFO: Expecting 41600 events.
[16:20:14.551] <TB1> INFO: 41600 events read in total (3593ms).
[16:20:14.552] <TB1> INFO: Test took 4404ms.
[16:20:14.555] <TB1> INFO: scanning low vcal = 150
[16:20:14.807] <TB1> INFO: Expecting 41600 events.
[16:20:18.957] <TB1> INFO: 41600 events read in total (3594ms).
[16:20:18.958] <TB1> INFO: Test took 4403ms.
[16:20:18.961] <TB1> INFO: scanning low vcal = 160
[16:20:19.213] <TB1> INFO: Expecting 41600 events.
[16:20:23.366] <TB1> INFO: 41600 events read in total (3597ms).
[16:20:23.367] <TB1> INFO: Test took 4406ms.
[16:20:23.370] <TB1> INFO: scanning low vcal = 170
[16:20:23.626] <TB1> INFO: Expecting 41600 events.
[16:20:27.779] <TB1> INFO: 41600 events read in total (3597ms).
[16:20:27.779] <TB1> INFO: Test took 4409ms.
[16:20:27.783] <TB1> INFO: scanning low vcal = 180
[16:20:28.039] <TB1> INFO: Expecting 41600 events.
[16:20:32.189] <TB1> INFO: 41600 events read in total (3593ms).
[16:20:32.189] <TB1> INFO: Test took 4406ms.
[16:20:32.192] <TB1> INFO: scanning low vcal = 190
[16:20:32.447] <TB1> INFO: Expecting 41600 events.
[16:20:36.600] <TB1> INFO: 41600 events read in total (3597ms).
[16:20:36.600] <TB1> INFO: Test took 4408ms.
[16:20:36.603] <TB1> INFO: scanning low vcal = 200
[16:20:36.857] <TB1> INFO: Expecting 41600 events.
[16:20:41.008] <TB1> INFO: 41600 events read in total (3595ms).
[16:20:41.008] <TB1> INFO: Test took 4405ms.
[16:20:41.011] <TB1> INFO: scanning low vcal = 210
[16:20:41.269] <TB1> INFO: Expecting 41600 events.
[16:20:45.422] <TB1> INFO: 41600 events read in total (3596ms).
[16:20:45.423] <TB1> INFO: Test took 4412ms.
[16:20:45.425] <TB1> INFO: scanning low vcal = 220
[16:20:45.680] <TB1> INFO: Expecting 41600 events.
[16:20:49.833] <TB1> INFO: 41600 events read in total (3596ms).
[16:20:49.833] <TB1> INFO: Test took 4407ms.
[16:20:49.836] <TB1> INFO: scanning low vcal = 230
[16:20:50.093] <TB1> INFO: Expecting 41600 events.
[16:20:54.248] <TB1> INFO: 41600 events read in total (3598ms).
[16:20:54.249] <TB1> INFO: Test took 4413ms.
[16:20:54.251] <TB1> INFO: scanning low vcal = 240
[16:20:54.508] <TB1> INFO: Expecting 41600 events.
[16:20:58.661] <TB1> INFO: 41600 events read in total (3596ms).
[16:20:58.661] <TB1> INFO: Test took 4410ms.
[16:20:58.664] <TB1> INFO: scanning low vcal = 250
[16:20:58.919] <TB1> INFO: Expecting 41600 events.
[16:21:03.083] <TB1> INFO: 41600 events read in total (3607ms).
[16:21:03.083] <TB1> INFO: Test took 4419ms.
[16:21:03.087] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[16:21:03.343] <TB1> INFO: Expecting 41600 events.
[16:21:07.494] <TB1> INFO: 41600 events read in total (3595ms).
[16:21:07.495] <TB1> INFO: Test took 4408ms.
[16:21:07.497] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[16:21:07.753] <TB1> INFO: Expecting 41600 events.
[16:21:11.909] <TB1> INFO: 41600 events read in total (3599ms).
[16:21:11.909] <TB1> INFO: Test took 4411ms.
[16:21:11.912] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[16:21:12.170] <TB1> INFO: Expecting 41600 events.
[16:21:16.326] <TB1> INFO: 41600 events read in total (3600ms).
[16:21:16.326] <TB1> INFO: Test took 4414ms.
[16:21:16.329] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[16:21:16.585] <TB1> INFO: Expecting 41600 events.
[16:21:20.738] <TB1> INFO: 41600 events read in total (3596ms).
[16:21:20.739] <TB1> INFO: Test took 4410ms.
[16:21:20.741] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:21:20.998] <TB1> INFO: Expecting 41600 events.
[16:21:25.150] <TB1> INFO: 41600 events read in total (3596ms).
[16:21:25.150] <TB1> INFO: Test took 4409ms.
[16:21:25.591] <TB1> INFO: PixTestGainPedestal::measure() done
[16:21:56.354] <TB1> INFO: PixTestGainPedestal::fit() done
[16:21:56.354] <TB1> INFO: non-linearity mean: 0.956 0.960 0.956 0.950 0.966 0.951 0.962 0.956 0.957 0.956 0.957 0.961 0.951 0.963 0.954 0.953
[16:21:56.354] <TB1> INFO: non-linearity RMS: 0.006 0.006 0.007 0.008 0.006 0.007 0.005 0.006 0.008 0.007 0.006 0.006 0.007 0.005 0.007 0.008
[16:21:56.354] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[16:21:56.372] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[16:21:56.390] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[16:21:56.407] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[16:21:56.425] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[16:21:56.442] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[16:21:56.460] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[16:21:56.477] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[16:21:56.494] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[16:21:56.512] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[16:21:56.529] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[16:21:56.547] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[16:21:56.564] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[16:21:56.582] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[16:21:56.599] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[16:21:56.617] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[16:21:56.635] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 162 seconds
[16:21:56.635] <TB1> INFO: Decoding statistics:
[16:21:56.635] <TB1> INFO: General information:
[16:21:56.635] <TB1> INFO: 16bit words read: 2662400
[16:21:56.635] <TB1> INFO: valid events total: 166400
[16:21:56.635] <TB1> INFO: empty events: 0
[16:21:56.635] <TB1> INFO: valid events with pixels: 166400
[16:21:56.635] <TB1> INFO: valid pixel hits: 665581
[16:21:56.635] <TB1> INFO: Event errors: 0
[16:21:56.635] <TB1> INFO: start marker: 0
[16:21:56.635] <TB1> INFO: stop marker: 0
[16:21:56.635] <TB1> INFO: overflow: 0
[16:21:56.635] <TB1> INFO: invalid 5bit words: 0
[16:21:56.635] <TB1> INFO: invalid XOR eye diagram: 0
[16:21:56.635] <TB1> INFO: frame (failed synchr.): 0
[16:21:56.635] <TB1> INFO: idle data (no TBM trl): 0
[16:21:56.635] <TB1> INFO: no data (only TBM hdr): 0
[16:21:56.635] <TB1> INFO: TBM errors: 0
[16:21:56.635] <TB1> INFO: flawed TBM headers: 0
[16:21:56.635] <TB1> INFO: flawed TBM trailers: 0
[16:21:56.635] <TB1> INFO: event ID mismatches: 0
[16:21:56.635] <TB1> INFO: ROC errors: 0
[16:21:56.635] <TB1> INFO: missing ROC header(s): 0
[16:21:56.635] <TB1> INFO: misplaced readback start: 0
[16:21:56.635] <TB1> INFO: Pixel decoding errors: 0
[16:21:56.635] <TB1> INFO: pixel data incomplete: 0
[16:21:56.635] <TB1> INFO: pixel address: 0
[16:21:56.635] <TB1> INFO: pulse height fill bit: 0
[16:21:56.635] <TB1> INFO: buffer corruption: 0
[16:21:56.642] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C15.dat
[16:21:56.643] <TB1> INFO: ######################################################################
[16:21:56.643] <TB1> INFO: PixTestReadback::doTest()
[16:21:56.643] <TB1> INFO: ######################################################################
[16:21:56.643] <TB1> INFO: ----------------------------------------------------------------------
[16:21:56.643] <TB1> INFO: PixTestReadback::CalibrateVd()
[16:21:56.643] <TB1> INFO: ----------------------------------------------------------------------
[16:22:06.117] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C0.dat
[16:22:06.118] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C1.dat
[16:22:06.118] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C2.dat
[16:22:06.118] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C3.dat
[16:22:06.118] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C4.dat
[16:22:06.118] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C5.dat
[16:22:06.118] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C6.dat
[16:22:06.118] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C7.dat
[16:22:06.118] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C8.dat
[16:22:06.118] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C9.dat
[16:22:06.118] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C10.dat
[16:22:06.118] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C11.dat
[16:22:06.119] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C12.dat
[16:22:06.119] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C13.dat
[16:22:06.119] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C14.dat
[16:22:06.119] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C15.dat
[16:22:06.149] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:22:06.149] <TB1> INFO: ----------------------------------------------------------------------
[16:22:06.149] <TB1> INFO: PixTestReadback::CalibrateVa()
[16:22:06.149] <TB1> INFO: ----------------------------------------------------------------------
[16:22:15.652] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C0.dat
[16:22:15.652] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C1.dat
[16:22:15.652] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C2.dat
[16:22:15.652] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C3.dat
[16:22:15.652] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C4.dat
[16:22:15.652] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C5.dat
[16:22:15.652] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C6.dat
[16:22:15.652] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C7.dat
[16:22:15.652] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C8.dat
[16:22:15.652] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C9.dat
[16:22:15.653] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C10.dat
[16:22:15.653] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C11.dat
[16:22:15.653] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C12.dat
[16:22:15.653] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C13.dat
[16:22:15.653] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C14.dat
[16:22:15.653] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C15.dat
[16:22:15.682] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:22:15.682] <TB1> INFO: ----------------------------------------------------------------------
[16:22:15.682] <TB1> INFO: PixTestReadback::readbackVbg()
[16:22:15.682] <TB1> INFO: ----------------------------------------------------------------------
[16:22:22.992] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:22:22.992] <TB1> INFO: ----------------------------------------------------------------------
[16:22:22.992] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[16:22:22.992] <TB1> INFO: ----------------------------------------------------------------------
[16:22:22.992] <TB1> INFO: Vbg will be calibrated using Vd calibration
[16:22:22.992] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 155.4calibrated Vbg = 1.20092 :::*/*/*/*/
[16:22:22.992] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 158.1calibrated Vbg = 1.20274 :::*/*/*/*/
[16:22:22.992] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 156.9calibrated Vbg = 1.20115 :::*/*/*/*/
[16:22:22.992] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 149.5calibrated Vbg = 1.20701 :::*/*/*/*/
[16:22:22.992] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 160.1calibrated Vbg = 1.21338 :::*/*/*/*/
[16:22:22.992] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 157.9calibrated Vbg = 1.21464 :::*/*/*/*/
[16:22:22.992] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 144.3calibrated Vbg = 1.21791 :::*/*/*/*/
[16:22:22.992] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 155calibrated Vbg = 1.21345 :::*/*/*/*/
[16:22:22.992] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 153.6calibrated Vbg = 1.21126 :::*/*/*/*/
[16:22:22.992] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 147calibrated Vbg = 1.21467 :::*/*/*/*/
[16:22:22.992] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 155.9calibrated Vbg = 1.21879 :::*/*/*/*/
[16:22:22.992] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 148.3calibrated Vbg = 1.20814 :::*/*/*/*/
[16:22:22.992] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 147.5calibrated Vbg = 1.20584 :::*/*/*/*/
[16:22:22.992] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 151calibrated Vbg = 1.20049 :::*/*/*/*/
[16:22:22.992] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 154.4calibrated Vbg = 1.20506 :::*/*/*/*/
[16:22:22.992] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 151.5calibrated Vbg = 1.2064 :::*/*/*/*/
[16:22:22.994] <TB1> INFO: ----------------------------------------------------------------------
[16:22:22.994] <TB1> INFO: PixTestReadback::CalibrateIa()
[16:22:22.994] <TB1> INFO: ----------------------------------------------------------------------
[16:24:58.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C0.dat
[16:24:58.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C1.dat
[16:24:58.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C2.dat
[16:24:58.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C3.dat
[16:24:58.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C4.dat
[16:24:58.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C5.dat
[16:24:58.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C6.dat
[16:24:58.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C7.dat
[16:24:58.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C8.dat
[16:24:58.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C9.dat
[16:24:58.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C10.dat
[16:24:58.142] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C11.dat
[16:24:58.143] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C12.dat
[16:24:58.143] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C13.dat
[16:24:58.143] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C14.dat
[16:24:58.143] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2314_FullQualification_2016-09-07_10h23m_1473236586//004_FulltestPxar_p17//readbackCal_C15.dat
[16:24:58.172] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[16:24:58.173] <TB1> INFO: PixTestReadback::doTest() done
[16:24:58.173] <TB1> INFO: Decoding statistics:
[16:24:58.173] <TB1> INFO: General information:
[16:24:58.173] <TB1> INFO: 16bit words read: 1024
[16:24:58.173] <TB1> INFO: valid events total: 128
[16:24:58.173] <TB1> INFO: empty events: 128
[16:24:58.173] <TB1> INFO: valid events with pixels: 0
[16:24:58.173] <TB1> INFO: valid pixel hits: 0
[16:24:58.173] <TB1> INFO: Event errors: 0
[16:24:58.173] <TB1> INFO: start marker: 0
[16:24:58.173] <TB1> INFO: stop marker: 0
[16:24:58.173] <TB1> INFO: overflow: 0
[16:24:58.173] <TB1> INFO: invalid 5bit words: 0
[16:24:58.173] <TB1> INFO: invalid XOR eye diagram: 0
[16:24:58.173] <TB1> INFO: frame (failed synchr.): 0
[16:24:58.173] <TB1> INFO: idle data (no TBM trl): 0
[16:24:58.173] <TB1> INFO: no data (only TBM hdr): 0
[16:24:58.173] <TB1> INFO: TBM errors: 0
[16:24:58.173] <TB1> INFO: flawed TBM headers: 0
[16:24:58.173] <TB1> INFO: flawed TBM trailers: 0
[16:24:58.173] <TB1> INFO: event ID mismatches: 0
[16:24:58.173] <TB1> INFO: ROC errors: 0
[16:24:58.173] <TB1> INFO: missing ROC header(s): 0
[16:24:58.173] <TB1> INFO: misplaced readback start: 0
[16:24:58.173] <TB1> INFO: Pixel decoding errors: 0
[16:24:58.173] <TB1> INFO: pixel data incomplete: 0
[16:24:58.173] <TB1> INFO: pixel address: 0
[16:24:58.173] <TB1> INFO: pulse height fill bit: 0
[16:24:58.173] <TB1> INFO: buffer corruption: 0
[16:24:58.190] <TB1> INFO: Decoding statistics:
[16:24:58.190] <TB1> INFO: General information:
[16:24:58.190] <TB1> INFO: 16bit words read: 2755344
[16:24:58.190] <TB1> INFO: valid events total: 176768
[16:24:58.190] <TB1> INFO: empty events: 7868
[16:24:58.190] <TB1> INFO: valid events with pixels: 168900
[16:24:58.190] <TB1> INFO: valid pixel hits: 668081
[16:24:58.190] <TB1> INFO: Event errors: 0
[16:24:58.190] <TB1> INFO: start marker: 0
[16:24:58.190] <TB1> INFO: stop marker: 0
[16:24:58.190] <TB1> INFO: overflow: 0
[16:24:58.190] <TB1> INFO: invalid 5bit words: 0
[16:24:58.190] <TB1> INFO: invalid XOR eye diagram: 0
[16:24:58.190] <TB1> INFO: frame (failed synchr.): 0
[16:24:58.190] <TB1> INFO: idle data (no TBM trl): 0
[16:24:58.190] <TB1> INFO: no data (only TBM hdr): 0
[16:24:58.190] <TB1> INFO: TBM errors: 0
[16:24:58.190] <TB1> INFO: flawed TBM headers: 0
[16:24:58.190] <TB1> INFO: flawed TBM trailers: 0
[16:24:58.190] <TB1> INFO: event ID mismatches: 0
[16:24:58.190] <TB1> INFO: ROC errors: 0
[16:24:58.190] <TB1> INFO: missing ROC header(s): 0
[16:24:58.190] <TB1> INFO: misplaced readback start: 0
[16:24:58.190] <TB1> INFO: Pixel decoding errors: 0
[16:24:58.190] <TB1> INFO: pixel data incomplete: 0
[16:24:58.190] <TB1> INFO: pixel address: 0
[16:24:58.190] <TB1> INFO: pulse height fill bit: 0
[16:24:58.190] <TB1> INFO: buffer corruption: 0
[16:24:58.190] <TB1> INFO: enter test to run
[16:24:58.190] <TB1> INFO: test: exit no parameter change
[16:24:58.405] <TB1> QUIET: Connection to board 153 closed.
[16:24:58.484] <TB1> INFO: pXar: this is the end, my friend