[10:11:35.864] <TB1> INFO: *** Welcome to pxar ***
[10:11:35.864] <TB1> INFO: *** Today: 2016/08/29
[10:11:35.955] <TB1> INFO: *** Version: fe94
[10:11:35.955] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C15.dat
[10:11:35.956] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//tbmParameters_C0b.dat
[10:11:35.956] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//defaultMaskFile.dat
[10:11:35.956] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters_C15.dat
[10:11:36.036] <TB1> INFO: clk: 4
[10:11:36.036] <TB1> INFO: ctr: 4
[10:11:36.036] <TB1> INFO: sda: 19
[10:11:36.036] <TB1> INFO: tin: 9
[10:11:36.036] <TB1> INFO: level: 15
[10:11:36.036] <TB1> INFO: triggerdelay: 0
[10:11:36.036] <TB1> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[10:11:36.036] <TB1> INFO: Log level: INFO
[10:11:36.044] <TB1> INFO: Found DTB DTB_WXBYFL
[10:11:36.054] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[10:11:36.056] <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:
------------------------------------------------------
[10:11:36.057] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[10:11:37.610] <TB1> INFO: DUT info:
[10:11:37.610] <TB1> INFO: The DUT currently contains the following objects:
[10:11:37.610] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:11:37.610] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:11:37.610] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:11:37.610] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:11:37.610] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:37.610] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:37.610] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:37.610] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:37.610] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:37.610] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:37.610] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:37.610] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:37.610] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:37.610] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:37.610] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:37.610] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:37.610] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:37.610] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:37.610] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:37.610] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:11:38.011] <TB1> INFO: enter 'restricted' command line mode
[10:11:38.011] <TB1> INFO: enter test to run
[10:11:38.011] <TB1> INFO: test: pretest no parameter change
[10:11:38.011] <TB1> INFO: running: pretest
[10:11:38.019] <TB1> INFO: ----------------------------------------------------------------------
[10:11:38.019] <TB1> INFO: PixTestPretest::programROC()
[10:11:38.019] <TB1> INFO: ----------------------------------------------------------------------
[10:11:56.033] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[10:11:56.033] <TB1> INFO: IA differences per ROC: 20.1 20.1 20.9 20.1 22.5 19.3 18.5 20.9 20.9 20.1 20.9 18.5 21.7 20.1 20.9 20.1
[10:11:56.117] <TB1> INFO: enter test to run
[10:11:56.117] <TB1> INFO: test: pretest no parameter change
[10:11:56.117] <TB1> INFO: running: pretest
[10:11:56.118] <TB1> INFO: ----------------------------------------------------------------------
[10:11:56.118] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[10:11:56.118] <TB1> INFO: ----------------------------------------------------------------------
[10:12:02.715] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 388.3 mA = 24.2687 mA/ROC
[10:12:02.715] <TB1> INFO: i(loss) [mA/ROC]: 20.1 20.1 20.1 19.3 19.3 20.1 20.1 19.3 20.1 19.3 19.3 20.1 19.3 19.3 20.1 20.1
[10:12:02.752] <TB1> INFO: enter test to run
[10:12:02.752] <TB1> INFO: test: pretest no parameter change
[10:12:02.752] <TB1> INFO: running: pretest
[10:12:02.752] <TB1> INFO: ----------------------------------------------------------------------
[10:12:02.752] <TB1> INFO: PixTestPretest::findTiming()
[10:12:02.752] <TB1> INFO: ----------------------------------------------------------------------
[10:12:02.752] <TB1> INFO: PixTestCmd::init()
[10:12:03.733] <TB1> WARNING: Not unmasking DUT, not setting Calibrate bits!
[10:13:31.043] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[10:13:31.043] <TB1> INFO: (success/tries = 100/100), width = 4
[10:13:31.044] <TB1> INFO: enter test to run
[10:13:31.044] <TB1> INFO: test: pretest no parameter change
[10:13:31.044] <TB1> INFO: running: pretest
[10:13:31.046] <TB1> INFO: ----------------------------------------------------------------------
[10:13:31.046] <TB1> INFO: PixTestPretest::findWorkingPixel()
[10:13:31.046] <TB1> INFO: ----------------------------------------------------------------------
[10:13:31.141] <TB1> INFO: Expecting 231680 events.
[10:13:36.916] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L497> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[10:13:37.003] <TB1> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[10:13:41.416] <TB1> INFO: 231680 events read in total (9719ms).
[10:13:41.420] <TB1> INFO: Test took 10368ms.
[10:13:41.653] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:13:41.725] <TB1> INFO: enter test to run
[10:13:41.726] <TB1> INFO: test: pretest no parameter change
[10:13:41.726] <TB1> INFO: running: pretest
[10:13:41.727] <TB1> INFO: ----------------------------------------------------------------------
[10:13:41.727] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[10:13:41.727] <TB1> INFO: ----------------------------------------------------------------------
[10:13:41.819] <TB1> INFO: Expecting 231680 events.
[10:13:52.046] <TB1> INFO: 231680 events read in total (9670ms).
[10:13:52.050] <TB1> INFO: Test took 10319ms.
[10:13:52.274] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[10:13:52.274] <TB1> INFO: CalDel: 138 156 149 150 143 174 133 146 156 137 153 138 145 159 149 156
[10:13:52.274] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[10:13:52.309] <TB1> INFO: enter test to run
[10:13:52.309] <TB1> INFO: test: pretest no parameter change
[10:13:52.309] <TB1> INFO: running: pretest
[10:13:52.309] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C0.dat
[10:13:52.309] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C1.dat
[10:13:52.310] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C2.dat
[10:13:52.310] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C3.dat
[10:13:52.310] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C4.dat
[10:13:52.310] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C5.dat
[10:13:52.310] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C6.dat
[10:13:52.310] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C7.dat
[10:13:52.310] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C8.dat
[10:13:52.310] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C9.dat
[10:13:52.310] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C10.dat
[10:13:52.310] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C11.dat
[10:13:52.311] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C12.dat
[10:13:52.311] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C13.dat
[10:13:52.311] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C14.dat
[10:13:52.311] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters_C15.dat
[10:13:52.311] <TB1> INFO: enter test to run
[10:13:52.311] <TB1> INFO: test: fulltest no parameter change
[10:13:52.311] <TB1> INFO: running: fulltest
[10:13:52.311] <TB1> INFO: ######################################################################
[10:13:52.311] <TB1> INFO: PixTestFullTest::doTest()
[10:13:52.311] <TB1> INFO: ######################################################################
[10:13:52.312] <TB1> INFO: ######################################################################
[10:13:52.312] <TB1> INFO: PixTestAlive::doTest()
[10:13:52.312] <TB1> INFO: ######################################################################
[10:13:52.314] <TB1> INFO: ----------------------------------------------------------------------
[10:13:52.314] <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)
[10:13:52.314] <TB1> INFO: ----------------------------------------------------------------------
[10:13:52.582] <TB1> INFO: Expecting 41600 events.
[10:13:56.448] <TB1> INFO: 41600 events read in total (3309ms).
[10:13:56.448] <TB1> INFO: Test took 4133ms.
[10:13:56.680] <TB1> INFO: PixTestAlive::aliveTest() done
[10:13:56.680] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:13:56.681] <TB1> INFO: ----------------------------------------------------------------------
[10:13:56.681] <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)
[10:13:56.681] <TB1> INFO: ----------------------------------------------------------------------
[10:13:56.968] <TB1> INFO: Expecting 41600 events.
[10:13:59.976] <TB1> INFO: 41600 events read in total (2452ms).
[10:13:59.976] <TB1> INFO: Test took 3292ms.
[10:13:59.977] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:14:00.222] <TB1> INFO: PixTestAlive::maskTest() done
[10:14:00.222] <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
[10:14:00.223] <TB1> INFO: ----------------------------------------------------------------------
[10:14:00.223] <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)
[10:14:00.223] <TB1> INFO: ----------------------------------------------------------------------
[10:14:00.497] <TB1> INFO: Expecting 41600 events.
[10:14:04.306] <TB1> INFO: 41600 events read in total (3253ms).
[10:14:04.306] <TB1> INFO: Test took 4080ms.
[10:14:04.534] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[10:14:04.534] <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
[10:14:04.535] <TB1> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[10:14:04.535] <TB1> INFO: Decoding statistics:
[10:14:04.535] <TB1> INFO: General information:
[10:14:04.535] <TB1> INFO: 16bit words read: 0
[10:14:04.535] <TB1> INFO: valid events total: 0
[10:14:04.535] <TB1> INFO: empty events: 0
[10:14:04.535] <TB1> INFO: valid events with pixels: 0
[10:14:04.535] <TB1> INFO: valid pixel hits: 0
[10:14:04.535] <TB1> INFO: Event errors: 0
[10:14:04.535] <TB1> INFO: start marker: 0
[10:14:04.535] <TB1> INFO: stop marker: 0
[10:14:04.535] <TB1> INFO: overflow: 0
[10:14:04.535] <TB1> INFO: invalid 5bit words: 0
[10:14:04.535] <TB1> INFO: invalid XOR eye diagram: 0
[10:14:04.535] <TB1> INFO: frame (failed synchr.): 0
[10:14:04.535] <TB1> INFO: idle data (no TBM trl): 0
[10:14:04.535] <TB1> INFO: no data (only TBM hdr): 0
[10:14:04.535] <TB1> INFO: TBM errors: 0
[10:14:04.535] <TB1> INFO: flawed TBM headers: 0
[10:14:04.535] <TB1> INFO: flawed TBM trailers: 0
[10:14:04.535] <TB1> INFO: event ID mismatches: 0
[10:14:04.535] <TB1> INFO: ROC errors: 0
[10:14:04.535] <TB1> INFO: missing ROC header(s): 0
[10:14:04.535] <TB1> INFO: misplaced readback start: 0
[10:14:04.535] <TB1> INFO: Pixel decoding errors: 0
[10:14:04.535] <TB1> INFO: pixel data incomplete: 0
[10:14:04.535] <TB1> INFO: pixel address: 0
[10:14:04.535] <TB1> INFO: pulse height fill bit: 0
[10:14:04.535] <TB1> INFO: buffer corruption: 0
[10:14:04.549] <TB1> INFO: ######################################################################
[10:14:04.549] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:14:04.549] <TB1> INFO: ######################################################################
[10:14:04.551] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[10:14:04.562] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:14:04.562] <TB1> INFO: run 1 of 1
[10:14:04.849] <TB1> INFO: Expecting 3120000 events.
[10:14:46.218] <TB1> INFO: 844370 events read in total (40813ms).
[10:15:26.452] <TB1> INFO: 1675950 events read in total (81048ms).
[10:16:06.865] <TB1> INFO: 2515265 events read in total (121460ms).
[10:16:35.696] <TB1> INFO: 3120000 events read in total (150291ms).
[10:16:35.747] <TB1> INFO: Test took 151185ms.
[10:17:02.894] <TB1> INFO: PixTestBBMap::doTest() done, duration: 178 seconds
[10:17:02.894] <TB1> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:17:02.894] <TB1> INFO: separation cut (per ROC): 76 87 89 80 84 74 82 80 73 70 69 72 93 88 87 79
[10:17:02.894] <TB1> INFO: Decoding statistics:
[10:17:02.894] <TB1> INFO: General information:
[10:17:02.894] <TB1> INFO: 16bit words read: 0
[10:17:02.894] <TB1> INFO: valid events total: 0
[10:17:02.894] <TB1> INFO: empty events: 0
[10:17:02.894] <TB1> INFO: valid events with pixels: 0
[10:17:02.894] <TB1> INFO: valid pixel hits: 0
[10:17:02.894] <TB1> INFO: Event errors: 0
[10:17:02.894] <TB1> INFO: start marker: 0
[10:17:02.894] <TB1> INFO: stop marker: 0
[10:17:02.894] <TB1> INFO: overflow: 0
[10:17:02.894] <TB1> INFO: invalid 5bit words: 0
[10:17:02.894] <TB1> INFO: invalid XOR eye diagram: 0
[10:17:02.894] <TB1> INFO: frame (failed synchr.): 0
[10:17:02.894] <TB1> INFO: idle data (no TBM trl): 0
[10:17:02.894] <TB1> INFO: no data (only TBM hdr): 0
[10:17:02.894] <TB1> INFO: TBM errors: 0
[10:17:02.894] <TB1> INFO: flawed TBM headers: 0
[10:17:02.894] <TB1> INFO: flawed TBM trailers: 0
[10:17:02.894] <TB1> INFO: event ID mismatches: 0
[10:17:02.894] <TB1> INFO: ROC errors: 0
[10:17:02.894] <TB1> INFO: missing ROC header(s): 0
[10:17:02.894] <TB1> INFO: misplaced readback start: 0
[10:17:02.894] <TB1> INFO: Pixel decoding errors: 0
[10:17:02.894] <TB1> INFO: pixel data incomplete: 0
[10:17:02.894] <TB1> INFO: pixel address: 0
[10:17:02.894] <TB1> INFO: pulse height fill bit: 0
[10:17:02.894] <TB1> INFO: buffer corruption: 0
[10:17:02.976] <TB1> INFO: ######################################################################
[10:17:02.976] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:17:02.976] <TB1> INFO: ######################################################################
[10:17:02.976] <TB1> INFO: ----------------------------------------------------------------------
[10:17:02.976] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:17:02.976] <TB1> INFO: ----------------------------------------------------------------------
[10:17:02.976] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:17:02.985] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:17:02.985] <TB1> INFO: run 1 of 1
[10:17:03.244] <TB1> INFO: Expecting 26208000 events.
[10:17:36.902] <TB1> INFO: 872500 events read in total (33101ms).
[10:18:09.793] <TB1> INFO: 1735100 events read in total (65992ms).
[10:18:42.741] <TB1> INFO: 2595150 events read in total (98940ms).
[10:19:15.191] <TB1> INFO: 3454450 events read in total (131390ms).
[10:19:47.963] <TB1> INFO: 4312400 events read in total (164162ms).
[10:20:20.624] <TB1> INFO: 5169700 events read in total (196823ms).
[10:20:53.365] <TB1> INFO: 6027300 events read in total (229564ms).
[10:21:26.048] <TB1> INFO: 6884000 events read in total (262247ms).
[10:21:58.826] <TB1> INFO: 7740900 events read in total (295025ms).
[10:22:32.111] <TB1> INFO: 8597200 events read in total (328310ms).
[10:23:05.264] <TB1> INFO: 9452850 events read in total (361463ms).
[10:23:37.670] <TB1> INFO: 10305750 events read in total (393869ms).
[10:24:10.417] <TB1> INFO: 11158650 events read in total (426616ms).
[10:24:43.022] <TB1> INFO: 12010550 events read in total (459221ms).
[10:25:16.168] <TB1> INFO: 12862750 events read in total (492367ms).
[10:25:49.020] <TB1> INFO: 13708850 events read in total (525219ms).
[10:26:21.779] <TB1> INFO: 14552550 events read in total (557978ms).
[10:26:54.430] <TB1> INFO: 15396850 events read in total (590629ms).
[10:27:26.937] <TB1> INFO: 16239300 events read in total (623136ms).
[10:27:59.751] <TB1> INFO: 17079000 events read in total (655950ms).
[10:28:32.542] <TB1> INFO: 17920750 events read in total (688741ms).
[10:29:04.814] <TB1> INFO: 18761650 events read in total (721013ms).
[10:29:37.591] <TB1> INFO: 19600050 events read in total (753790ms).
[10:30:10.206] <TB1> INFO: 20439300 events read in total (786405ms).
[10:30:42.753] <TB1> INFO: 21278750 events read in total (818952ms).
[10:31:15.702] <TB1> INFO: 22116800 events read in total (851901ms).
[10:31:48.658] <TB1> INFO: 22956950 events read in total (884857ms).
[10:32:21.103] <TB1> INFO: 23800450 events read in total (917302ms).
[10:32:53.841] <TB1> INFO: 24643350 events read in total (950040ms).
[10:33:26.694] <TB1> INFO: 25485750 events read in total (982893ms).
[10:33:55.431] <TB1> INFO: 26208000 events read in total (1011630ms).
[10:33:55.464] <TB1> INFO: Test took 1012479ms.
[10:33:55.786] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:33:57.503] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:33:59.020] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:34:00.606] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:34:02.339] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:34:04.057] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:34:05.597] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:34:07.608] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:34:09.569] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:34:11.232] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:34:13.474] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:34:15.415] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:34:17.073] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:34:18.634] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:34:20.205] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:34:21.753] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:34:23.271] <TB1> INFO: PixTestScurves::scurves() done
[10:34:23.271] <TB1> INFO: Vcal mean: 83.30 86.26 94.24 84.87 81.26 81.80 84.46 80.82 80.64 76.10 80.98 78.56 101.51 92.17 84.81 83.50
[10:34:23.271] <TB1> INFO: Vcal RMS: 4.66 4.94 5.29 5.29 3.74 4.05 4.37 4.25 4.13 4.22 4.14 4.31 5.71 5.26 5.10 4.63
[10:34:23.271] <TB1> INFO: PixTestScurves::fullTest() done, duration: 1040 seconds
[10:34:23.271] <TB1> INFO: Decoding statistics:
[10:34:23.271] <TB1> INFO: General information:
[10:34:23.271] <TB1> INFO: 16bit words read: 0
[10:34:23.271] <TB1> INFO: valid events total: 0
[10:34:23.271] <TB1> INFO: empty events: 0
[10:34:23.271] <TB1> INFO: valid events with pixels: 0
[10:34:23.271] <TB1> INFO: valid pixel hits: 0
[10:34:23.271] <TB1> INFO: Event errors: 0
[10:34:23.271] <TB1> INFO: start marker: 0
[10:34:23.271] <TB1> INFO: stop marker: 0
[10:34:23.271] <TB1> INFO: overflow: 0
[10:34:23.271] <TB1> INFO: invalid 5bit words: 0
[10:34:23.271] <TB1> INFO: invalid XOR eye diagram: 0
[10:34:23.271] <TB1> INFO: frame (failed synchr.): 0
[10:34:23.271] <TB1> INFO: idle data (no TBM trl): 0
[10:34:23.271] <TB1> INFO: no data (only TBM hdr): 0
[10:34:23.271] <TB1> INFO: TBM errors: 0
[10:34:23.271] <TB1> INFO: flawed TBM headers: 0
[10:34:23.271] <TB1> INFO: flawed TBM trailers: 0
[10:34:23.271] <TB1> INFO: event ID mismatches: 0
[10:34:23.271] <TB1> INFO: ROC errors: 0
[10:34:23.271] <TB1> INFO: missing ROC header(s): 0
[10:34:23.271] <TB1> INFO: misplaced readback start: 0
[10:34:23.271] <TB1> INFO: Pixel decoding errors: 0
[10:34:23.271] <TB1> INFO: pixel data incomplete: 0
[10:34:23.271] <TB1> INFO: pixel address: 0
[10:34:23.271] <TB1> INFO: pulse height fill bit: 0
[10:34:23.271] <TB1> INFO: buffer corruption: 0
[10:34:23.345] <TB1> INFO: ######################################################################
[10:34:23.345] <TB1> INFO: PixTestTrim::doTest()
[10:34:23.345] <TB1> INFO: ######################################################################
[10:34:23.346] <TB1> INFO: ----------------------------------------------------------------------
[10:34:23.346] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:34:23.346] <TB1> INFO: ----------------------------------------------------------------------
[10:34:23.425] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:34:23.425] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:34:23.434] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:34:23.434] <TB1> INFO: run 1 of 1
[10:34:23.733] <TB1> INFO: Expecting 6281600 events.
[10:35:03.207] <TB1> INFO: 1058840 events read in total (38917ms).
[10:35:42.163] <TB1> INFO: 2111280 events read in total (77873ms).
[10:36:21.359] <TB1> INFO: 3156720 events read in total (117069ms).
[10:37:00.802] <TB1> INFO: 4206360 events read in total (156512ms).
[10:37:39.456] <TB1> INFO: 5260020 events read in total (195166ms).
[10:38:17.875] <TB1> INFO: 6281600 events read in total (233585ms).
[10:38:17.902] <TB1> INFO: Test took 234468ms.
[10:38:37.874] <TB1> INFO: ROC 0 VthrComp = 92
[10:38:37.874] <TB1> INFO: ROC 1 VthrComp = 95
[10:38:37.875] <TB1> INFO: ROC 2 VthrComp = 100
[10:38:37.875] <TB1> INFO: ROC 3 VthrComp = 90
[10:38:37.875] <TB1> INFO: ROC 4 VthrComp = 93
[10:38:37.875] <TB1> INFO: ROC 5 VthrComp = 88
[10:38:37.875] <TB1> INFO: ROC 6 VthrComp = 93
[10:38:37.875] <TB1> INFO: ROC 7 VthrComp = 88
[10:38:37.875] <TB1> INFO: ROC 8 VthrComp = 86
[10:38:37.875] <TB1> INFO: ROC 9 VthrComp = 82
[10:38:37.875] <TB1> INFO: ROC 10 VthrComp = 89
[10:38:37.875] <TB1> INFO: ROC 11 VthrComp = 84
[10:38:37.876] <TB1> INFO: ROC 12 VthrComp = 101
[10:38:37.876] <TB1> INFO: ROC 13 VthrComp = 94
[10:38:37.876] <TB1> INFO: ROC 14 VthrComp = 90
[10:38:37.876] <TB1> INFO: ROC 15 VthrComp = 90
[10:38:37.876] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:38:37.876] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:38:37.884] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:38:37.884] <TB1> INFO: run 1 of 1
[10:38:38.197] <TB1> INFO: Expecting 6281600 events.
[10:39:13.468] <TB1> INFO: 753000 events read in total (34715ms).
[10:39:48.729] <TB1> INFO: 1504570 events read in total (69976ms).
[10:40:23.629] <TB1> INFO: 2256800 events read in total (104876ms).
[10:40:58.622] <TB1> INFO: 3009340 events read in total (139869ms).
[10:41:33.663] <TB1> INFO: 3757570 events read in total (174910ms).
[10:42:08.688] <TB1> INFO: 4502720 events read in total (209935ms).
[10:42:43.286] <TB1> INFO: 5246360 events read in total (244533ms).
[10:43:17.926] <TB1> INFO: 5989670 events read in total (279173ms).
[10:43:31.875] <TB1> INFO: 6281600 events read in total (293122ms).
[10:43:31.928] <TB1> INFO: Test took 294043ms.
[10:43:58.433] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 56.7698 for pixel 0/64 mean/min/max = 44.5087/32.0644/56.953
[10:43:58.433] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 57.0393 for pixel 0/21 mean/min/max = 44.7187/32.3089/57.1285
[10:43:58.434] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 57.4034 for pixel 2/8 mean/min/max = 44.7304/32.0075/57.4533
[10:43:58.434] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 58.948 for pixel 0/71 mean/min/max = 45.7074/32.3677/59.0471
[10:43:58.434] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 54.5563 for pixel 48/12 mean/min/max = 43.7835/32.9629/54.6041
[10:43:58.434] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 55.3366 for pixel 22/79 mean/min/max = 44.3273/33.2981/55.3565
[10:43:58.435] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 56.3012 for pixel 0/36 mean/min/max = 44.5963/32.8879/56.3048
[10:43:58.435] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 56.5796 for pixel 6/78 mean/min/max = 45.1919/33.6269/56.7568
[10:43:58.435] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 56.4178 for pixel 0/17 mean/min/max = 44.6103/32.7591/56.4615
[10:43:58.435] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 56.2288 for pixel 13/76 mean/min/max = 44.4742/32.6416/56.3067
[10:43:58.436] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 56.6733 for pixel 0/77 mean/min/max = 45.1865/33.4032/56.9698
[10:43:58.436] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 57.051 for pixel 15/18 mean/min/max = 44.9199/32.2674/57.5723
[10:43:58.436] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 61.8509 for pixel 24/10 mean/min/max = 46.8956/31.8227/61.9685
[10:43:58.436] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 58.6136 for pixel 18/11 mean/min/max = 45.4108/32.1805/58.6411
[10:43:58.437] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 59.0435 for pixel 4/76 mean/min/max = 45.7019/32.2932/59.1106
[10:43:58.437] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 57.6084 for pixel 8/25 mean/min/max = 45.1193/32.61/57.6286
[10:43:58.438] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:43:58.527] <TB1> INFO: Expecting 514560 events.
[10:44:11.464] <TB1> INFO: 514560 events read in total (12381ms).
[10:44:11.469] <TB1> INFO: Expecting 514560 events.
[10:44:24.553] <TB1> INFO: 514560 events read in total (12661ms).
[10:44:24.560] <TB1> INFO: Expecting 514560 events.
[10:44:37.461] <TB1> INFO: 514560 events read in total (12487ms).
[10:44:37.470] <TB1> INFO: Expecting 514560 events.
[10:44:50.352] <TB1> INFO: 514560 events read in total (12468ms).
[10:44:50.364] <TB1> INFO: Expecting 514560 events.
[10:45:03.509] <TB1> INFO: 514560 events read in total (12734ms).
[10:45:03.523] <TB1> INFO: Expecting 514560 events.
[10:45:16.477] <TB1> INFO: 514560 events read in total (12553ms).
[10:45:16.492] <TB1> INFO: Expecting 514560 events.
[10:45:29.537] <TB1> INFO: 514560 events read in total (12633ms).
[10:45:29.554] <TB1> INFO: Expecting 514560 events.
[10:45:42.621] <TB1> INFO: 514560 events read in total (12664ms).
[10:45:42.640] <TB1> INFO: Expecting 514560 events.
[10:45:55.540] <TB1> INFO: 514560 events read in total (12498ms).
[10:45:55.562] <TB1> INFO: Expecting 514560 events.
[10:46:08.554] <TB1> INFO: 514560 events read in total (12590ms).
[10:46:08.578] <TB1> INFO: Expecting 514560 events.
[10:46:21.370] <TB1> INFO: 514560 events read in total (12390ms).
[10:46:21.394] <TB1> INFO: Expecting 514560 events.
[10:46:34.532] <TB1> INFO: 514560 events read in total (12736ms).
[10:46:34.558] <TB1> INFO: Expecting 514560 events.
[10:46:47.531] <TB1> INFO: 514560 events read in total (12571ms).
[10:46:47.562] <TB1> INFO: Expecting 514560 events.
[10:47:00.459] <TB1> INFO: 514560 events read in total (12496ms).
[10:47:00.491] <TB1> INFO: Expecting 514560 events.
[10:47:13.426] <TB1> INFO: 514560 events read in total (12533ms).
[10:47:13.457] <TB1> INFO: Expecting 514560 events.
[10:47:26.340] <TB1> INFO: 514560 events read in total (12481ms).
[10:47:26.374] <TB1> INFO: Test took 207936ms.
[10:47:27.513] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:47:27.522] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:47:27.522] <TB1> INFO: run 1 of 1
[10:47:27.807] <TB1> INFO: Expecting 6281600 events.
[10:48:03.143] <TB1> INFO: 742780 events read in total (34780ms).
[10:48:37.913] <TB1> INFO: 1484330 events read in total (69550ms).
[10:49:13.110] <TB1> INFO: 2226660 events read in total (104747ms).
[10:49:48.075] <TB1> INFO: 2969340 events read in total (139712ms).
[10:50:22.648] <TB1> INFO: 3706980 events read in total (174285ms).
[10:50:57.677] <TB1> INFO: 4440600 events read in total (209314ms).
[10:51:32.824] <TB1> INFO: 5172050 events read in total (244461ms).
[10:52:07.083] <TB1> INFO: 5903020 events read in total (278720ms).
[10:52:24.979] <TB1> INFO: 6281600 events read in total (296616ms).
[10:52:25.023] <TB1> INFO: Test took 297501ms.
[10:52:48.917] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.086949 .. 49.721757
[10:52:48.993] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 59 (-1/-1) hits flags = 528 (plus default)
[10:52:49.001] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:52:49.001] <TB1> INFO: run 1 of 1
[10:52:49.262] <TB1> INFO: Expecting 2496000 events.
[10:53:27.703] <TB1> INFO: 937050 events read in total (37885ms).
[10:54:05.536] <TB1> INFO: 1871600 events read in total (75718ms).
[10:54:31.368] <TB1> INFO: 2496000 events read in total (101550ms).
[10:54:31.384] <TB1> INFO: Test took 102384ms.
[10:54:46.643] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 16.672774 .. 44.493534
[10:54:46.750] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 6 .. 54 (-1/-1) hits flags = 528 (plus default)
[10:54:46.760] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:54:46.760] <TB1> INFO: run 1 of 1
[10:54:47.026] <TB1> INFO: Expecting 2038400 events.
[10:55:25.829] <TB1> INFO: 941260 events read in total (38246ms).
[10:56:03.731] <TB1> INFO: 1880520 events read in total (76148ms).
[10:56:10.561] <TB1> INFO: 2038400 events read in total (82978ms).
[10:56:10.571] <TB1> INFO: Test took 83811ms.
[10:56:23.849] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 22.470347 .. 41.493520
[10:56:23.953] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 12 .. 51 (-1/-1) hits flags = 528 (plus default)
[10:56:23.964] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:56:23.964] <TB1> INFO: run 1 of 1
[10:56:24.302] <TB1> INFO: Expecting 1664000 events.
[10:57:02.994] <TB1> INFO: 932810 events read in total (38136ms).
[10:57:32.881] <TB1> INFO: 1664000 events read in total (68023ms).
[10:57:32.891] <TB1> INFO: Test took 68926ms.
[10:57:46.490] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 24.513621 .. 41.493520
[10:57:46.569] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 14 .. 51 (-1/-1) hits flags = 528 (plus default)
[10:57:46.577] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:57:46.577] <TB1> INFO: run 1 of 1
[10:57:46.837] <TB1> INFO: Expecting 1580800 events.
[10:58:25.830] <TB1> INFO: 918200 events read in total (38436ms).
[10:58:53.367] <TB1> INFO: 1580800 events read in total (65973ms).
[10:58:53.379] <TB1> INFO: Test took 66802ms.
[10:59:06.602] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[10:59:06.602] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[10:59:06.610] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:59:06.610] <TB1> INFO: run 1 of 1
[10:59:06.885] <TB1> INFO: Expecting 1705600 events.
[10:59:44.670] <TB1> INFO: 879710 events read in total (37229ms).
[11:00:19.429] <TB1> INFO: 1705600 events read in total (71988ms).
[11:00:19.443] <TB1> INFO: Test took 72833ms.
[11:00:33.971] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C0.dat
[11:00:33.971] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C1.dat
[11:00:33.971] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C2.dat
[11:00:33.971] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C3.dat
[11:00:33.971] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C4.dat
[11:00:33.971] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C5.dat
[11:00:33.971] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C6.dat
[11:00:33.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C7.dat
[11:00:33.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C8.dat
[11:00:33.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C9.dat
[11:00:33.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C10.dat
[11:00:33.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C11.dat
[11:00:33.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C12.dat
[11:00:33.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C13.dat
[11:00:33.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C14.dat
[11:00:33.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C15.dat
[11:00:33.973] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C0.dat
[11:00:33.979] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C1.dat
[11:00:33.986] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C2.dat
[11:00:33.992] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C3.dat
[11:00:33.999] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C4.dat
[11:00:34.006] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C5.dat
[11:00:34.012] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C6.dat
[11:00:34.018] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C7.dat
[11:00:34.024] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C8.dat
[11:00:34.031] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C9.dat
[11:00:34.038] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C10.dat
[11:00:34.044] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C11.dat
[11:00:34.050] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C12.dat
[11:00:34.057] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C13.dat
[11:00:34.064] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C14.dat
[11:00:34.071] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//trimParameters35_C15.dat
[11:00:34.077] <TB1> INFO: PixTestTrim::trimTest() done
[11:00:34.077] <TB1> INFO: vtrim: 86 98 97 101 99 93 99 101 80 100 88 97 109 99 96 99
[11:00:34.077] <TB1> INFO: vthrcomp: 92 95 100 90 93 88 93 88 86 82 89 84 101 94 90 90
[11:00:34.077] <TB1> INFO: vcal mean: 34.99 34.93 34.98 34.93 35.00 34.95 35.00 34.92 35.00 34.96 35.02 34.96 34.97 34.98 34.95 34.94
[11:00:34.077] <TB1> INFO: vcal RMS: 0.77 0.77 0.84 0.88 0.75 0.77 0.80 0.78 0.78 0.79 0.79 0.80 0.86 0.84 0.81 0.79
[11:00:34.077] <TB1> INFO: bits mean: 9.19 9.49 9.71 8.92 9.85 9.67 9.62 9.14 9.28 9.65 9.08 9.43 9.22 9.46 9.27 9.20
[11:00:34.077] <TB1> INFO: bits RMS: 2.87 2.67 2.63 2.91 2.48 2.46 2.63 2.69 2.75 2.61 2.67 2.76 2.73 2.66 2.74 2.77
[11:00:34.084] <TB1> INFO: ----------------------------------------------------------------------
[11:00:34.084] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[11:00:34.084] <TB1> INFO: ----------------------------------------------------------------------
[11:00:34.086] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[11:00:34.094] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:00:34.094] <TB1> INFO: run 1 of 1
[11:00:34.357] <TB1> INFO: Expecting 4160000 events.
[11:01:16.406] <TB1> INFO: 906785 events read in total (41493ms).
[11:01:57.322] <TB1> INFO: 1806680 events read in total (82409ms).
[11:02:38.200] <TB1> INFO: 2697905 events read in total (123287ms).
[11:03:19.621] <TB1> INFO: 3584895 events read in total (164708ms).
[11:03:47.066] <TB1> INFO: 4160000 events read in total (192153ms).
[11:03:47.109] <TB1> INFO: Test took 193014ms.
[11:04:19.301] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 161 (-1/-1) hits flags = 528 (plus default)
[11:04:19.310] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:04:19.310] <TB1> INFO: run 1 of 1
[11:04:19.577] <TB1> INFO: Expecting 3369600 events.
[11:05:02.705] <TB1> INFO: 949510 events read in total (42572ms).
[11:05:44.591] <TB1> INFO: 1887480 events read in total (84458ms).
[11:06:26.515] <TB1> INFO: 2813420 events read in total (126382ms).
[11:06:51.485] <TB1> INFO: 3369600 events read in total (151352ms).
[11:06:51.514] <TB1> INFO: Test took 152204ms.
[11:07:17.787] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 151 (-1/-1) hits flags = 528 (plus default)
[11:07:17.795] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:07:17.795] <TB1> INFO: run 1 of 1
[11:07:18.066] <TB1> INFO: Expecting 3161600 events.
[11:08:01.668] <TB1> INFO: 976280 events read in total (43046ms).
[11:08:44.180] <TB1> INFO: 1937110 events read in total (85558ms).
[11:09:26.396] <TB1> INFO: 2887135 events read in total (127775ms).
[11:09:39.011] <TB1> INFO: 3161600 events read in total (140389ms).
[11:09:39.049] <TB1> INFO: Test took 141254ms.
[11:10:03.218] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 151 (-1/-1) hits flags = 528 (plus default)
[11:10:03.226] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:10:03.226] <TB1> INFO: run 1 of 1
[11:10:03.490] <TB1> INFO: Expecting 3161600 events.
[11:10:46.944] <TB1> INFO: 975455 events read in total (42894ms).
[11:11:29.119] <TB1> INFO: 1935335 events read in total (85069ms).
[11:12:11.088] <TB1> INFO: 2884665 events read in total (127039ms).
[11:12:23.633] <TB1> INFO: 3161600 events read in total (139583ms).
[11:12:23.661] <TB1> INFO: Test took 140435ms.
[11:12:47.772] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 151 (-1/-1) hits flags = 528 (plus default)
[11:12:47.781] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:12:47.781] <TB1> INFO: run 1 of 1
[11:12:48.046] <TB1> INFO: Expecting 3161600 events.
[11:13:31.613] <TB1> INFO: 974340 events read in total (43011ms).
[11:14:14.134] <TB1> INFO: 1933400 events read in total (85532ms).
[11:14:56.082] <TB1> INFO: 2881900 events read in total (127480ms).
[11:15:08.653] <TB1> INFO: 3161600 events read in total (140051ms).
[11:15:08.681] <TB1> INFO: Test took 140900ms.
[11:15:34.576] <TB1> INFO: PixTestTrim::trimBitTest() done
[11:15:34.578] <TB1> INFO: PixTestTrim::doTest() done, duration: 2471 seconds
[11:15:34.578] <TB1> INFO: Decoding statistics:
[11:15:34.578] <TB1> INFO: General information:
[11:15:34.578] <TB1> INFO: 16bit words read: 0
[11:15:34.578] <TB1> INFO: valid events total: 0
[11:15:34.578] <TB1> INFO: empty events: 0
[11:15:34.578] <TB1> INFO: valid events with pixels: 0
[11:15:34.578] <TB1> INFO: valid pixel hits: 0
[11:15:34.578] <TB1> INFO: Event errors: 0
[11:15:34.578] <TB1> INFO: start marker: 0
[11:15:34.578] <TB1> INFO: stop marker: 0
[11:15:34.578] <TB1> INFO: overflow: 0
[11:15:34.578] <TB1> INFO: invalid 5bit words: 0
[11:15:34.578] <TB1> INFO: invalid XOR eye diagram: 0
[11:15:34.578] <TB1> INFO: frame (failed synchr.): 0
[11:15:34.578] <TB1> INFO: idle data (no TBM trl): 0
[11:15:34.578] <TB1> INFO: no data (only TBM hdr): 0
[11:15:34.578] <TB1> INFO: TBM errors: 0
[11:15:34.578] <TB1> INFO: flawed TBM headers: 0
[11:15:34.578] <TB1> INFO: flawed TBM trailers: 0
[11:15:34.578] <TB1> INFO: event ID mismatches: 0
[11:15:34.578] <TB1> INFO: ROC errors: 0
[11:15:34.578] <TB1> INFO: missing ROC header(s): 0
[11:15:34.578] <TB1> INFO: misplaced readback start: 0
[11:15:34.578] <TB1> INFO: Pixel decoding errors: 0
[11:15:34.578] <TB1> INFO: pixel data incomplete: 0
[11:15:34.578] <TB1> INFO: pixel address: 0
[11:15:34.578] <TB1> INFO: pulse height fill bit: 0
[11:15:34.578] <TB1> INFO: buffer corruption: 0
[11:15:35.267] <TB1> INFO: ######################################################################
[11:15:35.267] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:15:35.267] <TB1> INFO: ######################################################################
[11:15:35.527] <TB1> INFO: Expecting 41600 events.
[11:15:39.290] <TB1> INFO: 41600 events read in total (3207ms).
[11:15:39.291] <TB1> INFO: Test took 4023ms.
[11:15:39.766] <TB1> INFO: Expecting 41600 events.
[11:15:43.600] <TB1> INFO: 41600 events read in total (3278ms).
[11:15:43.600] <TB1> INFO: Test took 4107ms.
[11:15:43.888] <TB1> INFO: Expecting 41600 events.
[11:15:47.724] <TB1> INFO: 41600 events read in total (3280ms).
[11:15:47.725] <TB1> INFO: Test took 4103ms.
[11:15:47.981] <TB1> INFO: Expecting 2560 events.
[11:15:48.835] <TB1> INFO: 2560 events read in total (298ms).
[11:15:48.835] <TB1> INFO: Test took 1097ms.
[11:15:49.140] <TB1> INFO: Expecting 2560 events.
[11:15:49.993] <TB1> INFO: 2560 events read in total (297ms).
[11:15:49.993] <TB1> INFO: Test took 1158ms.
[11:15:50.298] <TB1> INFO: Expecting 2560 events.
[11:15:51.151] <TB1> INFO: 2560 events read in total (296ms).
[11:15:51.151] <TB1> INFO: Test took 1157ms.
[11:15:51.456] <TB1> INFO: Expecting 2560 events.
[11:15:52.312] <TB1> INFO: 2560 events read in total (300ms).
[11:15:52.313] <TB1> INFO: Test took 1162ms.
[11:15:52.617] <TB1> INFO: Expecting 2560 events.
[11:15:53.472] <TB1> INFO: 2560 events read in total (298ms).
[11:15:53.472] <TB1> INFO: Test took 1159ms.
[11:15:53.777] <TB1> INFO: Expecting 2560 events.
[11:15:54.630] <TB1> INFO: 2560 events read in total (297ms).
[11:15:54.630] <TB1> INFO: Test took 1157ms.
[11:15:54.936] <TB1> INFO: Expecting 2560 events.
[11:15:55.789] <TB1> INFO: 2560 events read in total (297ms).
[11:15:55.789] <TB1> INFO: Test took 1158ms.
[11:15:56.094] <TB1> INFO: Expecting 2560 events.
[11:15:56.948] <TB1> INFO: 2560 events read in total (297ms).
[11:15:56.948] <TB1> INFO: Test took 1159ms.
[11:15:57.254] <TB1> INFO: Expecting 2560 events.
[11:15:58.109] <TB1> INFO: 2560 events read in total (299ms).
[11:15:58.109] <TB1> INFO: Test took 1160ms.
[11:15:58.414] <TB1> INFO: Expecting 2560 events.
[11:15:59.267] <TB1> INFO: 2560 events read in total (297ms).
[11:15:59.268] <TB1> INFO: Test took 1158ms.
[11:15:59.572] <TB1> INFO: Expecting 2560 events.
[11:16:00.428] <TB1> INFO: 2560 events read in total (300ms).
[11:16:00.428] <TB1> INFO: Test took 1160ms.
[11:16:00.733] <TB1> INFO: Expecting 2560 events.
[11:16:01.590] <TB1> INFO: 2560 events read in total (301ms).
[11:16:01.590] <TB1> INFO: Test took 1161ms.
[11:16:01.894] <TB1> INFO: Expecting 2560 events.
[11:16:02.747] <TB1> INFO: 2560 events read in total (297ms).
[11:16:02.747] <TB1> INFO: Test took 1157ms.
[11:16:03.052] <TB1> INFO: Expecting 2560 events.
[11:16:03.906] <TB1> INFO: 2560 events read in total (297ms).
[11:16:03.906] <TB1> INFO: Test took 1159ms.
[11:16:04.212] <TB1> INFO: Expecting 2560 events.
[11:16:05.070] <TB1> INFO: 2560 events read in total (302ms).
[11:16:05.070] <TB1> INFO: Test took 1162ms.
[11:16:05.375] <TB1> INFO: Expecting 2560 events.
[11:16:06.234] <TB1> INFO: 2560 events read in total (303ms).
[11:16:06.234] <TB1> INFO: Test took 1164ms.
[11:16:06.236] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:16:06.540] <TB1> INFO: Expecting 655360 events.
[11:16:23.384] <TB1> INFO: 655360 events read in total (16287ms).
[11:16:23.393] <TB1> INFO: Expecting 655360 events.
[11:16:40.084] <TB1> INFO: 655360 events read in total (16289ms).
[11:16:40.096] <TB1> INFO: Expecting 655360 events.
[11:16:56.844] <TB1> INFO: 655360 events read in total (16346ms).
[11:16:56.867] <TB1> INFO: Expecting 655360 events.
[11:17:13.461] <TB1> INFO: 655360 events read in total (16192ms).
[11:17:13.482] <TB1> INFO: Expecting 655360 events.
[11:17:30.416] <TB1> INFO: 655360 events read in total (16532ms).
[11:17:30.439] <TB1> INFO: Expecting 655360 events.
[11:17:47.103] <TB1> INFO: 655360 events read in total (16262ms).
[11:17:47.133] <TB1> INFO: Expecting 655360 events.
[11:18:03.852] <TB1> INFO: 655360 events read in total (16317ms).
[11:18:03.882] <TB1> INFO: Expecting 655360 events.
[11:18:20.640] <TB1> INFO: 655360 events read in total (16356ms).
[11:18:20.678] <TB1> INFO: Expecting 655360 events.
[11:18:37.344] <TB1> INFO: 655360 events read in total (16264ms).
[11:18:37.385] <TB1> INFO: Expecting 655360 events.
[11:18:54.191] <TB1> INFO: 655360 events read in total (16404ms).
[11:18:54.234] <TB1> INFO: Expecting 655360 events.
[11:19:11.006] <TB1> INFO: 655360 events read in total (16371ms).
[11:19:11.048] <TB1> INFO: Expecting 655360 events.
[11:19:27.683] <TB1> INFO: 655360 events read in total (16233ms).
[11:19:27.731] <TB1> INFO: Expecting 655360 events.
[11:19:44.234] <TB1> INFO: 655360 events read in total (16101ms).
[11:19:44.285] <TB1> INFO: Expecting 655360 events.
[11:20:01.067] <TB1> INFO: 655360 events read in total (16381ms).
[11:20:01.125] <TB1> INFO: Expecting 655360 events.
[11:20:17.799] <TB1> INFO: 655360 events read in total (16272ms).
[11:20:17.870] <TB1> INFO: Expecting 655360 events.
[11:20:34.587] <TB1> INFO: 655360 events read in total (16315ms).
[11:20:34.647] <TB1> INFO: Test took 268411ms.
[11:20:34.726] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:20:34.956] <TB1> INFO: Expecting 655360 events.
[11:20:51.904] <TB1> INFO: 655360 events read in total (16391ms).
[11:20:51.913] <TB1> INFO: Expecting 655360 events.
[11:21:08.634] <TB1> INFO: 655360 events read in total (16319ms).
[11:21:08.648] <TB1> INFO: Expecting 655360 events.
[11:21:25.262] <TB1> INFO: 655360 events read in total (16212ms).
[11:21:25.278] <TB1> INFO: Expecting 655360 events.
[11:21:41.692] <TB1> INFO: 655360 events read in total (16012ms).
[11:21:41.711] <TB1> INFO: Expecting 655360 events.
[11:21:58.435] <TB1> INFO: 655360 events read in total (16322ms).
[11:21:58.460] <TB1> INFO: Expecting 655360 events.
[11:22:14.868] <TB1> INFO: 655360 events read in total (16006ms).
[11:22:14.893] <TB1> INFO: Expecting 655360 events.
[11:22:31.516] <TB1> INFO: 655360 events read in total (16221ms).
[11:22:31.545] <TB1> INFO: Expecting 655360 events.
[11:22:48.566] <TB1> INFO: 655360 events read in total (16619ms).
[11:22:48.602] <TB1> INFO: Expecting 655360 events.
[11:23:05.355] <TB1> INFO: 655360 events read in total (16351ms).
[11:23:05.393] <TB1> INFO: Expecting 655360 events.
[11:23:22.305] <TB1> INFO: 655360 events read in total (16510ms).
[11:23:22.345] <TB1> INFO: Expecting 655360 events.
[11:23:38.999] <TB1> INFO: 655360 events read in total (16252ms).
[11:23:39.045] <TB1> INFO: Expecting 655360 events.
[11:23:55.720] <TB1> INFO: 655360 events read in total (16273ms).
[11:23:55.769] <TB1> INFO: Expecting 655360 events.
[11:24:12.347] <TB1> INFO: 655360 events read in total (16176ms).
[11:24:12.396] <TB1> INFO: Expecting 655360 events.
[11:24:28.814] <TB1> INFO: 655360 events read in total (16016ms).
[11:24:28.865] <TB1> INFO: Expecting 655360 events.
[11:24:45.339] <TB1> INFO: 655360 events read in total (16072ms).
[11:24:45.395] <TB1> INFO: Expecting 655360 events.
[11:25:02.156] <TB1> INFO: 655360 events read in total (16359ms).
[11:25:02.223] <TB1> INFO: Test took 267497ms.
[11:25:02.422] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:25:02.429] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:25:02.441] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:25:02.452] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:25:02.464] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:25:02.475] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:25:02.486] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:25:02.498] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[11:25:02.505] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:25:02.512] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:25:02.519] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:25:02.526] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[11:25:02.532] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:25:02.539] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:25:02.546] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:25:02.553] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[11:25:02.560] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[11:25:02.566] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:25:02.573] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[11:25:02.580] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:25:02.587] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:25:02.621] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C0.dat
[11:25:02.621] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C1.dat
[11:25:02.621] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C2.dat
[11:25:02.621] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C3.dat
[11:25:02.621] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C4.dat
[11:25:02.621] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C5.dat
[11:25:02.621] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C6.dat
[11:25:02.622] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C7.dat
[11:25:02.622] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C8.dat
[11:25:02.622] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C9.dat
[11:25:02.622] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C10.dat
[11:25:02.622] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C11.dat
[11:25:02.622] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C12.dat
[11:25:02.622] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C13.dat
[11:25:02.622] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C14.dat
[11:25:02.622] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//dacParameters35_C15.dat
[11:25:02.888] <TB1> INFO: Expecting 41600 events.
[11:25:06.371] <TB1> INFO: 41600 events read in total (2926ms).
[11:25:06.371] <TB1> INFO: Test took 3746ms.
[11:25:06.818] <TB1> INFO: Expecting 41600 events.
[11:25:10.324] <TB1> INFO: 41600 events read in total (2949ms).
[11:25:10.325] <TB1> INFO: Test took 3776ms.
[11:25:10.772] <TB1> INFO: Expecting 41600 events.
[11:25:14.242] <TB1> INFO: 41600 events read in total (2913ms).
[11:25:14.242] <TB1> INFO: Test took 3741ms.
[11:25:14.424] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:14.512] <TB1> INFO: Expecting 2560 events.
[11:25:15.370] <TB1> INFO: 2560 events read in total (302ms).
[11:25:15.370] <TB1> INFO: Test took 946ms.
[11:25:15.372] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:15.676] <TB1> INFO: Expecting 2560 events.
[11:25:16.529] <TB1> INFO: 2560 events read in total (297ms).
[11:25:16.529] <TB1> INFO: Test took 1157ms.
[11:25:16.532] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:16.835] <TB1> INFO: Expecting 2560 events.
[11:25:17.690] <TB1> INFO: 2560 events read in total (298ms).
[11:25:17.690] <TB1> INFO: Test took 1158ms.
[11:25:17.693] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:17.995] <TB1> INFO: Expecting 2560 events.
[11:25:18.850] <TB1> INFO: 2560 events read in total (296ms).
[11:25:18.850] <TB1> INFO: Test took 1157ms.
[11:25:18.852] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:19.156] <TB1> INFO: Expecting 2560 events.
[11:25:20.009] <TB1> INFO: 2560 events read in total (297ms).
[11:25:20.010] <TB1> INFO: Test took 1158ms.
[11:25:20.011] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:20.315] <TB1> INFO: Expecting 2560 events.
[11:25:21.172] <TB1> INFO: 2560 events read in total (301ms).
[11:25:21.172] <TB1> INFO: Test took 1161ms.
[11:25:21.173] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:21.478] <TB1> INFO: Expecting 2560 events.
[11:25:22.331] <TB1> INFO: 2560 events read in total (297ms).
[11:25:22.331] <TB1> INFO: Test took 1158ms.
[11:25:22.333] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:22.637] <TB1> INFO: Expecting 2560 events.
[11:25:23.496] <TB1> INFO: 2560 events read in total (303ms).
[11:25:23.496] <TB1> INFO: Test took 1164ms.
[11:25:23.499] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:23.802] <TB1> INFO: Expecting 2560 events.
[11:25:24.656] <TB1> INFO: 2560 events read in total (298ms).
[11:25:24.656] <TB1> INFO: Test took 1157ms.
[11:25:24.660] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:24.962] <TB1> INFO: Expecting 2560 events.
[11:25:25.818] <TB1> INFO: 2560 events read in total (300ms).
[11:25:25.818] <TB1> INFO: Test took 1158ms.
[11:25:25.821] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:26.124] <TB1> INFO: Expecting 2560 events.
[11:25:26.979] <TB1> INFO: 2560 events read in total (298ms).
[11:25:26.980] <TB1> INFO: Test took 1159ms.
[11:25:26.982] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:27.286] <TB1> INFO: Expecting 2560 events.
[11:25:28.145] <TB1> INFO: 2560 events read in total (303ms).
[11:25:28.145] <TB1> INFO: Test took 1163ms.
[11:25:28.148] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:28.451] <TB1> INFO: Expecting 2560 events.
[11:25:29.310] <TB1> INFO: 2560 events read in total (303ms).
[11:25:29.310] <TB1> INFO: Test took 1162ms.
[11:25:29.315] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:29.616] <TB1> INFO: Expecting 2560 events.
[11:25:30.475] <TB1> INFO: 2560 events read in total (303ms).
[11:25:30.475] <TB1> INFO: Test took 1161ms.
[11:25:30.477] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:30.781] <TB1> INFO: Expecting 2560 events.
[11:25:31.639] <TB1> INFO: 2560 events read in total (302ms).
[11:25:31.639] <TB1> INFO: Test took 1162ms.
[11:25:31.642] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:31.945] <TB1> INFO: Expecting 2560 events.
[11:25:32.805] <TB1> INFO: 2560 events read in total (303ms).
[11:25:32.805] <TB1> INFO: Test took 1163ms.
[11:25:32.808] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:33.111] <TB1> INFO: Expecting 2560 events.
[11:25:33.970] <TB1> INFO: 2560 events read in total (302ms).
[11:25:33.970] <TB1> INFO: Test took 1162ms.
[11:25:33.975] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:34.275] <TB1> INFO: Expecting 2560 events.
[11:25:35.131] <TB1> INFO: 2560 events read in total (299ms).
[11:25:35.131] <TB1> INFO: Test took 1156ms.
[11:25:35.134] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:35.438] <TB1> INFO: Expecting 2560 events.
[11:25:36.291] <TB1> INFO: 2560 events read in total (297ms).
[11:25:36.291] <TB1> INFO: Test took 1157ms.
[11:25:36.293] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:36.597] <TB1> INFO: Expecting 2560 events.
[11:25:37.456] <TB1> INFO: 2560 events read in total (302ms).
[11:25:37.456] <TB1> INFO: Test took 1163ms.
[11:25:37.459] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:37.762] <TB1> INFO: Expecting 2560 events.
[11:25:38.616] <TB1> INFO: 2560 events read in total (297ms).
[11:25:38.616] <TB1> INFO: Test took 1157ms.
[11:25:38.619] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:38.923] <TB1> INFO: Expecting 2560 events.
[11:25:39.782] <TB1> INFO: 2560 events read in total (303ms).
[11:25:39.782] <TB1> INFO: Test took 1163ms.
[11:25:39.787] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:40.087] <TB1> INFO: Expecting 2560 events.
[11:25:40.945] <TB1> INFO: 2560 events read in total (302ms).
[11:25:40.945] <TB1> INFO: Test took 1158ms.
[11:25:40.948] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:41.251] <TB1> INFO: Expecting 2560 events.
[11:25:42.106] <TB1> INFO: 2560 events read in total (298ms).
[11:25:42.106] <TB1> INFO: Test took 1158ms.
[11:25:42.109] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:42.412] <TB1> INFO: Expecting 2560 events.
[11:25:43.266] <TB1> INFO: 2560 events read in total (297ms).
[11:25:43.266] <TB1> INFO: Test took 1158ms.
[11:25:43.268] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:43.572] <TB1> INFO: Expecting 2560 events.
[11:25:44.425] <TB1> INFO: 2560 events read in total (297ms).
[11:25:44.425] <TB1> INFO: Test took 1157ms.
[11:25:44.427] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:44.730] <TB1> INFO: Expecting 2560 events.
[11:25:45.584] <TB1> INFO: 2560 events read in total (297ms).
[11:25:45.584] <TB1> INFO: Test took 1157ms.
[11:25:45.586] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:45.890] <TB1> INFO: Expecting 2560 events.
[11:25:46.749] <TB1> INFO: 2560 events read in total (303ms).
[11:25:46.749] <TB1> INFO: Test took 1163ms.
[11:25:46.751] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:47.056] <TB1> INFO: Expecting 2560 events.
[11:25:47.910] <TB1> INFO: 2560 events read in total (298ms).
[11:25:47.910] <TB1> INFO: Test took 1159ms.
[11:25:47.912] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:48.215] <TB1> INFO: Expecting 2560 events.
[11:25:49.074] <TB1> INFO: 2560 events read in total (302ms).
[11:25:49.074] <TB1> INFO: Test took 1162ms.
[11:25:49.077] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:49.380] <TB1> INFO: Expecting 2560 events.
[11:25:50.233] <TB1> INFO: 2560 events read in total (297ms).
[11:25:50.233] <TB1> INFO: Test took 1156ms.
[11:25:50.235] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:50.540] <TB1> INFO: Expecting 2560 events.
[11:25:51.395] <TB1> INFO: 2560 events read in total (297ms).
[11:25:51.396] <TB1> INFO: Test took 1161ms.
[11:25:51.876] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 616 seconds
[11:25:51.876] <TB1> INFO: PH scale (per ROC): 81 85 80 81 93 91 90 98 88 87 76 85 80 82 87 83
[11:25:51.877] <TB1> INFO: PH offset (per ROC): 145 134 174 156 146 144 144 156 147 144 147 149 158 160 149 154
[11:25:51.881] <TB1> INFO: Decoding statistics:
[11:25:51.881] <TB1> INFO: General information:
[11:25:51.881] <TB1> INFO: 16bit words read: 91924
[11:25:51.881] <TB1> INFO: valid events total: 10240
[11:25:51.881] <TB1> INFO: empty events: 7739
[11:25:51.881] <TB1> INFO: valid events with pixels: 2501
[11:25:51.881] <TB1> INFO: valid pixel hits: 2501
[11:25:51.881] <TB1> INFO: Event errors: 0
[11:25:51.881] <TB1> INFO: start marker: 0
[11:25:51.881] <TB1> INFO: stop marker: 0
[11:25:51.881] <TB1> INFO: overflow: 0
[11:25:51.881] <TB1> INFO: invalid 5bit words: 0
[11:25:51.881] <TB1> INFO: invalid XOR eye diagram: 0
[11:25:51.881] <TB1> INFO: frame (failed synchr.): 0
[11:25:51.881] <TB1> INFO: idle data (no TBM trl): 0
[11:25:51.881] <TB1> INFO: no data (only TBM hdr): 0
[11:25:51.881] <TB1> INFO: TBM errors: 0
[11:25:51.881] <TB1> INFO: flawed TBM headers: 0
[11:25:51.881] <TB1> INFO: flawed TBM trailers: 0
[11:25:51.881] <TB1> INFO: event ID mismatches: 0
[11:25:51.881] <TB1> INFO: ROC errors: 0
[11:25:51.881] <TB1> INFO: missing ROC header(s): 0
[11:25:51.881] <TB1> INFO: misplaced readback start: 0
[11:25:51.881] <TB1> INFO: Pixel decoding errors: 0
[11:25:51.881] <TB1> INFO: pixel data incomplete: 0
[11:25:51.881] <TB1> INFO: pixel address: 0
[11:25:51.881] <TB1> INFO: pulse height fill bit: 0
[11:25:51.881] <TB1> INFO: buffer corruption: 0
[11:25:52.067] <TB1> INFO: ######################################################################
[11:25:52.067] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:25:52.067] <TB1> INFO: ######################################################################
[11:25:52.077] <TB1> INFO: scanning low vcal = 10
[11:25:52.342] <TB1> INFO: Expecting 41600 events.
[11:25:55.889] <TB1> INFO: 41600 events read in total (2991ms).
[11:25:55.890] <TB1> INFO: Test took 3813ms.
[11:25:55.891] <TB1> INFO: scanning low vcal = 20
[11:25:56.195] <TB1> INFO: Expecting 41600 events.
[11:25:59.750] <TB1> INFO: 41600 events read in total (2999ms).
[11:25:59.750] <TB1> INFO: Test took 3859ms.
[11:25:59.754] <TB1> INFO: scanning low vcal = 30
[11:26:00.055] <TB1> INFO: Expecting 41600 events.
[11:26:03.608] <TB1> INFO: 41600 events read in total (2997ms).
[11:26:03.609] <TB1> INFO: Test took 3855ms.
[11:26:03.611] <TB1> INFO: scanning low vcal = 40
[11:26:03.911] <TB1> INFO: Expecting 41600 events.
[11:26:08.052] <TB1> INFO: 41600 events read in total (3584ms).
[11:26:08.052] <TB1> INFO: Test took 4441ms.
[11:26:08.055] <TB1> INFO: scanning low vcal = 50
[11:26:08.324] <TB1> INFO: Expecting 41600 events.
[11:26:12.530] <TB1> INFO: 41600 events read in total (3649ms).
[11:26:12.531] <TB1> INFO: Test took 4475ms.
[11:26:12.533] <TB1> INFO: scanning low vcal = 60
[11:26:12.799] <TB1> INFO: Expecting 41600 events.
[11:26:17.044] <TB1> INFO: 41600 events read in total (3689ms).
[11:26:17.045] <TB1> INFO: Test took 4512ms.
[11:26:17.047] <TB1> INFO: scanning low vcal = 70
[11:26:17.327] <TB1> INFO: Expecting 41600 events.
[11:26:21.572] <TB1> INFO: 41600 events read in total (3688ms).
[11:26:21.573] <TB1> INFO: Test took 4526ms.
[11:26:21.575] <TB1> INFO: scanning low vcal = 80
[11:26:21.864] <TB1> INFO: Expecting 41600 events.
[11:26:26.095] <TB1> INFO: 41600 events read in total (3675ms).
[11:26:26.096] <TB1> INFO: Test took 4521ms.
[11:26:26.099] <TB1> INFO: scanning low vcal = 90
[11:26:26.384] <TB1> INFO: Expecting 41600 events.
[11:26:30.567] <TB1> INFO: 41600 events read in total (3626ms).
[11:26:30.567] <TB1> INFO: Test took 4468ms.
[11:26:30.570] <TB1> INFO: scanning low vcal = 100
[11:26:30.845] <TB1> INFO: Expecting 41600 events.
[11:26:35.025] <TB1> INFO: 41600 events read in total (3624ms).
[11:26:35.026] <TB1> INFO: Test took 4455ms.
[11:26:35.029] <TB1> INFO: scanning low vcal = 110
[11:26:35.295] <TB1> INFO: Expecting 41600 events.
[11:26:39.491] <TB1> INFO: 41600 events read in total (3640ms).
[11:26:39.492] <TB1> INFO: Test took 4463ms.
[11:26:39.494] <TB1> INFO: scanning low vcal = 120
[11:26:39.763] <TB1> INFO: Expecting 41600 events.
[11:26:43.961] <TB1> INFO: 41600 events read in total (3641ms).
[11:26:43.961] <TB1> INFO: Test took 4467ms.
[11:26:43.975] <TB1> INFO: scanning low vcal = 130
[11:26:44.242] <TB1> INFO: Expecting 41600 events.
[11:26:48.447] <TB1> INFO: 41600 events read in total (3649ms).
[11:26:48.448] <TB1> INFO: Test took 4473ms.
[11:26:48.451] <TB1> INFO: scanning low vcal = 140
[11:26:48.713] <TB1> INFO: Expecting 41600 events.
[11:26:52.915] <TB1> INFO: 41600 events read in total (3646ms).
[11:26:52.915] <TB1> INFO: Test took 4464ms.
[11:26:52.918] <TB1> INFO: scanning low vcal = 150
[11:26:53.181] <TB1> INFO: Expecting 41600 events.
[11:26:57.411] <TB1> INFO: 41600 events read in total (3674ms).
[11:26:57.411] <TB1> INFO: Test took 4493ms.
[11:26:57.414] <TB1> INFO: scanning low vcal = 160
[11:26:57.679] <TB1> INFO: Expecting 41600 events.
[11:27:01.885] <TB1> INFO: 41600 events read in total (3650ms).
[11:27:01.885] <TB1> INFO: Test took 4471ms.
[11:27:01.888] <TB1> INFO: scanning low vcal = 170
[11:27:02.175] <TB1> INFO: Expecting 41600 events.
[11:27:06.403] <TB1> INFO: 41600 events read in total (3672ms).
[11:27:06.404] <TB1> INFO: Test took 4516ms.
[11:27:06.408] <TB1> INFO: scanning low vcal = 180
[11:27:06.711] <TB1> INFO: Expecting 41600 events.
[11:27:10.905] <TB1> INFO: 41600 events read in total (3637ms).
[11:27:10.906] <TB1> INFO: Test took 4497ms.
[11:27:10.909] <TB1> INFO: scanning low vcal = 190
[11:27:11.192] <TB1> INFO: Expecting 41600 events.
[11:27:15.365] <TB1> INFO: 41600 events read in total (3617ms).
[11:27:15.365] <TB1> INFO: Test took 4456ms.
[11:27:15.368] <TB1> INFO: scanning low vcal = 200
[11:27:15.630] <TB1> INFO: Expecting 41600 events.
[11:27:19.793] <TB1> INFO: 41600 events read in total (3607ms).
[11:27:19.793] <TB1> INFO: Test took 4425ms.
[11:27:19.796] <TB1> INFO: scanning low vcal = 210
[11:27:20.068] <TB1> INFO: Expecting 41600 events.
[11:27:24.320] <TB1> INFO: 41600 events read in total (3696ms).
[11:27:24.321] <TB1> INFO: Test took 4525ms.
[11:27:24.324] <TB1> INFO: scanning low vcal = 220
[11:27:24.605] <TB1> INFO: Expecting 41600 events.
[11:27:28.819] <TB1> INFO: 41600 events read in total (3658ms).
[11:27:28.820] <TB1> INFO: Test took 4496ms.
[11:27:28.823] <TB1> INFO: scanning low vcal = 230
[11:27:29.116] <TB1> INFO: Expecting 41600 events.
[11:27:33.286] <TB1> INFO: 41600 events read in total (3614ms).
[11:27:33.286] <TB1> INFO: Test took 4463ms.
[11:27:33.289] <TB1> INFO: scanning low vcal = 240
[11:27:33.550] <TB1> INFO: Expecting 41600 events.
[11:27:37.717] <TB1> INFO: 41600 events read in total (3611ms).
[11:27:37.717] <TB1> INFO: Test took 4428ms.
[11:27:37.721] <TB1> INFO: scanning low vcal = 250
[11:27:37.984] <TB1> INFO: Expecting 41600 events.
[11:27:42.181] <TB1> INFO: 41600 events read in total (3641ms).
[11:27:42.181] <TB1> INFO: Test took 4460ms.
[11:27:42.185] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[11:27:42.445] <TB1> INFO: Expecting 41600 events.
[11:27:46.638] <TB1> INFO: 41600 events read in total (3637ms).
[11:27:46.639] <TB1> INFO: Test took 4454ms.
[11:27:46.642] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[11:27:46.901] <TB1> INFO: Expecting 41600 events.
[11:27:51.096] <TB1> INFO: 41600 events read in total (3639ms).
[11:27:51.096] <TB1> INFO: Test took 4455ms.
[11:27:51.100] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[11:27:51.362] <TB1> INFO: Expecting 41600 events.
[11:27:55.551] <TB1> INFO: 41600 events read in total (3633ms).
[11:27:55.551] <TB1> INFO: Test took 4451ms.
[11:27:55.556] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[11:27:55.816] <TB1> INFO: Expecting 41600 events.
[11:28:00.059] <TB1> INFO: 41600 events read in total (3687ms).
[11:28:00.059] <TB1> INFO: Test took 4503ms.
[11:28:00.062] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:28:00.325] <TB1> INFO: Expecting 41600 events.
[11:28:04.523] <TB1> INFO: 41600 events read in total (3642ms).
[11:28:04.523] <TB1> INFO: Test took 4461ms.
[11:28:05.066] <TB1> INFO: PixTestGainPedestal::measure() done
[11:28:36.974] <TB1> INFO: PixTestGainPedestal::fit() done
[11:28:36.974] <TB1> INFO: non-linearity mean: 0.958 0.958 0.956 0.956 0.950 0.958 0.953 0.956 0.949 0.957 0.952 0.955 0.964 0.961 0.951 0.952
[11:28:36.974] <TB1> INFO: non-linearity RMS: 0.006 0.004 0.006 0.005 0.006 0.005 0.007 0.006 0.006 0.005 0.007 0.005 0.005 0.006 0.005 0.007
[11:28:36.974] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[11:28:36.993] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[11:28:37.013] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[11:28:37.032] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[11:28:37.052] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[11:28:37.072] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[11:28:37.092] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[11:28:37.111] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[11:28:37.131] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[11:28:37.151] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[11:28:37.172] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[11:28:37.191] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[11:28:37.211] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[11:28:37.231] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[11:28:37.251] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[11:28:37.270] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[11:28:37.290] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 165 seconds
[11:28:37.290] <TB1> INFO: Decoding statistics:
[11:28:37.290] <TB1> INFO: General information:
[11:28:37.290] <TB1> INFO: 16bit words read: 2662400
[11:28:37.290] <TB1> INFO: valid events total: 166400
[11:28:37.290] <TB1> INFO: empty events: 0
[11:28:37.290] <TB1> INFO: valid events with pixels: 166400
[11:28:37.290] <TB1> INFO: valid pixel hits: 665600
[11:28:37.290] <TB1> INFO: Event errors: 0
[11:28:37.290] <TB1> INFO: start marker: 0
[11:28:37.290] <TB1> INFO: stop marker: 0
[11:28:37.290] <TB1> INFO: overflow: 0
[11:28:37.290] <TB1> INFO: invalid 5bit words: 0
[11:28:37.290] <TB1> INFO: invalid XOR eye diagram: 0
[11:28:37.290] <TB1> INFO: frame (failed synchr.): 0
[11:28:37.290] <TB1> INFO: idle data (no TBM trl): 0
[11:28:37.290] <TB1> INFO: no data (only TBM hdr): 0
[11:28:37.290] <TB1> INFO: TBM errors: 0
[11:28:37.290] <TB1> INFO: flawed TBM headers: 0
[11:28:37.290] <TB1> INFO: flawed TBM trailers: 0
[11:28:37.290] <TB1> INFO: event ID mismatches: 0
[11:28:37.290] <TB1> INFO: ROC errors: 0
[11:28:37.290] <TB1> INFO: missing ROC header(s): 0
[11:28:37.290] <TB1> INFO: misplaced readback start: 0
[11:28:37.290] <TB1> INFO: Pixel decoding errors: 0
[11:28:37.290] <TB1> INFO: pixel data incomplete: 0
[11:28:37.290] <TB1> INFO: pixel address: 0
[11:28:37.290] <TB1> INFO: pulse height fill bit: 0
[11:28:37.290] <TB1> INFO: buffer corruption: 0
[11:28:37.298] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C15.dat
[11:28:37.299] <TB1> INFO: ######################################################################
[11:28:37.299] <TB1> INFO: PixTestReadback::doTest()
[11:28:37.299] <TB1> INFO: ######################################################################
[11:28:37.299] <TB1> INFO: ----------------------------------------------------------------------
[11:28:37.299] <TB1> INFO: PixTestReadback::CalibrateVd()
[11:28:37.299] <TB1> INFO: ----------------------------------------------------------------------
[11:28:46.782] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C0.dat
[11:28:46.782] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C1.dat
[11:28:46.782] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C2.dat
[11:28:46.782] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C3.dat
[11:28:46.782] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C4.dat
[11:28:46.782] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C5.dat
[11:28:46.782] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C6.dat
[11:28:46.782] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C7.dat
[11:28:46.782] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C8.dat
[11:28:46.782] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C9.dat
[11:28:46.783] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C10.dat
[11:28:46.783] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C11.dat
[11:28:46.783] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C12.dat
[11:28:46.783] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C13.dat
[11:28:46.783] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C14.dat
[11:28:46.783] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C15.dat
[11:28:46.809] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:28:46.809] <TB1> INFO: ----------------------------------------------------------------------
[11:28:46.809] <TB1> INFO: PixTestReadback::CalibrateVa()
[11:28:46.809] <TB1> INFO: ----------------------------------------------------------------------
[11:28:56.295] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C0.dat
[11:28:56.295] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C1.dat
[11:28:56.295] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C2.dat
[11:28:56.295] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C3.dat
[11:28:56.295] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C4.dat
[11:28:56.295] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C5.dat
[11:28:56.295] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C6.dat
[11:28:56.296] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C7.dat
[11:28:56.296] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C8.dat
[11:28:56.296] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C9.dat
[11:28:56.296] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C10.dat
[11:28:56.296] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C11.dat
[11:28:56.296] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C12.dat
[11:28:56.296] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C13.dat
[11:28:56.296] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C14.dat
[11:28:56.296] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C15.dat
[11:28:56.317] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:28:56.317] <TB1> INFO: ----------------------------------------------------------------------
[11:28:56.317] <TB1> INFO: PixTestReadback::readbackVbg()
[11:28:56.317] <TB1> INFO: ----------------------------------------------------------------------
[11:29:03.618] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:29:03.618] <TB1> INFO: ----------------------------------------------------------------------
[11:29:03.618] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[11:29:03.618] <TB1> INFO: ----------------------------------------------------------------------
[11:29:03.618] <TB1> INFO: Vbg will be calibrated using Vd calibration
[11:29:03.618] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 145.6calibrated Vbg = 1.18249 :::*/*/*/*/
[11:29:03.618] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 143calibrated Vbg = 1.17871 :::*/*/*/*/
[11:29:03.618] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 158.8calibrated Vbg = 1.18992 :::*/*/*/*/
[11:29:03.619] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 154calibrated Vbg = 1.19134 :::*/*/*/*/
[11:29:03.619] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 141.5calibrated Vbg = 1.19651 :::*/*/*/*/
[11:29:03.619] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 144.6calibrated Vbg = 1.20692 :::*/*/*/*/
[11:29:03.619] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 149.4calibrated Vbg = 1.19642 :::*/*/*/*/
[11:29:03.619] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 156.8calibrated Vbg = 1.20186 :::*/*/*/*/
[11:29:03.619] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 152.1calibrated Vbg = 1.21048 :::*/*/*/*/
[11:29:03.619] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 157.8calibrated Vbg = 1.20411 :::*/*/*/*/
[11:29:03.619] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 152.4calibrated Vbg = 1.20475 :::*/*/*/*/
[11:29:03.619] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 149.9calibrated Vbg = 1.19423 :::*/*/*/*/
[11:29:03.619] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 153calibrated Vbg = 1.18824 :::*/*/*/*/
[11:29:03.619] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 152.9calibrated Vbg = 1.188 :::*/*/*/*/
[11:29:03.619] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 146.8calibrated Vbg = 1.19067 :::*/*/*/*/
[11:29:03.619] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 149.9calibrated Vbg = 1.1846 :::*/*/*/*/
[11:29:03.621] <TB1> INFO: ----------------------------------------------------------------------
[11:29:03.621] <TB1> INFO: PixTestReadback::CalibrateIa()
[11:29:03.621] <TB1> INFO: ----------------------------------------------------------------------
[11:31:39.026] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C0.dat
[11:31:39.027] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C1.dat
[11:31:39.027] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C2.dat
[11:31:39.027] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C3.dat
[11:31:39.027] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C4.dat
[11:31:39.027] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C5.dat
[11:31:39.027] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C6.dat
[11:31:39.027] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C7.dat
[11:31:39.027] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C8.dat
[11:31:39.029] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C9.dat
[11:31:39.029] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C10.dat
[11:31:39.029] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C11.dat
[11:31:39.029] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C12.dat
[11:31:39.029] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C13.dat
[11:31:39.029] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C14.dat
[11:31:39.029] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2300_FullQualification_2016-08-29_11h59m_1472464790//000_FulltestPxar_m20//readbackCal_C15.dat
[11:31:39.056] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:31:39.057] <TB1> INFO: PixTestReadback::doTest() done
[11:31:39.057] <TB1> INFO: Decoding statistics:
[11:31:39.057] <TB1> INFO: General information:
[11:31:39.057] <TB1> INFO: 16bit words read: 1024
[11:31:39.057] <TB1> INFO: valid events total: 128
[11:31:39.057] <TB1> INFO: empty events: 128
[11:31:39.057] <TB1> INFO: valid events with pixels: 0
[11:31:39.057] <TB1> INFO: valid pixel hits: 0
[11:31:39.057] <TB1> INFO: Event errors: 0
[11:31:39.057] <TB1> INFO: start marker: 0
[11:31:39.057] <TB1> INFO: stop marker: 0
[11:31:39.057] <TB1> INFO: overflow: 0
[11:31:39.057] <TB1> INFO: invalid 5bit words: 0
[11:31:39.057] <TB1> INFO: invalid XOR eye diagram: 0
[11:31:39.057] <TB1> INFO: frame (failed synchr.): 0
[11:31:39.057] <TB1> INFO: idle data (no TBM trl): 0
[11:31:39.057] <TB1> INFO: no data (only TBM hdr): 0
[11:31:39.057] <TB1> INFO: TBM errors: 0
[11:31:39.057] <TB1> INFO: flawed TBM headers: 0
[11:31:39.057] <TB1> INFO: flawed TBM trailers: 0
[11:31:39.057] <TB1> INFO: event ID mismatches: 0
[11:31:39.057] <TB1> INFO: ROC errors: 0
[11:31:39.057] <TB1> INFO: missing ROC header(s): 0
[11:31:39.057] <TB1> INFO: misplaced readback start: 0
[11:31:39.057] <TB1> INFO: Pixel decoding errors: 0
[11:31:39.057] <TB1> INFO: pixel data incomplete: 0
[11:31:39.057] <TB1> INFO: pixel address: 0
[11:31:39.057] <TB1> INFO: pulse height fill bit: 0
[11:31:39.057] <TB1> INFO: buffer corruption: 0
[11:31:39.080] <TB1> INFO: Decoding statistics:
[11:31:39.080] <TB1> INFO: General information:
[11:31:39.080] <TB1> INFO: 16bit words read: 2755348
[11:31:39.080] <TB1> INFO: valid events total: 176768
[11:31:39.080] <TB1> INFO: empty events: 7867
[11:31:39.080] <TB1> INFO: valid events with pixels: 168901
[11:31:39.080] <TB1> INFO: valid pixel hits: 668101
[11:31:39.080] <TB1> INFO: Event errors: 0
[11:31:39.080] <TB1> INFO: start marker: 0
[11:31:39.080] <TB1> INFO: stop marker: 0
[11:31:39.080] <TB1> INFO: overflow: 0
[11:31:39.080] <TB1> INFO: invalid 5bit words: 0
[11:31:39.080] <TB1> INFO: invalid XOR eye diagram: 0
[11:31:39.080] <TB1> INFO: frame (failed synchr.): 0
[11:31:39.080] <TB1> INFO: idle data (no TBM trl): 0
[11:31:39.080] <TB1> INFO: no data (only TBM hdr): 0
[11:31:39.080] <TB1> INFO: TBM errors: 0
[11:31:39.080] <TB1> INFO: flawed TBM headers: 0
[11:31:39.080] <TB1> INFO: flawed TBM trailers: 0
[11:31:39.080] <TB1> INFO: event ID mismatches: 0
[11:31:39.080] <TB1> INFO: ROC errors: 0
[11:31:39.080] <TB1> INFO: missing ROC header(s): 0
[11:31:39.080] <TB1> INFO: misplaced readback start: 0
[11:31:39.080] <TB1> INFO: Pixel decoding errors: 0
[11:31:39.080] <TB1> INFO: pixel data incomplete: 0
[11:31:39.080] <TB1> INFO: pixel address: 0
[11:31:39.080] <TB1> INFO: pulse height fill bit: 0
[11:31:39.080] <TB1> INFO: buffer corruption: 0
[11:31:39.080] <TB1> INFO: enter test to run
[11:31:39.080] <TB1> INFO: test: exit no parameter change
[11:31:39.240] <TB1> QUIET: Connection to board 153 closed.
[11:31:39.319] <TB1> INFO: pXar: this is the end, my friend