[07:06:33.131] <TB1> INFO: *** Welcome to pxar ***
[07:06:33.131] <TB1> INFO: *** Today: 2016/05/09
[07:06:33.236] <TB1> INFO: *** Version: 9751-dirty
[07:06:33.236] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters_C15.dat
[07:06:33.238] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//tbmParameters_C0b.dat
[07:06:33.238] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//defaultMaskFile.dat
[07:06:33.238] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//trimParameters_C15.dat
[07:06:33.308] <TB1> INFO: clk: 4
[07:06:33.308] <TB1> INFO: ctr: 4
[07:06:33.308] <TB1> INFO: sda: 19
[07:06:33.308] <TB1> INFO: tin: 9
[07:06:33.308] <TB1> INFO: level: 15
[07:06:33.308] <TB1> INFO: triggerdelay: 0
[07:06:33.308] <TB1> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[07:06:33.308] <TB1> INFO: Log level: INFO
[07:06:33.315] <TB1> INFO: Found DTB DTB_WXBYFL
[07:06:33.325] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[07:06:33.327] <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:
------------------------------------------------------
[07:06:33.328] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[07:06:34.878] <TB1> INFO: DUT info:
[07:06:34.878] <TB1> INFO: The DUT currently contains the following objects:
[07:06:34.878] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[07:06:34.879] <TB1> INFO: TBM Core alpha (0): 7 registers set
[07:06:34.879] <TB1> INFO: TBM Core beta (1): 7 registers set
[07:06:34.879] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:06:34.879] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:06:34.879] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:06:34.879] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:06:34.879] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:06:34.879] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:06:34.879] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:06:34.879] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:06:34.879] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:06:34.879] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:06:34.879] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:06:34.879] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:06:34.879] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:06:34.879] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:06:34.879] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:06:34.879] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:06:34.879] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:06:35.280] <TB1> INFO: enter 'restricted' command line mode
[07:06:35.280] <TB1> INFO: enter test to run
[07:06:35.280] <TB1> INFO: test: pretest no parameter change
[07:06:35.280] <TB1> INFO: running: pretest
[07:06:35.287] <TB1> INFO: ----------------------------------------------------------------------
[07:06:35.287] <TB1> INFO: PixTestPretest::programROC()
[07:06:35.287] <TB1> INFO: ----------------------------------------------------------------------
[07:06:53.302] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[07:06:53.302] <TB1> INFO: IA differences per ROC: 19.3 19.3 18.5 19.3 18.5 18.5 19.3 19.3 17.7 18.5 18.5 18.5 16.9 19.3 17.7 15.3
[07:06:53.400] <TB1> INFO: enter test to run
[07:06:53.400] <TB1> INFO: test: pretest no parameter change
[07:06:53.400] <TB1> INFO: running: pretest
[07:06:53.401] <TB1> INFO: ----------------------------------------------------------------------
[07:06:53.401] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[07:06:53.401] <TB1> INFO: ----------------------------------------------------------------------
[07:07:14.659] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 390.7 mA = 24.4187 mA/ROC
[07:07:14.659] <TB1> INFO: i(loss) [mA/ROC]: 20.9 20.9 20.9 20.9 20.1 20.1 20.9 19.3 20.1 19.3 20.1 20.1 20.1 20.1 20.1 20.1
[07:07:14.689] <TB1> INFO: enter test to run
[07:07:14.690] <TB1> INFO: test: pretest no parameter change
[07:07:14.690] <TB1> INFO: running: pretest
[07:07:14.690] <TB1> INFO: ----------------------------------------------------------------------
[07:07:14.690] <TB1> INFO: PixTestPretest::findTiming()
[07:07:14.690] <TB1> INFO: ----------------------------------------------------------------------
[07:07:14.690] <TB1> INFO: PixTestCmd::init()
[07:07:15.632] <TB1> WARNING: Not unmasking DUT, not setting Calibrate bits!
[07:08:43.596] <TB1> INFO: TBM phases: 160MHz: 0, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[07:08:43.596] <TB1> INFO: (success/tries = 100/100), width = 4
[07:08:43.597] <TB1> INFO: enter test to run
[07:08:43.597] <TB1> INFO: test: pretest no parameter change
[07:08:43.597] <TB1> INFO: running: pretest
[07:08:43.598] <TB1> INFO: ----------------------------------------------------------------------
[07:08:43.598] <TB1> INFO: PixTestPretest::findWorkingPixel()
[07:08:43.598] <TB1> INFO: ----------------------------------------------------------------------
[07:08:43.689] <TB1> INFO: Expecting 231680 events.
[07:08:49.468] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L497> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[07:08:49.549] <TB1> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[07:08:53.997] <TB1> INFO: 231680 events read in total (9752ms).
[07:08:54.002] <TB1> INFO: Test took 10401ms.
[07:08:54.242] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[07:08:54.306] <TB1> INFO: enter test to run
[07:08:54.306] <TB1> INFO: test: pretest no parameter change
[07:08:54.306] <TB1> INFO: running: pretest
[07:08:54.307] <TB1> INFO: ----------------------------------------------------------------------
[07:08:54.307] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[07:08:54.307] <TB1> INFO: ----------------------------------------------------------------------
[07:08:54.399] <TB1> INFO: Expecting 231680 events.
[07:09:04.686] <TB1> INFO: 231680 events read in total (9730ms).
[07:09:04.690] <TB1> INFO: Test took 10379ms.
[07:09:04.928] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[07:09:04.928] <TB1> INFO: CalDel: 156 164 143 147 145 128 140 129 141 120 150 136 109 154 136 154
[07:09:04.928] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 54 51 51 51
[07:09:04.960] <TB1> INFO: enter test to run
[07:09:04.960] <TB1> INFO: test: pretest no parameter change
[07:09:04.960] <TB1> INFO: running: pretest
[07:09:04.961] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters_C0.dat
[07:09:04.961] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters_C1.dat
[07:09:04.961] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters_C2.dat
[07:09:04.961] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters_C3.dat
[07:09:04.961] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters_C4.dat
[07:09:04.961] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters_C5.dat
[07:09:04.961] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters_C6.dat
[07:09:04.961] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters_C7.dat
[07:09:04.961] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters_C8.dat
[07:09:04.961] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters_C9.dat
[07:09:04.961] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters_C10.dat
[07:09:04.962] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters_C11.dat
[07:09:04.962] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters_C12.dat
[07:09:04.962] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters_C13.dat
[07:09:04.962] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters_C14.dat
[07:09:04.962] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters_C15.dat
[07:09:04.962] <TB1> INFO: enter test to run
[07:09:04.962] <TB1> INFO: test: fulltest no parameter change
[07:09:04.962] <TB1> INFO: running: fulltest
[07:09:04.962] <TB1> INFO: ######################################################################
[07:09:04.962] <TB1> INFO: PixTestFullTest::doTest()
[07:09:04.962] <TB1> INFO: ######################################################################
[07:09:04.963] <TB1> INFO: ######################################################################
[07:09:04.963] <TB1> INFO: PixTestAlive::doTest()
[07:09:04.963] <TB1> INFO: ######################################################################
[07:09:04.964] <TB1> INFO: ----------------------------------------------------------------------
[07:09:04.965] <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)
[07:09:04.965] <TB1> INFO: ----------------------------------------------------------------------
[07:09:05.239] <TB1> INFO: Expecting 41600 events.
[07:09:09.018] <TB1> INFO: 41600 events read in total (3223ms).
[07:09:09.019] <TB1> INFO: Test took 4053ms.
[07:09:09.242] <TB1> INFO: PixTestAlive::aliveTest() done
[07:09:09.242] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0
[07:09:09.244] <TB1> INFO: ----------------------------------------------------------------------
[07:09:09.244] <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)
[07:09:09.244] <TB1> INFO: ----------------------------------------------------------------------
[07:09:09.506] <TB1> INFO: Expecting 41600 events.
[07:09:12.458] <TB1> INFO: 41600 events read in total (2396ms).
[07:09:12.459] <TB1> INFO: Test took 3214ms.
[07:09:12.459] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[07:09:12.697] <TB1> INFO: PixTestAlive::maskTest() done
[07:09:12.697] <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
[07:09:12.698] <TB1> INFO: ----------------------------------------------------------------------
[07:09:12.698] <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)
[07:09:12.698] <TB1> INFO: ----------------------------------------------------------------------
[07:09:12.993] <TB1> INFO: Expecting 41600 events.
[07:09:16.844] <TB1> INFO: 41600 events read in total (3295ms).
[07:09:16.844] <TB1> INFO: Test took 4145ms.
[07:09:17.073] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[07:09:17.073] <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
[07:09:17.073] <TB1> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[07:09:17.073] <TB1> INFO: Decoding statistics:
[07:09:17.073] <TB1> INFO: General information:
[07:09:17.073] <TB1> INFO: 16bit words read: 0
[07:09:17.073] <TB1> INFO: valid events total: 0
[07:09:17.073] <TB1> INFO: empty events: 0
[07:09:17.073] <TB1> INFO: valid events with pixels: 0
[07:09:17.074] <TB1> INFO: valid pixel hits: 0
[07:09:17.074] <TB1> INFO: Event errors: 0
[07:09:17.074] <TB1> INFO: start marker: 0
[07:09:17.074] <TB1> INFO: stop marker: 0
[07:09:17.074] <TB1> INFO: overflow: 0
[07:09:17.074] <TB1> INFO: invalid 5bit words: 0
[07:09:17.074] <TB1> INFO: invalid XOR eye diagram: 0
[07:09:17.074] <TB1> INFO: frame (failed synchr.): 0
[07:09:17.074] <TB1> INFO: idle data (no TBM trl): 0
[07:09:17.074] <TB1> INFO: no data (only TBM hdr): 0
[07:09:17.074] <TB1> INFO: TBM errors: 0
[07:09:17.074] <TB1> INFO: flawed TBM headers: 0
[07:09:17.074] <TB1> INFO: flawed TBM trailers: 0
[07:09:17.074] <TB1> INFO: event ID mismatches: 0
[07:09:17.074] <TB1> INFO: ROC errors: 0
[07:09:17.074] <TB1> INFO: missing ROC header(s): 0
[07:09:17.074] <TB1> INFO: misplaced readback start: 0
[07:09:17.074] <TB1> INFO: Pixel decoding errors: 0
[07:09:17.074] <TB1> INFO: pixel data incomplete: 0
[07:09:17.074] <TB1> INFO: pixel address: 0
[07:09:17.074] <TB1> INFO: pulse height fill bit: 0
[07:09:17.074] <TB1> INFO: buffer corruption: 0
[07:09:17.088] <TB1> INFO: ######################################################################
[07:09:17.088] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[07:09:17.088] <TB1> INFO: ######################################################################
[07:09:17.090] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[07:09:17.102] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:09:17.102] <TB1> INFO: run 1 of 1
[07:09:17.368] <TB1> INFO: Expecting 3120000 events.
[07:09:58.767] <TB1> INFO: 871170 events read in total (40843ms).
[07:10:39.410] <TB1> INFO: 1728355 events read in total (81486ms).
[07:11:20.267] <TB1> INFO: 2594745 events read in total (122344ms).
[07:11:45.006] <TB1> INFO: 3120000 events read in total (147082ms).
[07:11:45.056] <TB1> INFO: Test took 147954ms.
[07:12:08.464] <TB1> INFO: PixTestBBMap::doTest() done, duration: 171 seconds
[07:12:08.464] <TB1> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 1 2 0 0 0 1 6 2 5
[07:12:08.464] <TB1> INFO: separation cut (per ROC): 93 100 111 89 101 94 97 88 83 100 74 93 110 87 91 72
[07:12:08.464] <TB1> INFO: Decoding statistics:
[07:12:08.464] <TB1> INFO: General information:
[07:12:08.464] <TB1> INFO: 16bit words read: 0
[07:12:08.464] <TB1> INFO: valid events total: 0
[07:12:08.464] <TB1> INFO: empty events: 0
[07:12:08.464] <TB1> INFO: valid events with pixels: 0
[07:12:08.464] <TB1> INFO: valid pixel hits: 0
[07:12:08.464] <TB1> INFO: Event errors: 0
[07:12:08.464] <TB1> INFO: start marker: 0
[07:12:08.464] <TB1> INFO: stop marker: 0
[07:12:08.464] <TB1> INFO: overflow: 0
[07:12:08.464] <TB1> INFO: invalid 5bit words: 0
[07:12:08.464] <TB1> INFO: invalid XOR eye diagram: 0
[07:12:08.464] <TB1> INFO: frame (failed synchr.): 0
[07:12:08.464] <TB1> INFO: idle data (no TBM trl): 0
[07:12:08.464] <TB1> INFO: no data (only TBM hdr): 0
[07:12:08.464] <TB1> INFO: TBM errors: 0
[07:12:08.464] <TB1> INFO: flawed TBM headers: 0
[07:12:08.464] <TB1> INFO: flawed TBM trailers: 0
[07:12:08.464] <TB1> INFO: event ID mismatches: 0
[07:12:08.464] <TB1> INFO: ROC errors: 0
[07:12:08.464] <TB1> INFO: missing ROC header(s): 0
[07:12:08.464] <TB1> INFO: misplaced readback start: 0
[07:12:08.464] <TB1> INFO: Pixel decoding errors: 0
[07:12:08.464] <TB1> INFO: pixel data incomplete: 0
[07:12:08.464] <TB1> INFO: pixel address: 0
[07:12:08.464] <TB1> INFO: pulse height fill bit: 0
[07:12:08.464] <TB1> INFO: buffer corruption: 0
[07:12:08.547] <TB1> INFO: ######################################################################
[07:12:08.547] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[07:12:08.547] <TB1> INFO: ######################################################################
[07:12:08.547] <TB1> INFO: ----------------------------------------------------------------------
[07:12:08.547] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[07:12:08.547] <TB1> INFO: ----------------------------------------------------------------------
[07:12:08.547] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[07:12:08.555] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:12:08.555] <TB1> INFO: run 1 of 1
[07:12:08.828] <TB1> INFO: Expecting 26208000 events.
[07:12:42.272] <TB1> INFO: 922250 events read in total (32888ms).
[07:13:15.026] <TB1> INFO: 1825900 events read in total (65642ms).
[07:13:48.002] <TB1> INFO: 2730100 events read in total (98618ms).
[07:14:21.367] <TB1> INFO: 3632200 events read in total (131983ms).
[07:14:54.458] <TB1> INFO: 4529500 events read in total (165074ms).
[07:15:27.258] <TB1> INFO: 5426550 events read in total (197874ms).
[07:16:00.383] <TB1> INFO: 6324200 events read in total (230999ms).
[07:16:32.992] <TB1> INFO: 7220350 events read in total (263608ms).
[07:17:06.106] <TB1> INFO: 8117400 events read in total (296722ms).
[07:17:39.000] <TB1> INFO: 9010200 events read in total (329616ms).
[07:18:11.940] <TB1> INFO: 9907350 events read in total (362556ms).
[07:18:44.760] <TB1> INFO: 10802250 events read in total (395376ms).
[07:19:17.846] <TB1> INFO: 11696700 events read in total (428462ms).
[07:19:50.186] <TB1> INFO: 12588200 events read in total (460802ms).
[07:20:22.866] <TB1> INFO: 13473850 events read in total (493482ms).
[07:20:55.536] <TB1> INFO: 14356450 events read in total (526152ms).
[07:21:28.184] <TB1> INFO: 15238250 events read in total (558800ms).
[07:22:00.919] <TB1> INFO: 16116950 events read in total (591535ms).
[07:22:33.152] <TB1> INFO: 16995700 events read in total (623768ms).
[07:23:05.624] <TB1> INFO: 17876150 events read in total (656240ms).
[07:23:38.141] <TB1> INFO: 18754200 events read in total (688757ms).
[07:24:10.862] <TB1> INFO: 19629350 events read in total (721478ms).
[07:24:43.240] <TB1> INFO: 20507800 events read in total (753856ms).
[07:25:16.079] <TB1> INFO: 21383950 events read in total (786695ms).
[07:25:48.674] <TB1> INFO: 22260950 events read in total (819290ms).
[07:26:21.366] <TB1> INFO: 23137350 events read in total (851982ms).
[07:26:53.725] <TB1> INFO: 24014950 events read in total (884341ms).
[07:27:26.462] <TB1> INFO: 24894750 events read in total (917078ms).
[07:27:58.750] <TB1> INFO: 25777800 events read in total (949366ms).
[07:28:14.095] <TB1> INFO: 26208000 events read in total (964711ms).
[07:28:14.122] <TB1> INFO: Test took 965567ms.
[07:28:14.347] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:28:15.755] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:28:17.243] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:28:18.598] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:28:20.003] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:28:21.521] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:28:22.896] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:28:24.320] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:28:25.888] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:28:27.336] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:28:28.724] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:28:30.170] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:28:31.589] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:28:33.035] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:28:34.433] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:28:35.813] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:28:37.243] <TB1> INFO: PixTestScurves::scurves() done
[07:28:37.243] <TB1> INFO: Vcal mean: 92.94 89.55 108.51 87.90 97.39 90.94 97.43 87.45 89.51 99.99 79.11 86.50 104.28 87.29 92.93 86.65
[07:28:37.243] <TB1> INFO: Vcal RMS: 5.70 5.18 6.05 5.00 5.66 5.80 5.27 5.45 5.12 6.92 4.17 5.24 7.20 5.53 5.96 5.37
[07:28:37.244] <TB1> INFO: PixTestScurves::fullTest() done, duration: 988 seconds
[07:28:37.244] <TB1> INFO: Decoding statistics:
[07:28:37.244] <TB1> INFO: General information:
[07:28:37.244] <TB1> INFO: 16bit words read: 0
[07:28:37.244] <TB1> INFO: valid events total: 0
[07:28:37.244] <TB1> INFO: empty events: 0
[07:28:37.244] <TB1> INFO: valid events with pixels: 0
[07:28:37.244] <TB1> INFO: valid pixel hits: 0
[07:28:37.244] <TB1> INFO: Event errors: 0
[07:28:37.244] <TB1> INFO: start marker: 0
[07:28:37.244] <TB1> INFO: stop marker: 0
[07:28:37.244] <TB1> INFO: overflow: 0
[07:28:37.244] <TB1> INFO: invalid 5bit words: 0
[07:28:37.244] <TB1> INFO: invalid XOR eye diagram: 0
[07:28:37.244] <TB1> INFO: frame (failed synchr.): 0
[07:28:37.244] <TB1> INFO: idle data (no TBM trl): 0
[07:28:37.244] <TB1> INFO: no data (only TBM hdr): 0
[07:28:37.244] <TB1> INFO: TBM errors: 0
[07:28:37.244] <TB1> INFO: flawed TBM headers: 0
[07:28:37.244] <TB1> INFO: flawed TBM trailers: 0
[07:28:37.244] <TB1> INFO: event ID mismatches: 0
[07:28:37.244] <TB1> INFO: ROC errors: 0
[07:28:37.244] <TB1> INFO: missing ROC header(s): 0
[07:28:37.244] <TB1> INFO: misplaced readback start: 0
[07:28:37.244] <TB1> INFO: Pixel decoding errors: 0
[07:28:37.244] <TB1> INFO: pixel data incomplete: 0
[07:28:37.244] <TB1> INFO: pixel address: 0
[07:28:37.244] <TB1> INFO: pulse height fill bit: 0
[07:28:37.244] <TB1> INFO: buffer corruption: 0
[07:28:37.313] <TB1> INFO: ######################################################################
[07:28:37.313] <TB1> INFO: PixTestTrim::doTest()
[07:28:37.313] <TB1> INFO: ######################################################################
[07:28:37.314] <TB1> INFO: ----------------------------------------------------------------------
[07:28:37.314] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[07:28:37.314] <TB1> INFO: ----------------------------------------------------------------------
[07:28:37.392] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[07:28:37.392] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[07:28:37.399] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[07:28:37.399] <TB1> INFO: run 1 of 1
[07:28:37.662] <TB1> INFO: Expecting 6281600 events.
[07:29:17.341] <TB1> INFO: 1085430 events read in total (39122ms).
[07:29:56.482] <TB1> INFO: 2165410 events read in total (78263ms).
[07:30:35.175] <TB1> INFO: 3240210 events read in total (116956ms).
[07:31:14.078] <TB1> INFO: 4321580 events read in total (155859ms).
[07:31:53.367] <TB1> INFO: 5405760 events read in total (195148ms).
[07:32:24.772] <TB1> INFO: 6281600 events read in total (226553ms).
[07:32:24.804] <TB1> INFO: Test took 227405ms.
[07:32:44.436] <TB1> INFO: ROC 0 VthrComp = 95
[07:32:44.436] <TB1> INFO: ROC 1 VthrComp = 94
[07:32:44.436] <TB1> INFO: ROC 2 VthrComp = 105
[07:32:44.436] <TB1> INFO: ROC 3 VthrComp = 91
[07:32:44.436] <TB1> INFO: ROC 4 VthrComp = 99
[07:32:44.436] <TB1> INFO: ROC 5 VthrComp = 92
[07:32:44.436] <TB1> INFO: ROC 6 VthrComp = 101
[07:32:44.436] <TB1> INFO: ROC 7 VthrComp = 92
[07:32:44.436] <TB1> INFO: ROC 8 VthrComp = 93
[07:32:44.436] <TB1> INFO: ROC 9 VthrComp = 98
[07:32:44.436] <TB1> INFO: ROC 10 VthrComp = 84
[07:32:44.437] <TB1> INFO: ROC 11 VthrComp = 94
[07:32:44.437] <TB1> INFO: ROC 12 VthrComp = 101
[07:32:44.437] <TB1> INFO: ROC 13 VthrComp = 88
[07:32:44.437] <TB1> INFO: ROC 14 VthrComp = 94
[07:32:44.437] <TB1> INFO: ROC 15 VthrComp = 84
[07:32:44.437] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[07:32:44.437] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[07:32:44.445] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[07:32:44.445] <TB1> INFO: run 1 of 1
[07:32:44.704] <TB1> INFO: Expecting 6281600 events.
[07:33:19.882] <TB1> INFO: 757980 events read in total (34622ms).
[07:33:54.420] <TB1> INFO: 1513590 events read in total (69160ms).
[07:34:28.997] <TB1> INFO: 2269070 events read in total (103737ms).
[07:35:03.942] <TB1> INFO: 3025710 events read in total (138682ms).
[07:35:38.591] <TB1> INFO: 3776560 events read in total (173331ms).
[07:36:12.894] <TB1> INFO: 4523880 events read in total (207634ms).
[07:36:47.327] <TB1> INFO: 5269860 events read in total (242067ms).
[07:37:21.860] <TB1> INFO: 6015520 events read in total (276600ms).
[07:37:34.263] <TB1> INFO: 6281600 events read in total (289003ms).
[07:37:34.322] <TB1> INFO: Test took 289877ms.
[07:37:58.137] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 59.5891 for pixel 7/11 mean/min/max = 45.9428/32.289/59.5966
[07:37:58.137] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 58.0749 for pixel 0/15 mean/min/max = 45.5302/32.8384/58.2221
[07:37:58.147] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 65.4877 for pixel 3/47 mean/min/max = 49.0916/32.6773/65.5058
[07:37:58.148] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 57.107 for pixel 15/65 mean/min/max = 45.1272/32.9895/57.2649
[07:37:58.148] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 58.8903 for pixel 51/3 mean/min/max = 45.5211/32.0049/59.0372
[07:37:58.148] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 60.5408 for pixel 11/0 mean/min/max = 46.3785/32.087/60.6701
[07:37:58.149] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 58.1716 for pixel 51/79 mean/min/max = 45.3305/32.2658/58.3951
[07:37:58.149] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 58.3007 for pixel 28/79 mean/min/max = 45.2952/32.1957/58.3946
[07:37:58.149] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 58.0632 for pixel 51/69 mean/min/max = 45.2811/32.4321/58.1302
[07:37:58.150] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 65.0304 for pixel 9/79 mean/min/max = 47.9933/30.9234/65.0633
[07:37:58.150] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 56.6099 for pixel 23/75 mean/min/max = 44.6962/32.7221/56.6703
[07:37:58.150] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 57.6255 for pixel 22/79 mean/min/max = 44.8418/32.047/57.6366
[07:37:58.151] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 69.7223 for pixel 30/79 mean/min/max = 50.4429/31.1472/69.7386
[07:37:58.151] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 59.056 for pixel 23/45 mean/min/max = 45.6354/32.1859/59.085
[07:37:58.151] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 60.1733 for pixel 18/77 mean/min/max = 45.949/31.682/60.216
[07:37:58.152] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 60.6737 for pixel 41/9 mean/min/max = 46.6526/32.5977/60.7075
[07:37:58.152] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:37:58.241] <TB1> INFO: Expecting 514560 events.
[07:38:11.256] <TB1> INFO: 514560 events read in total (12459ms).
[07:38:11.262] <TB1> INFO: Expecting 514560 events.
[07:38:23.971] <TB1> INFO: 514560 events read in total (12296ms).
[07:38:23.979] <TB1> INFO: Expecting 514560 events.
[07:38:36.768] <TB1> INFO: 514560 events read in total (12376ms).
[07:38:36.778] <TB1> INFO: Expecting 514560 events.
[07:38:49.533] <TB1> INFO: 514560 events read in total (12338ms).
[07:38:49.544] <TB1> INFO: Expecting 514560 events.
[07:39:02.169] <TB1> INFO: 514560 events read in total (12219ms).
[07:39:02.182] <TB1> INFO: Expecting 514560 events.
[07:39:14.958] <TB1> INFO: 514560 events read in total (12366ms).
[07:39:14.972] <TB1> INFO: Expecting 514560 events.
[07:39:27.667] <TB1> INFO: 514560 events read in total (12279ms).
[07:39:27.687] <TB1> INFO: Expecting 514560 events.
[07:39:40.385] <TB1> INFO: 514560 events read in total (12292ms).
[07:39:40.402] <TB1> INFO: Expecting 514560 events.
[07:39:53.123] <TB1> INFO: 514560 events read in total (12317ms).
[07:39:53.146] <TB1> INFO: Expecting 514560 events.
[07:40:05.874] <TB1> INFO: 514560 events read in total (12323ms).
[07:40:05.895] <TB1> INFO: Expecting 514560 events.
[07:40:18.629] <TB1> INFO: 514560 events read in total (12328ms).
[07:40:18.652] <TB1> INFO: Expecting 514560 events.
[07:40:31.429] <TB1> INFO: 514560 events read in total (12370ms).
[07:40:31.455] <TB1> INFO: Expecting 514560 events.
[07:40:44.257] <TB1> INFO: 514560 events read in total (12400ms).
[07:40:44.285] <TB1> INFO: Expecting 514560 events.
[07:40:57.007] <TB1> INFO: 514560 events read in total (12320ms).
[07:40:57.035] <TB1> INFO: Expecting 514560 events.
[07:41:09.857] <TB1> INFO: 514560 events read in total (12418ms).
[07:41:09.892] <TB1> INFO: Expecting 514560 events.
[07:41:22.618] <TB1> INFO: 514560 events read in total (12324ms).
[07:41:22.655] <TB1> INFO: Test took 204503ms.
[07:41:23.602] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[07:41:23.612] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[07:41:23.612] <TB1> INFO: run 1 of 1
[07:41:23.897] <TB1> INFO: Expecting 6281600 events.
[07:41:58.659] <TB1> INFO: 742490 events read in total (34205ms).
[07:42:33.218] <TB1> INFO: 1482590 events read in total (68764ms).
[07:43:07.672] <TB1> INFO: 2222560 events read in total (103218ms).
[07:43:41.829] <TB1> INFO: 2963530 events read in total (137375ms).
[07:44:16.008] <TB1> INFO: 3698490 events read in total (171554ms).
[07:44:50.052] <TB1> INFO: 4429900 events read in total (205598ms).
[07:45:24.093] <TB1> INFO: 5159110 events read in total (239639ms).
[07:45:58.230] <TB1> INFO: 5888110 events read in total (273776ms).
[07:46:16.825] <TB1> INFO: 6281600 events read in total (292372ms).
[07:46:16.879] <TB1> INFO: Test took 293267ms.
[07:46:40.386] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.099627 .. 255.000000
[07:46:40.460] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[07:46:40.468] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[07:46:40.468] <TB1> INFO: run 1 of 1
[07:46:40.728] <TB1> INFO: Expecting 10649600 events.
[07:47:14.878] <TB1> INFO: 711820 events read in total (33595ms).
[07:47:49.045] <TB1> INFO: 1424150 events read in total (67761ms).
[07:48:22.912] <TB1> INFO: 2136450 events read in total (101628ms).
[07:48:57.231] <TB1> INFO: 2848960 events read in total (135947ms).
[07:49:30.935] <TB1> INFO: 3561800 events read in total (169651ms).
[07:50:04.541] <TB1> INFO: 4274280 events read in total (203257ms).
[07:50:38.364] <TB1> INFO: 4986870 events read in total (237080ms).
[07:51:12.373] <TB1> INFO: 5699680 events read in total (271089ms).
[07:51:46.351] <TB1> INFO: 6411870 events read in total (305067ms).
[07:52:20.434] <TB1> INFO: 7123480 events read in total (339150ms).
[07:52:54.328] <TB1> INFO: 7835060 events read in total (373044ms).
[07:53:28.308] <TB1> INFO: 8546220 events read in total (407024ms).
[07:54:02.296] <TB1> INFO: 9257050 events read in total (441012ms).
[07:54:36.081] <TB1> INFO: 9967980 events read in total (474797ms).
[07:55:08.617] <TB1> INFO: 10649600 events read in total (507333ms).
[07:55:08.707] <TB1> INFO: Test took 508239ms.
[07:55:37.468] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 16.783622 .. 46.201699
[07:55:37.543] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 6 .. 56 (-1/-1) hits flags = 528 (plus default)
[07:55:37.551] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[07:55:37.551] <TB1> INFO: run 1 of 1
[07:55:37.810] <TB1> INFO: Expecting 2121600 events.
[07:56:16.099] <TB1> INFO: 924660 events read in total (37727ms).
[07:56:53.858] <TB1> INFO: 1847120 events read in total (75486ms).
[07:57:05.269] <TB1> INFO: 2121600 events read in total (86898ms).
[07:57:05.283] <TB1> INFO: Test took 87732ms.
[07:57:19.095] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 1.968208 .. 43.088629
[07:57:19.171] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 1 .. 53 (-1/-1) hits flags = 528 (plus default)
[07:57:19.180] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[07:57:19.180] <TB1> INFO: run 1 of 1
[07:57:19.442] <TB1> INFO: Expecting 2204800 events.
[07:57:58.141] <TB1> INFO: 976950 events read in total (38143ms).
[07:58:36.278] <TB1> INFO: 1952390 events read in total (76280ms).
[07:58:46.583] <TB1> INFO: 2204800 events read in total (86585ms).
[07:58:46.592] <TB1> INFO: Test took 87413ms.
[07:58:59.239] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 9.843325 .. 43.088629
[07:58:59.317] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 9 .. 53 (-1/-1) hits flags = 528 (plus default)
[07:58:59.325] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[07:58:59.325] <TB1> INFO: run 1 of 1
[07:58:59.609] <TB1> INFO: Expecting 1872000 events.
[07:59:37.832] <TB1> INFO: 933000 events read in total (37667ms).
[08:00:15.487] <TB1> INFO: 1866020 events read in total (75322ms).
[08:00:16.158] <TB1> INFO: 1872000 events read in total (75994ms).
[08:00:16.174] <TB1> INFO: Test took 76849ms.
[08:00:29.269] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[08:00:29.269] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[08:00:29.277] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[08:00:29.277] <TB1> INFO: run 1 of 1
[08:00:29.537] <TB1> INFO: Expecting 1705600 events.
[08:01:07.027] <TB1> INFO: 878510 events read in total (36933ms).
[08:01:41.914] <TB1> INFO: 1705600 events read in total (71820ms).
[08:01:41.935] <TB1> INFO: Test took 72659ms.
[08:01:56.263] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:01:56.263] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:01:56.264] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:01:56.264] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:01:56.264] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:01:56.264] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:01:56.264] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:01:56.264] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:01:56.265] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:01:56.265] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:01:56.265] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:01:56.265] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:01:56.265] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:01:56.265] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:01:56.266] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:01:56.266] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:01:56.266] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//trimParameters35_C0.dat
[08:01:56.277] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//trimParameters35_C1.dat
[08:01:56.288] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//trimParameters35_C2.dat
[08:01:56.299] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//trimParameters35_C3.dat
[08:01:56.310] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//trimParameters35_C4.dat
[08:01:56.320] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//trimParameters35_C5.dat
[08:01:56.327] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//trimParameters35_C6.dat
[08:01:56.333] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//trimParameters35_C7.dat
[08:01:56.339] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//trimParameters35_C8.dat
[08:01:56.346] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//trimParameters35_C9.dat
[08:01:56.352] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//trimParameters35_C10.dat
[08:01:56.358] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//trimParameters35_C11.dat
[08:01:56.364] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//trimParameters35_C12.dat
[08:01:56.371] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//trimParameters35_C13.dat
[08:01:56.379] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//trimParameters35_C14.dat
[08:01:56.385] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//trimParameters35_C15.dat
[08:01:56.391] <TB1> INFO: PixTestTrim::trimTest() done
[08:01:56.391] <TB1> INFO: vtrim: 113 91 123 105 98 105 98 91 88 105 87 93 131 87 114 104
[08:01:56.391] <TB1> INFO: vthrcomp: 95 94 105 91 99 92 101 92 93 98 84 94 101 88 94 84
[08:01:56.391] <TB1> INFO: vcal mean: 34.96 34.97 35.01 34.96 35.00 34.98 35.02 34.99 34.95 35.03 34.99 34.96 34.99 35.02 34.89 34.98
[08:01:56.391] <TB1> INFO: vcal RMS: 0.83 0.79 0.92 0.82 0.88 0.86 0.84 0.87 0.84 0.98 0.81 0.80 1.05 0.88 0.92 0.90
[08:01:56.391] <TB1> INFO: bits mean: 9.28 9.10 8.89 9.61 9.21 9.36 8.61 9.40 9.24 8.66 9.42 9.21 9.05 9.61 9.82 9.41
[08:01:56.391] <TB1> INFO: bits RMS: 2.76 2.78 2.61 2.54 2.84 2.66 3.07 2.75 2.75 2.99 2.72 2.86 2.63 2.58 2.52 2.57
[08:01:56.397] <TB1> INFO: ----------------------------------------------------------------------
[08:01:56.397] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[08:01:56.397] <TB1> INFO: ----------------------------------------------------------------------
[08:01:56.399] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[08:01:56.407] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[08:01:56.407] <TB1> INFO: run 1 of 1
[08:01:56.695] <TB1> INFO: Expecting 4160000 events.
[08:02:38.845] <TB1> INFO: 942785 events read in total (41594ms).
[08:03:20.359] <TB1> INFO: 1877210 events read in total (83108ms).
[08:04:01.812] <TB1> INFO: 2801815 events read in total (124561ms).
[08:04:42.709] <TB1> INFO: 3723000 events read in total (165458ms).
[08:05:02.054] <TB1> INFO: 4160000 events read in total (184803ms).
[08:05:02.091] <TB1> INFO: Test took 185683ms.
[08:05:32.476] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 190 (-1/-1) hits flags = 528 (plus default)
[08:05:32.484] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[08:05:32.484] <TB1> INFO: run 1 of 1
[08:05:32.777] <TB1> INFO: Expecting 3972800 events.
[08:06:14.765] <TB1> INFO: 927000 events read in total (41432ms).
[08:06:55.705] <TB1> INFO: 1845960 events read in total (82372ms).
[08:07:36.437] <TB1> INFO: 2755065 events read in total (123104ms).
[08:08:17.326] <TB1> INFO: 3661995 events read in total (163993ms).
[08:08:31.521] <TB1> INFO: 3972800 events read in total (178189ms).
[08:08:31.563] <TB1> INFO: Test took 179079ms.
[08:09:00.412] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 179 (-1/-1) hits flags = 528 (plus default)
[08:09:00.420] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[08:09:00.425] <TB1> INFO: run 1 of 1
[08:09:00.688] <TB1> INFO: Expecting 3744000 events.
[08:09:43.089] <TB1> INFO: 949130 events read in total (41845ms).
[08:10:24.865] <TB1> INFO: 1888825 events read in total (83622ms).
[08:11:06.272] <TB1> INFO: 2817020 events read in total (125029ms).
[08:11:47.117] <TB1> INFO: 3744000 events read in total (165873ms).
[08:11:47.153] <TB1> INFO: Test took 166728ms.
[08:12:13.781] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 179 (-1/-1) hits flags = 528 (plus default)
[08:12:13.789] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[08:12:13.789] <TB1> INFO: run 1 of 1
[08:12:14.049] <TB1> INFO: Expecting 3744000 events.
[08:12:55.776] <TB1> INFO: 948350 events read in total (41171ms).
[08:13:37.217] <TB1> INFO: 1887290 events read in total (82612ms).
[08:14:18.198] <TB1> INFO: 2815115 events read in total (123594ms).
[08:14:59.837] <TB1> INFO: 3744000 events read in total (165232ms).
[08:14:59.874] <TB1> INFO: Test took 166086ms.
[08:15:27.747] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 177 (-1/-1) hits flags = 528 (plus default)
[08:15:27.756] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[08:15:27.756] <TB1> INFO: run 1 of 1
[08:15:28.047] <TB1> INFO: Expecting 3702400 events.
[08:16:10.599] <TB1> INFO: 952285 events read in total (41995ms).
[08:16:52.382] <TB1> INFO: 1894920 events read in total (83778ms).
[08:17:33.567] <TB1> INFO: 2826490 events read in total (124963ms).
[08:18:12.482] <TB1> INFO: 3702400 events read in total (163878ms).
[08:18:12.522] <TB1> INFO: Test took 164765ms.
[08:18:40.308] <TB1> INFO: PixTestTrim::trimBitTest() done
[08:18:40.309] <TB1> INFO: PixTestTrim::doTest() done, duration: 3002 seconds
[08:18:40.309] <TB1> INFO: Decoding statistics:
[08:18:40.309] <TB1> INFO: General information:
[08:18:40.309] <TB1> INFO: 16bit words read: 0
[08:18:40.309] <TB1> INFO: valid events total: 0
[08:18:40.309] <TB1> INFO: empty events: 0
[08:18:40.309] <TB1> INFO: valid events with pixels: 0
[08:18:40.309] <TB1> INFO: valid pixel hits: 0
[08:18:40.309] <TB1> INFO: Event errors: 0
[08:18:40.309] <TB1> INFO: start marker: 0
[08:18:40.309] <TB1> INFO: stop marker: 0
[08:18:40.309] <TB1> INFO: overflow: 0
[08:18:40.309] <TB1> INFO: invalid 5bit words: 0
[08:18:40.309] <TB1> INFO: invalid XOR eye diagram: 0
[08:18:40.309] <TB1> INFO: frame (failed synchr.): 0
[08:18:40.309] <TB1> INFO: idle data (no TBM trl): 0
[08:18:40.309] <TB1> INFO: no data (only TBM hdr): 0
[08:18:40.309] <TB1> INFO: TBM errors: 0
[08:18:40.309] <TB1> INFO: flawed TBM headers: 0
[08:18:40.309] <TB1> INFO: flawed TBM trailers: 0
[08:18:40.309] <TB1> INFO: event ID mismatches: 0
[08:18:40.309] <TB1> INFO: ROC errors: 0
[08:18:40.309] <TB1> INFO: missing ROC header(s): 0
[08:18:40.309] <TB1> INFO: misplaced readback start: 0
[08:18:40.309] <TB1> INFO: Pixel decoding errors: 0
[08:18:40.309] <TB1> INFO: pixel data incomplete: 0
[08:18:40.309] <TB1> INFO: pixel address: 0
[08:18:40.309] <TB1> INFO: pulse height fill bit: 0
[08:18:40.309] <TB1> INFO: buffer corruption: 0
[08:18:41.013] <TB1> INFO: ######################################################################
[08:18:41.013] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[08:18:41.013] <TB1> INFO: ######################################################################
[08:18:41.276] <TB1> INFO: Expecting 41600 events.
[08:18:45.090] <TB1> INFO: 41600 events read in total (3258ms).
[08:18:45.090] <TB1> INFO: Test took 4076ms.
[08:18:45.566] <TB1> INFO: Expecting 41600 events.
[08:18:49.380] <TB1> INFO: 41600 events read in total (3258ms).
[08:18:49.380] <TB1> INFO: Test took 4084ms.
[08:18:49.694] <TB1> INFO: Expecting 41600 events.
[08:18:53.485] <TB1> INFO: 41600 events read in total (3235ms).
[08:18:53.486] <TB1> INFO: Test took 4083ms.
[08:18:53.740] <TB1> INFO: Expecting 2560 events.
[08:18:54.594] <TB1> INFO: 2560 events read in total (298ms).
[08:18:54.594] <TB1> INFO: Test took 1096ms.
[08:18:54.899] <TB1> INFO: Expecting 2560 events.
[08:18:55.752] <TB1> INFO: 2560 events read in total (297ms).
[08:18:55.752] <TB1> INFO: Test took 1158ms.
[08:18:56.057] <TB1> INFO: Expecting 2560 events.
[08:18:56.910] <TB1> INFO: 2560 events read in total (297ms).
[08:18:56.910] <TB1> INFO: Test took 1158ms.
[08:18:57.215] <TB1> INFO: Expecting 2560 events.
[08:18:58.066] <TB1> INFO: 2560 events read in total (295ms).
[08:18:58.067] <TB1> INFO: Test took 1157ms.
[08:18:58.372] <TB1> INFO: Expecting 2560 events.
[08:18:59.224] <TB1> INFO: 2560 events read in total (296ms).
[08:18:59.224] <TB1> INFO: Test took 1157ms.
[08:18:59.529] <TB1> INFO: Expecting 2560 events.
[08:19:00.382] <TB1> INFO: 2560 events read in total (297ms).
[08:19:00.382] <TB1> INFO: Test took 1158ms.
[08:19:00.687] <TB1> INFO: Expecting 2560 events.
[08:19:01.540] <TB1> INFO: 2560 events read in total (297ms).
[08:19:01.540] <TB1> INFO: Test took 1158ms.
[08:19:01.845] <TB1> INFO: Expecting 2560 events.
[08:19:02.700] <TB1> INFO: 2560 events read in total (298ms).
[08:19:02.700] <TB1> INFO: Test took 1159ms.
[08:19:03.006] <TB1> INFO: Expecting 2560 events.
[08:19:03.858] <TB1> INFO: 2560 events read in total (296ms).
[08:19:03.858] <TB1> INFO: Test took 1157ms.
[08:19:04.164] <TB1> INFO: Expecting 2560 events.
[08:19:05.018] <TB1> INFO: 2560 events read in total (298ms).
[08:19:05.018] <TB1> INFO: Test took 1159ms.
[08:19:05.323] <TB1> INFO: Expecting 2560 events.
[08:19:06.176] <TB1> INFO: 2560 events read in total (297ms).
[08:19:06.177] <TB1> INFO: Test took 1159ms.
[08:19:06.480] <TB1> INFO: Expecting 2560 events.
[08:19:07.333] <TB1> INFO: 2560 events read in total (297ms).
[08:19:07.333] <TB1> INFO: Test took 1156ms.
[08:19:07.638] <TB1> INFO: Expecting 2560 events.
[08:19:08.493] <TB1> INFO: 2560 events read in total (298ms).
[08:19:08.493] <TB1> INFO: Test took 1159ms.
[08:19:08.799] <TB1> INFO: Expecting 2560 events.
[08:19:09.657] <TB1> INFO: 2560 events read in total (302ms).
[08:19:09.657] <TB1> INFO: Test took 1164ms.
[08:19:09.962] <TB1> INFO: Expecting 2560 events.
[08:19:10.815] <TB1> INFO: 2560 events read in total (297ms).
[08:19:10.815] <TB1> INFO: Test took 1157ms.
[08:19:11.121] <TB1> INFO: Expecting 2560 events.
[08:19:11.978] <TB1> INFO: 2560 events read in total (301ms).
[08:19:11.978] <TB1> INFO: Test took 1162ms.
[08:19:11.981] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:19:12.284] <TB1> INFO: Expecting 655360 events.
[08:19:28.981] <TB1> INFO: 655360 events read in total (16141ms).
[08:19:28.989] <TB1> INFO: Expecting 655360 events.
[08:19:45.577] <TB1> INFO: 655360 events read in total (16186ms).
[08:19:45.590] <TB1> INFO: Expecting 655360 events.
[08:20:02.269] <TB1> INFO: 655360 events read in total (16278ms).
[08:20:02.285] <TB1> INFO: Expecting 655360 events.
[08:20:18.847] <TB1> INFO: 655360 events read in total (16161ms).
[08:20:18.867] <TB1> INFO: Expecting 655360 events.
[08:20:35.375] <TB1> INFO: 655360 events read in total (16107ms).
[08:20:35.397] <TB1> INFO: Expecting 655360 events.
[08:20:52.169] <TB1> INFO: 655360 events read in total (16371ms).
[08:20:52.195] <TB1> INFO: Expecting 655360 events.
[08:21:09.103] <TB1> INFO: 655360 events read in total (16507ms).
[08:21:09.138] <TB1> INFO: Expecting 655360 events.
[08:21:25.844] <TB1> INFO: 655360 events read in total (16304ms).
[08:21:25.877] <TB1> INFO: Expecting 655360 events.
[08:21:42.531] <TB1> INFO: 655360 events read in total (16253ms).
[08:21:42.569] <TB1> INFO: Expecting 655360 events.
[08:21:59.367] <TB1> INFO: 655360 events read in total (16396ms).
[08:21:59.409] <TB1> INFO: Expecting 655360 events.
[08:22:16.183] <TB1> INFO: 655360 events read in total (16373ms).
[08:22:16.235] <TB1> INFO: Expecting 655360 events.
[08:22:32.981] <TB1> INFO: 655360 events read in total (16344ms).
[08:22:33.030] <TB1> INFO: Expecting 655360 events.
[08:22:49.639] <TB1> INFO: 655360 events read in total (16207ms).
[08:22:49.693] <TB1> INFO: Expecting 655360 events.
[08:23:06.352] <TB1> INFO: 655360 events read in total (16257ms).
[08:23:06.410] <TB1> INFO: Expecting 655360 events.
[08:23:23.059] <TB1> INFO: 655360 events read in total (16248ms).
[08:23:23.121] <TB1> INFO: Expecting 655360 events.
[08:23:39.881] <TB1> INFO: 655360 events read in total (16358ms).
[08:23:39.953] <TB1> INFO: Test took 267972ms.
[08:23:40.036] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:23:40.265] <TB1> INFO: Expecting 655360 events.
[08:23:57.134] <TB1> INFO: 655360 events read in total (16312ms).
[08:23:57.142] <TB1> INFO: Expecting 655360 events.
[08:24:13.854] <TB1> INFO: 655360 events read in total (16310ms).
[08:24:13.868] <TB1> INFO: Expecting 655360 events.
[08:24:30.609] <TB1> INFO: 655360 events read in total (16340ms).
[08:24:30.627] <TB1> INFO: Expecting 655360 events.
[08:24:47.221] <TB1> INFO: 655360 events read in total (16192ms).
[08:24:47.243] <TB1> INFO: Expecting 655360 events.
[08:25:04.033] <TB1> INFO: 655360 events read in total (16389ms).
[08:25:04.056] <TB1> INFO: Expecting 655360 events.
[08:25:20.697] <TB1> INFO: 655360 events read in total (16240ms).
[08:25:20.722] <TB1> INFO: Expecting 655360 events.
[08:25:37.503] <TB1> INFO: 655360 events read in total (16379ms).
[08:25:37.534] <TB1> INFO: Expecting 655360 events.
[08:25:54.190] <TB1> INFO: 655360 events read in total (16255ms).
[08:25:54.230] <TB1> INFO: Expecting 655360 events.
[08:26:10.925] <TB1> INFO: 655360 events read in total (16294ms).
[08:26:10.963] <TB1> INFO: Expecting 655360 events.
[08:26:27.632] <TB1> INFO: 655360 events read in total (16267ms).
[08:26:27.671] <TB1> INFO: Expecting 655360 events.
[08:26:44.457] <TB1> INFO: 655360 events read in total (16384ms).
[08:26:44.509] <TB1> INFO: Expecting 655360 events.
[08:27:01.191] <TB1> INFO: 655360 events read in total (16280ms).
[08:27:01.236] <TB1> INFO: Expecting 655360 events.
[08:27:17.968] <TB1> INFO: 655360 events read in total (16330ms).
[08:27:18.018] <TB1> INFO: Expecting 655360 events.
[08:27:34.813] <TB1> INFO: 655360 events read in total (16394ms).
[08:27:34.870] <TB1> INFO: Expecting 655360 events.
[08:27:51.565] <TB1> INFO: 655360 events read in total (16293ms).
[08:27:51.620] <TB1> INFO: Expecting 655360 events.
[08:28:08.287] <TB1> INFO: 655360 events read in total (16266ms).
[08:28:08.363] <TB1> INFO: Test took 268327ms.
[08:28:08.563] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:28:08.569] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:28:08.576] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:28:08.583] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:28:08.590] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:28:08.600] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:28:08.607] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:28:08.614] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:28:08.620] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:28:08.627] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:28:08.634] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:28:08.641] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:28:08.647] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:28:08.655] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[08:28:08.662] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:28:08.669] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:28:08.676] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:28:08.711] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:28:08.711] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:28:08.711] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:28:08.711] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:28:08.711] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:28:08.711] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:28:08.712] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:28:08.712] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:28:08.712] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:28:08.712] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:28:08.712] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:28:08.712] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:28:08.712] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:28:08.712] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:28:08.712] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:28:08.712] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:28:08.989] <TB1> INFO: Expecting 41600 events.
[08:28:12.443] <TB1> INFO: 41600 events read in total (2898ms).
[08:28:12.444] <TB1> INFO: Test took 3729ms.
[08:28:12.904] <TB1> INFO: Expecting 41600 events.
[08:28:16.399] <TB1> INFO: 41600 events read in total (2939ms).
[08:28:16.399] <TB1> INFO: Test took 3778ms.
[08:28:16.875] <TB1> INFO: Expecting 41600 events.
[08:28:20.346] <TB1> INFO: 41600 events read in total (2915ms).
[08:28:20.347] <TB1> INFO: Test took 3768ms.
[08:28:20.530] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:20.618] <TB1> INFO: Expecting 2560 events.
[08:28:21.471] <TB1> INFO: 2560 events read in total (297ms).
[08:28:21.472] <TB1> INFO: Test took 942ms.
[08:28:21.473] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:21.777] <TB1> INFO: Expecting 2560 events.
[08:28:22.631] <TB1> INFO: 2560 events read in total (297ms).
[08:28:22.631] <TB1> INFO: Test took 1158ms.
[08:28:22.633] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:22.936] <TB1> INFO: Expecting 2560 events.
[08:28:23.789] <TB1> INFO: 2560 events read in total (297ms).
[08:28:23.790] <TB1> INFO: Test took 1157ms.
[08:28:23.791] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:24.095] <TB1> INFO: Expecting 2560 events.
[08:28:24.948] <TB1> INFO: 2560 events read in total (297ms).
[08:28:24.948] <TB1> INFO: Test took 1157ms.
[08:28:24.950] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:25.254] <TB1> INFO: Expecting 2560 events.
[08:28:26.107] <TB1> INFO: 2560 events read in total (297ms).
[08:28:26.108] <TB1> INFO: Test took 1158ms.
[08:28:26.110] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:26.413] <TB1> INFO: Expecting 2560 events.
[08:28:27.267] <TB1> INFO: 2560 events read in total (298ms).
[08:28:27.267] <TB1> INFO: Test took 1157ms.
[08:28:27.269] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:27.574] <TB1> INFO: Expecting 2560 events.
[08:28:28.430] <TB1> INFO: 2560 events read in total (300ms).
[08:28:28.430] <TB1> INFO: Test took 1161ms.
[08:28:28.432] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:28.735] <TB1> INFO: Expecting 2560 events.
[08:28:29.587] <TB1> INFO: 2560 events read in total (296ms).
[08:28:29.587] <TB1> INFO: Test took 1155ms.
[08:28:29.589] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:29.894] <TB1> INFO: Expecting 2560 events.
[08:28:30.747] <TB1> INFO: 2560 events read in total (297ms).
[08:28:30.747] <TB1> INFO: Test took 1158ms.
[08:28:30.749] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:31.052] <TB1> INFO: Expecting 2560 events.
[08:28:31.907] <TB1> INFO: 2560 events read in total (299ms).
[08:28:31.907] <TB1> INFO: Test took 1158ms.
[08:28:31.909] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:32.213] <TB1> INFO: Expecting 2560 events.
[08:28:33.072] <TB1> INFO: 2560 events read in total (303ms).
[08:28:33.073] <TB1> INFO: Test took 1164ms.
[08:28:33.075] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:33.378] <TB1> INFO: Expecting 2560 events.
[08:28:34.233] <TB1> INFO: 2560 events read in total (298ms).
[08:28:34.233] <TB1> INFO: Test took 1159ms.
[08:28:34.235] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:34.539] <TB1> INFO: Expecting 2560 events.
[08:28:35.392] <TB1> INFO: 2560 events read in total (296ms).
[08:28:35.392] <TB1> INFO: Test took 1157ms.
[08:28:35.394] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:35.698] <TB1> INFO: Expecting 2560 events.
[08:28:36.552] <TB1> INFO: 2560 events read in total (298ms).
[08:28:36.552] <TB1> INFO: Test took 1158ms.
[08:28:36.554] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:36.858] <TB1> INFO: Expecting 2560 events.
[08:28:37.712] <TB1> INFO: 2560 events read in total (298ms).
[08:28:37.713] <TB1> INFO: Test took 1159ms.
[08:28:37.714] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:38.019] <TB1> INFO: Expecting 2560 events.
[08:28:38.873] <TB1> INFO: 2560 events read in total (298ms).
[08:28:38.874] <TB1> INFO: Test took 1160ms.
[08:28:38.876] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:39.179] <TB1> INFO: Expecting 2560 events.
[08:28:40.033] <TB1> INFO: 2560 events read in total (298ms).
[08:28:40.033] <TB1> INFO: Test took 1157ms.
[08:28:40.035] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:40.338] <TB1> INFO: Expecting 2560 events.
[08:28:41.192] <TB1> INFO: 2560 events read in total (298ms).
[08:28:41.192] <TB1> INFO: Test took 1157ms.
[08:28:41.195] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:41.498] <TB1> INFO: Expecting 2560 events.
[08:28:42.354] <TB1> INFO: 2560 events read in total (300ms).
[08:28:42.354] <TB1> INFO: Test took 1159ms.
[08:28:42.355] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:42.659] <TB1> INFO: Expecting 2560 events.
[08:28:43.513] <TB1> INFO: 2560 events read in total (298ms).
[08:28:43.513] <TB1> INFO: Test took 1158ms.
[08:28:43.515] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:43.819] <TB1> INFO: Expecting 2560 events.
[08:28:44.672] <TB1> INFO: 2560 events read in total (297ms).
[08:28:44.673] <TB1> INFO: Test took 1158ms.
[08:28:44.675] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:44.978] <TB1> INFO: Expecting 2560 events.
[08:28:45.833] <TB1> INFO: 2560 events read in total (299ms).
[08:28:45.833] <TB1> INFO: Test took 1158ms.
[08:28:45.835] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:46.138] <TB1> INFO: Expecting 2560 events.
[08:28:46.997] <TB1> INFO: 2560 events read in total (303ms).
[08:28:46.997] <TB1> INFO: Test took 1162ms.
[08:28:46.999] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:47.303] <TB1> INFO: Expecting 2560 events.
[08:28:48.157] <TB1> INFO: 2560 events read in total (298ms).
[08:28:48.158] <TB1> INFO: Test took 1159ms.
[08:28:48.160] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:48.463] <TB1> INFO: Expecting 2560 events.
[08:28:49.317] <TB1> INFO: 2560 events read in total (298ms).
[08:28:49.317] <TB1> INFO: Test took 1158ms.
[08:28:49.319] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:49.622] <TB1> INFO: Expecting 2560 events.
[08:28:50.478] <TB1> INFO: 2560 events read in total (299ms).
[08:28:50.478] <TB1> INFO: Test took 1159ms.
[08:28:50.480] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:50.784] <TB1> INFO: Expecting 2560 events.
[08:28:51.637] <TB1> INFO: 2560 events read in total (297ms).
[08:28:51.637] <TB1> INFO: Test took 1157ms.
[08:28:51.639] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:51.943] <TB1> INFO: Expecting 2560 events.
[08:28:52.797] <TB1> INFO: 2560 events read in total (298ms).
[08:28:52.797] <TB1> INFO: Test took 1158ms.
[08:28:52.799] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:53.103] <TB1> INFO: Expecting 2560 events.
[08:28:53.960] <TB1> INFO: 2560 events read in total (301ms).
[08:28:53.961] <TB1> INFO: Test took 1162ms.
[08:28:53.963] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:54.266] <TB1> INFO: Expecting 2560 events.
[08:28:55.119] <TB1> INFO: 2560 events read in total (297ms).
[08:28:55.119] <TB1> INFO: Test took 1157ms.
[08:28:55.121] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:55.425] <TB1> INFO: Expecting 2560 events.
[08:28:56.279] <TB1> INFO: 2560 events read in total (298ms).
[08:28:56.279] <TB1> INFO: Test took 1158ms.
[08:28:56.282] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:28:56.584] <TB1> INFO: Expecting 2560 events.
[08:28:57.440] <TB1> INFO: 2560 events read in total (300ms).
[08:28:57.440] <TB1> INFO: Test took 1158ms.
[08:28:57.901] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 616 seconds
[08:28:57.901] <TB1> INFO: PH scale (per ROC): 82 80 72 78 73 78 83 78 80 75 83 86 80 73 81 79
[08:28:57.901] <TB1> INFO: PH offset (per ROC): 162 149 173 169 155 175 160 173 172 183 160 160 174 160 172 179
[08:28:57.905] <TB1> INFO: Decoding statistics:
[08:28:57.905] <TB1> INFO: General information:
[08:28:57.905] <TB1> INFO: 16bit words read: 91924
[08:28:57.905] <TB1> INFO: valid events total: 10240
[08:28:57.905] <TB1> INFO: empty events: 7739
[08:28:57.905] <TB1> INFO: valid events with pixels: 2501
[08:28:57.905] <TB1> INFO: valid pixel hits: 2501
[08:28:57.905] <TB1> INFO: Event errors: 0
[08:28:57.905] <TB1> INFO: start marker: 0
[08:28:57.905] <TB1> INFO: stop marker: 0
[08:28:57.905] <TB1> INFO: overflow: 0
[08:28:57.905] <TB1> INFO: invalid 5bit words: 0
[08:28:57.905] <TB1> INFO: invalid XOR eye diagram: 0
[08:28:57.906] <TB1> INFO: frame (failed synchr.): 0
[08:28:57.906] <TB1> INFO: idle data (no TBM trl): 0
[08:28:57.906] <TB1> INFO: no data (only TBM hdr): 0
[08:28:57.906] <TB1> INFO: TBM errors: 0
[08:28:57.906] <TB1> INFO: flawed TBM headers: 0
[08:28:57.906] <TB1> INFO: flawed TBM trailers: 0
[08:28:57.906] <TB1> INFO: event ID mismatches: 0
[08:28:57.906] <TB1> INFO: ROC errors: 0
[08:28:57.906] <TB1> INFO: missing ROC header(s): 0
[08:28:57.906] <TB1> INFO: misplaced readback start: 0
[08:28:57.906] <TB1> INFO: Pixel decoding errors: 0
[08:28:57.906] <TB1> INFO: pixel data incomplete: 0
[08:28:57.906] <TB1> INFO: pixel address: 0
[08:28:57.906] <TB1> INFO: pulse height fill bit: 0
[08:28:57.906] <TB1> INFO: buffer corruption: 0
[08:28:58.080] <TB1> INFO: ######################################################################
[08:28:58.080] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[08:28:58.080] <TB1> INFO: ######################################################################
[08:28:58.090] <TB1> INFO: scanning low vcal = 10
[08:28:58.350] <TB1> INFO: Expecting 41600 events.
[08:29:01.884] <TB1> INFO: 41600 events read in total (2978ms).
[08:29:01.884] <TB1> INFO: Test took 3794ms.
[08:29:01.886] <TB1> INFO: scanning low vcal = 20
[08:29:02.189] <TB1> INFO: Expecting 41600 events.
[08:29:05.743] <TB1> INFO: 41600 events read in total (2998ms).
[08:29:05.743] <TB1> INFO: Test took 3857ms.
[08:29:05.745] <TB1> INFO: scanning low vcal = 30
[08:29:06.048] <TB1> INFO: Expecting 41600 events.
[08:29:09.611] <TB1> INFO: 41600 events read in total (3007ms).
[08:29:09.612] <TB1> INFO: Test took 3867ms.
[08:29:09.614] <TB1> INFO: scanning low vcal = 40
[08:29:09.908] <TB1> INFO: Expecting 41600 events.
[08:29:14.009] <TB1> INFO: 41600 events read in total (3545ms).
[08:29:14.010] <TB1> INFO: Test took 4396ms.
[08:29:14.012] <TB1> INFO: scanning low vcal = 50
[08:29:14.276] <TB1> INFO: Expecting 41600 events.
[08:29:18.456] <TB1> INFO: 41600 events read in total (3624ms).
[08:29:18.456] <TB1> INFO: Test took 4443ms.
[08:29:18.459] <TB1> INFO: scanning low vcal = 60
[08:29:18.720] <TB1> INFO: Expecting 41600 events.
[08:29:22.922] <TB1> INFO: 41600 events read in total (3646ms).
[08:29:22.922] <TB1> INFO: Test took 4463ms.
[08:29:22.925] <TB1> INFO: scanning low vcal = 70
[08:29:23.189] <TB1> INFO: Expecting 41600 events.
[08:29:27.397] <TB1> INFO: 41600 events read in total (3652ms).
[08:29:27.397] <TB1> INFO: Test took 4472ms.
[08:29:27.399] <TB1> INFO: scanning low vcal = 80
[08:29:27.659] <TB1> INFO: Expecting 41600 events.
[08:29:31.835] <TB1> INFO: 41600 events read in total (3620ms).
[08:29:31.835] <TB1> INFO: Test took 4435ms.
[08:29:31.838] <TB1> INFO: scanning low vcal = 90
[08:29:32.112] <TB1> INFO: Expecting 41600 events.
[08:29:36.305] <TB1> INFO: 41600 events read in total (3636ms).
[08:29:36.306] <TB1> INFO: Test took 4468ms.
[08:29:36.309] <TB1> INFO: scanning low vcal = 100
[08:29:36.569] <TB1> INFO: Expecting 41600 events.
[08:29:40.800] <TB1> INFO: 41600 events read in total (3675ms).
[08:29:40.800] <TB1> INFO: Test took 4491ms.
[08:29:40.803] <TB1> INFO: scanning low vcal = 110
[08:29:41.064] <TB1> INFO: Expecting 41600 events.
[08:29:45.271] <TB1> INFO: 41600 events read in total (3650ms).
[08:29:45.272] <TB1> INFO: Test took 4469ms.
[08:29:45.274] <TB1> INFO: scanning low vcal = 120
[08:29:45.540] <TB1> INFO: Expecting 41600 events.
[08:29:49.753] <TB1> INFO: 41600 events read in total (3657ms).
[08:29:49.753] <TB1> INFO: Test took 4479ms.
[08:29:49.756] <TB1> INFO: scanning low vcal = 130
[08:29:50.052] <TB1> INFO: Expecting 41600 events.
[08:29:54.253] <TB1> INFO: 41600 events read in total (3645ms).
[08:29:54.253] <TB1> INFO: Test took 4497ms.
[08:29:54.256] <TB1> INFO: scanning low vcal = 140
[08:29:54.524] <TB1> INFO: Expecting 41600 events.
[08:29:58.746] <TB1> INFO: 41600 events read in total (3666ms).
[08:29:58.746] <TB1> INFO: Test took 4490ms.
[08:29:58.749] <TB1> INFO: scanning low vcal = 150
[08:29:59.034] <TB1> INFO: Expecting 41600 events.
[08:30:03.222] <TB1> INFO: 41600 events read in total (3631ms).
[08:30:03.222] <TB1> INFO: Test took 4473ms.
[08:30:03.225] <TB1> INFO: scanning low vcal = 160
[08:30:03.494] <TB1> INFO: Expecting 41600 events.
[08:30:07.695] <TB1> INFO: 41600 events read in total (3644ms).
[08:30:07.695] <TB1> INFO: Test took 4470ms.
[08:30:07.698] <TB1> INFO: scanning low vcal = 170
[08:30:07.990] <TB1> INFO: Expecting 41600 events.
[08:30:12.196] <TB1> INFO: 41600 events read in total (3650ms).
[08:30:12.197] <TB1> INFO: Test took 4499ms.
[08:30:12.200] <TB1> INFO: scanning low vcal = 180
[08:30:12.481] <TB1> INFO: Expecting 41600 events.
[08:30:16.708] <TB1> INFO: 41600 events read in total (3671ms).
[08:30:16.709] <TB1> INFO: Test took 4509ms.
[08:30:16.711] <TB1> INFO: scanning low vcal = 190
[08:30:16.998] <TB1> INFO: Expecting 41600 events.
[08:30:21.213] <TB1> INFO: 41600 events read in total (3659ms).
[08:30:21.213] <TB1> INFO: Test took 4502ms.
[08:30:21.216] <TB1> INFO: scanning low vcal = 200
[08:30:21.518] <TB1> INFO: Expecting 41600 events.
[08:30:25.723] <TB1> INFO: 41600 events read in total (3649ms).
[08:30:25.723] <TB1> INFO: Test took 4507ms.
[08:30:25.726] <TB1> INFO: scanning low vcal = 210
[08:30:26.010] <TB1> INFO: Expecting 41600 events.
[08:30:30.210] <TB1> INFO: 41600 events read in total (3643ms).
[08:30:30.211] <TB1> INFO: Test took 4485ms.
[08:30:30.213] <TB1> INFO: scanning low vcal = 220
[08:30:30.472] <TB1> INFO: Expecting 41600 events.
[08:30:34.674] <TB1> INFO: 41600 events read in total (3646ms).
[08:30:34.675] <TB1> INFO: Test took 4462ms.
[08:30:34.677] <TB1> INFO: scanning low vcal = 230
[08:30:34.947] <TB1> INFO: Expecting 41600 events.
[08:30:39.159] <TB1> INFO: 41600 events read in total (3656ms).
[08:30:39.159] <TB1> INFO: Test took 4483ms.
[08:30:39.162] <TB1> INFO: scanning low vcal = 240
[08:30:39.438] <TB1> INFO: Expecting 41600 events.
[08:30:43.679] <TB1> INFO: 41600 events read in total (3685ms).
[08:30:43.680] <TB1> INFO: Test took 4518ms.
[08:30:43.682] <TB1> INFO: scanning low vcal = 250
[08:30:43.974] <TB1> INFO: Expecting 41600 events.
[08:30:48.201] <TB1> INFO: 41600 events read in total (3671ms).
[08:30:48.202] <TB1> INFO: Test took 4520ms.
[08:30:48.206] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[08:30:48.470] <TB1> INFO: Expecting 41600 events.
[08:30:52.667] <TB1> INFO: 41600 events read in total (3641ms).
[08:30:52.667] <TB1> INFO: Test took 4461ms.
[08:30:52.669] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[08:30:52.929] <TB1> INFO: Expecting 41600 events.
[08:30:57.145] <TB1> INFO: 41600 events read in total (3659ms).
[08:30:57.145] <TB1> INFO: Test took 4476ms.
[08:30:57.149] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[08:30:57.438] <TB1> INFO: Expecting 41600 events.
[08:31:01.661] <TB1> INFO: 41600 events read in total (3666ms).
[08:31:01.662] <TB1> INFO: Test took 4513ms.
[08:31:01.665] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[08:31:01.932] <TB1> INFO: Expecting 41600 events.
[08:31:06.135] <TB1> INFO: 41600 events read in total (3647ms).
[08:31:06.136] <TB1> INFO: Test took 4471ms.
[08:31:06.139] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[08:31:06.425] <TB1> INFO: Expecting 41600 events.
[08:31:10.629] <TB1> INFO: 41600 events read in total (3647ms).
[08:31:10.630] <TB1> INFO: Test took 4491ms.
[08:31:11.136] <TB1> INFO: PixTestGainPedestal::measure() done
[08:31:42.478] <TB1> INFO: PixTestGainPedestal::fit() done
[08:31:42.478] <TB1> INFO: non-linearity mean: 0.950 0.956 0.956 0.954 0.955 0.954 0.953 0.951 0.960 0.954 0.957 0.955 0.959 0.953 0.959 0.961
[08:31:42.478] <TB1> INFO: non-linearity RMS: 0.006 0.006 0.006 0.005 0.005 0.005 0.006 0.006 0.006 0.007 0.006 0.006 0.005 0.006 0.006 0.006
[08:31:42.478] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[08:31:42.499] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[08:31:42.517] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[08:31:42.548] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[08:31:42.568] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[08:31:42.587] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[08:31:42.606] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[08:31:42.639] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[08:31:42.674] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[08:31:42.708] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[08:31:42.727] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[08:31:42.745] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[08:31:42.764] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[08:31:42.782] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[08:31:42.814] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[08:31:42.836] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[08:31:42.858] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 164 seconds
[08:31:42.858] <TB1> INFO: Decoding statistics:
[08:31:42.858] <TB1> INFO: General information:
[08:31:42.858] <TB1> INFO: 16bit words read: 2662400
[08:31:42.858] <TB1> INFO: valid events total: 166400
[08:31:42.858] <TB1> INFO: empty events: 0
[08:31:42.858] <TB1> INFO: valid events with pixels: 166400
[08:31:42.858] <TB1> INFO: valid pixel hits: 665595
[08:31:42.858] <TB1> INFO: Event errors: 0
[08:31:42.858] <TB1> INFO: start marker: 0
[08:31:42.858] <TB1> INFO: stop marker: 0
[08:31:42.858] <TB1> INFO: overflow: 0
[08:31:42.858] <TB1> INFO: invalid 5bit words: 0
[08:31:42.858] <TB1> INFO: invalid XOR eye diagram: 0
[08:31:42.858] <TB1> INFO: frame (failed synchr.): 0
[08:31:42.858] <TB1> INFO: idle data (no TBM trl): 0
[08:31:42.858] <TB1> INFO: no data (only TBM hdr): 0
[08:31:42.858] <TB1> INFO: TBM errors: 0
[08:31:42.858] <TB1> INFO: flawed TBM headers: 0
[08:31:42.858] <TB1> INFO: flawed TBM trailers: 0
[08:31:42.858] <TB1> INFO: event ID mismatches: 0
[08:31:42.858] <TB1> INFO: ROC errors: 0
[08:31:42.858] <TB1> INFO: missing ROC header(s): 0
[08:31:42.858] <TB1> INFO: misplaced readback start: 0
[08:31:42.858] <TB1> INFO: Pixel decoding errors: 0
[08:31:42.858] <TB1> INFO: pixel data incomplete: 0
[08:31:42.858] <TB1> INFO: pixel address: 0
[08:31:42.858] <TB1> INFO: pulse height fill bit: 0
[08:31:42.858] <TB1> INFO: buffer corruption: 0
[08:31:42.892] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C15.dat
[08:31:42.894] <TB1> INFO: ######################################################################
[08:31:42.894] <TB1> INFO: PixTestReadback::doTest()
[08:31:42.894] <TB1> INFO: ######################################################################
[08:31:42.894] <TB1> INFO: ----------------------------------------------------------------------
[08:31:42.894] <TB1> INFO: PixTestReadback::CalibrateVd()
[08:31:42.894] <TB1> INFO: ----------------------------------------------------------------------
[08:31:52.339] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C0.dat
[08:31:52.339] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C1.dat
[08:31:52.339] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C2.dat
[08:31:52.339] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C3.dat
[08:31:52.339] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C4.dat
[08:31:52.339] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C5.dat
[08:31:52.340] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C6.dat
[08:31:52.340] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C7.dat
[08:31:52.340] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C8.dat
[08:31:52.340] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C9.dat
[08:31:52.340] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C10.dat
[08:31:52.340] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C11.dat
[08:31:52.340] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C12.dat
[08:31:52.340] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C13.dat
[08:31:52.340] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C14.dat
[08:31:52.340] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C15.dat
[08:31:52.372] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[08:31:52.372] <TB1> INFO: ----------------------------------------------------------------------
[08:31:52.372] <TB1> INFO: PixTestReadback::CalibrateVa()
[08:31:52.372] <TB1> INFO: ----------------------------------------------------------------------
[08:32:02.414] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C0.dat
[08:32:02.414] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C1.dat
[08:32:02.414] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C2.dat
[08:32:02.415] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C3.dat
[08:32:02.415] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C4.dat
[08:32:02.415] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C5.dat
[08:32:02.415] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C6.dat
[08:32:02.415] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C7.dat
[08:32:02.415] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C8.dat
[08:32:02.415] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C9.dat
[08:32:02.415] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C10.dat
[08:32:02.415] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C11.dat
[08:32:02.416] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C12.dat
[08:32:02.416] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C13.dat
[08:32:02.416] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C14.dat
[08:32:02.416] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C15.dat
[08:32:02.439] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[08:32:02.439] <TB1> INFO: ----------------------------------------------------------------------
[08:32:02.439] <TB1> INFO: PixTestReadback::readbackVbg()
[08:32:02.439] <TB1> INFO: ----------------------------------------------------------------------
[08:32:09.744] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[08:32:09.744] <TB1> INFO: ----------------------------------------------------------------------
[08:32:09.744] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[08:32:09.744] <TB1> INFO: ----------------------------------------------------------------------
[08:32:09.744] <TB1> INFO: Vbg will be calibrated using Vd calibration
[08:32:09.744] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 155.8calibrated Vbg = 1.17403 :::*/*/*/*/
[08:32:09.744] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 149.2calibrated Vbg = 1.17153 :::*/*/*/*/
[08:32:09.745] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 153.2calibrated Vbg = 1.17859 :::*/*/*/*/
[08:32:09.745] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 153.2calibrated Vbg = 1.18363 :::*/*/*/*/
[08:32:09.745] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 153.5calibrated Vbg = 1.18566 :::*/*/*/*/
[08:32:09.745] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 154.3calibrated Vbg = 1.18577 :::*/*/*/*/
[08:32:09.745] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 154.6calibrated Vbg = 1.18191 :::*/*/*/*/
[08:32:09.745] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 157.2calibrated Vbg = 1.19178 :::*/*/*/*/
[08:32:09.745] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 155.2calibrated Vbg = 1.19272 :::*/*/*/*/
[08:32:09.745] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 139.9calibrated Vbg = 1.19508 :::*/*/*/*/
[08:32:09.745] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 154.2calibrated Vbg = 1.18552 :::*/*/*/*/
[08:32:09.745] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 148.9calibrated Vbg = 1.18427 :::*/*/*/*/
[08:32:09.745] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 160.2calibrated Vbg = 1.17346 :::*/*/*/*/
[08:32:09.745] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 154.5calibrated Vbg = 1.17844 :::*/*/*/*/
[08:32:09.745] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 152.6calibrated Vbg = 1.18841 :::*/*/*/*/
[08:32:09.745] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 150.8calibrated Vbg = 1.18012 :::*/*/*/*/
[08:32:09.747] <TB1> INFO: ----------------------------------------------------------------------
[08:32:09.747] <TB1> INFO: PixTestReadback::CalibrateIa()
[08:32:09.747] <TB1> INFO: ----------------------------------------------------------------------
[08:34:44.981] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C0.dat
[08:34:44.981] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C1.dat
[08:34:44.981] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C2.dat
[08:34:44.981] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C3.dat
[08:34:44.981] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C4.dat
[08:34:44.981] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C5.dat
[08:34:44.981] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C6.dat
[08:34:44.981] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C7.dat
[08:34:44.981] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C8.dat
[08:34:44.981] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C9.dat
[08:34:44.981] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C10.dat
[08:34:44.981] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C11.dat
[08:34:44.981] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C12.dat
[08:34:44.982] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C13.dat
[08:34:44.982] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C14.dat
[08:34:44.982] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2227_FullQualification_2016-05-09_08h47m_1462776433//000_FulltestPxar_m20//readbackCal_C15.dat
[08:34:45.011] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[08:34:45.013] <TB1> INFO: PixTestReadback::doTest() done
[08:34:45.013] <TB1> INFO: Decoding statistics:
[08:34:45.013] <TB1> INFO: General information:
[08:34:45.013] <TB1> INFO: 16bit words read: 1024
[08:34:45.013] <TB1> INFO: valid events total: 128
[08:34:45.013] <TB1> INFO: empty events: 128
[08:34:45.013] <TB1> INFO: valid events with pixels: 0
[08:34:45.013] <TB1> INFO: valid pixel hits: 0
[08:34:45.013] <TB1> INFO: Event errors: 0
[08:34:45.013] <TB1> INFO: start marker: 0
[08:34:45.013] <TB1> INFO: stop marker: 0
[08:34:45.013] <TB1> INFO: overflow: 0
[08:34:45.013] <TB1> INFO: invalid 5bit words: 0
[08:34:45.013] <TB1> INFO: invalid XOR eye diagram: 0
[08:34:45.013] <TB1> INFO: frame (failed synchr.): 0
[08:34:45.014] <TB1> INFO: idle data (no TBM trl): 0
[08:34:45.014] <TB1> INFO: no data (only TBM hdr): 0
[08:34:45.014] <TB1> INFO: TBM errors: 0
[08:34:45.014] <TB1> INFO: flawed TBM headers: 0
[08:34:45.014] <TB1> INFO: flawed TBM trailers: 0
[08:34:45.014] <TB1> INFO: event ID mismatches: 0
[08:34:45.014] <TB1> INFO: ROC errors: 0
[08:34:45.014] <TB1> INFO: missing ROC header(s): 0
[08:34:45.014] <TB1> INFO: misplaced readback start: 0
[08:34:45.014] <TB1> INFO: Pixel decoding errors: 0
[08:34:45.014] <TB1> INFO: pixel data incomplete: 0
[08:34:45.014] <TB1> INFO: pixel address: 0
[08:34:45.014] <TB1> INFO: pulse height fill bit: 0
[08:34:45.014] <TB1> INFO: buffer corruption: 0
[08:34:45.066] <TB1> INFO: Decoding statistics:
[08:34:45.067] <TB1> INFO: General information:
[08:34:45.067] <TB1> INFO: 16bit words read: 2755348
[08:34:45.067] <TB1> INFO: valid events total: 176768
[08:34:45.067] <TB1> INFO: empty events: 7867
[08:34:45.067] <TB1> INFO: valid events with pixels: 168901
[08:34:45.067] <TB1> INFO: valid pixel hits: 668096
[08:34:45.067] <TB1> INFO: Event errors: 0
[08:34:45.067] <TB1> INFO: start marker: 0
[08:34:45.067] <TB1> INFO: stop marker: 0
[08:34:45.067] <TB1> INFO: overflow: 0
[08:34:45.067] <TB1> INFO: invalid 5bit words: 0
[08:34:45.067] <TB1> INFO: invalid XOR eye diagram: 0
[08:34:45.067] <TB1> INFO: frame (failed synchr.): 0
[08:34:45.067] <TB1> INFO: idle data (no TBM trl): 0
[08:34:45.067] <TB1> INFO: no data (only TBM hdr): 0
[08:34:45.067] <TB1> INFO: TBM errors: 0
[08:34:45.067] <TB1> INFO: flawed TBM headers: 0
[08:34:45.067] <TB1> INFO: flawed TBM trailers: 0
[08:34:45.067] <TB1> INFO: event ID mismatches: 0
[08:34:45.067] <TB1> INFO: ROC errors: 0
[08:34:45.067] <TB1> INFO: missing ROC header(s): 0
[08:34:45.067] <TB1> INFO: misplaced readback start: 0
[08:34:45.067] <TB1> INFO: Pixel decoding errors: 0
[08:34:45.067] <TB1> INFO: pixel data incomplete: 0
[08:34:45.067] <TB1> INFO: pixel address: 0
[08:34:45.067] <TB1> INFO: pulse height fill bit: 0
[08:34:45.067] <TB1> INFO: buffer corruption: 0
[08:34:45.067] <TB1> INFO: enter test to run
[08:34:45.067] <TB1> INFO: test: exit no parameter change
[08:34:45.251] <TB1> QUIET: Connection to board 153 closed.
[08:34:45.331] <TB1> INFO: pXar: this is the end, my friend