[17:04:45.592] <TB1> INFO: *** Welcome to pxar ***
[17:04:45.592] <TB1> INFO: *** Today: 2016/02/08
[17:04:45.680] <TB1> INFO: *** Version: 9751-dirty
[17:04:45.680] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C15.dat
[17:04:45.681] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//tbmParameters_C0b.dat
[17:04:45.681] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//defaultMaskFile.dat
[17:04:45.681] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters_C15.dat
[17:04:45.760] <TB1> INFO: clk: 4
[17:04:45.760] <TB1> INFO: ctr: 4
[17:04:45.760] <TB1> INFO: sda: 19
[17:04:45.760] <TB1> INFO: tin: 9
[17:04:45.760] <TB1> INFO: level: 15
[17:04:45.760] <TB1> INFO: triggerdelay: 0
[17:04:45.760] <TB1> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[17:04:45.760] <TB1> INFO: Log level: INFO
[17:04:45.767] <TB1> INFO: Found DTB DTB_WXBYFL
[17:04:45.777] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[17:04:45.779] <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:
------------------------------------------------------
[17:04:45.781] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[17:04:47.323] <TB1> INFO: DUT info:
[17:04:47.323] <TB1> INFO: The DUT currently contains the following objects:
[17:04:47.323] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:04:47.323] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:04:47.323] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:04:47.323] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:04:47.323] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:47.324] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:47.324] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:47.324] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:47.324] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:47.324] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:47.324] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:47.324] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:47.324] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:47.324] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:47.324] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:47.324] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:47.324] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:47.324] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:47.324] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:47.324] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:47.725] <TB1> INFO: enter 'restricted' command line mode
[17:04:47.725] <TB1> INFO: enter test to run
[17:04:47.725] <TB1> INFO: test: pretest no parameter change
[17:04:47.725] <TB1> INFO: running: pretest
[17:04:47.731] <TB1> INFO: ----------------------------------------------------------------------
[17:04:47.731] <TB1> INFO: PixTestPretest::programROC()
[17:04:47.731] <TB1> INFO: ----------------------------------------------------------------------
[17:05:05.745] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[17:05:05.745] <TB1> INFO: IA differences per ROC: 18.5 20.1 20.1 18.5 20.1 18.5 20.1 18.5 19.3 17.7 19.3 19.3 20.1 18.5 18.5 18.5
[17:05:05.836] <TB1> INFO: enter test to run
[17:05:05.836] <TB1> INFO: test: pretest no parameter change
[17:05:05.836] <TB1> INFO: running: pretest
[17:05:05.837] <TB1> INFO: ----------------------------------------------------------------------
[17:05:05.837] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[17:05:05.837] <TB1> INFO: ----------------------------------------------------------------------
[17:05:12.534] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 384.3 mA = 24.0187 mA/ROC
[17:05:12.534] <TB1> INFO: i(loss) [mA/ROC]: 18.5 19.3 18.5 19.3 18.5 19.3 18.5 18.5 18.5 19.3 18.5 19.3 19.3 19.3 18.5 18.5
[17:05:12.572] <TB1> INFO: enter test to run
[17:05:12.572] <TB1> INFO: test: pretest no parameter change
[17:05:12.572] <TB1> INFO: running: pretest
[17:05:12.573] <TB1> INFO: ----------------------------------------------------------------------
[17:05:12.573] <TB1> INFO: PixTestPretest::findTiming()
[17:05:12.573] <TB1> INFO: ----------------------------------------------------------------------
[17:05:12.573] <TB1> INFO: PixTestCmd::init()

[17:06:40.835] <TB1> INFO: TBM phases: 160MHz: 0, 400MHz: 4, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[17:06:40.835] <TB1> INFO: (success/tries = 100/100), width = 4
[17:06:40.836] <TB1> INFO: enter test to run
[17:06:40.836] <TB1> INFO: test: pretest no parameter change
[17:06:40.836] <TB1> INFO: running: pretest
[17:06:40.837] <TB1> INFO: ----------------------------------------------------------------------
[17:06:40.837] <TB1> INFO: PixTestPretest::findWorkingPixel()
[17:06:40.837] <TB1> INFO: ----------------------------------------------------------------------
[17:06:40.932] <TB1> INFO: Expecting 231680 events.


[17:06:51.254] <TB1> INFO: 231680 events read in total (9765ms).
[17:06:51.257] <TB1> INFO: Test took 10413ms.
[17:06:51.496] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[17:06:51.561] <TB1> INFO: enter test to run
[17:06:51.561] <TB1> INFO: test: pretest no parameter change
[17:06:51.561] <TB1> INFO: running: pretest
[17:06:51.562] <TB1> INFO: ----------------------------------------------------------------------
[17:06:51.562] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[17:06:51.562] <TB1> INFO: ----------------------------------------------------------------------
[17:06:51.654] <TB1> INFO: Expecting 231680 events.
[17:07:01.902] <TB1> INFO: 231680 events read in total (9691ms).
[17:07:01.905] <TB1> INFO: Test took 10339ms.
[17:07:02.153] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[17:07:02.153] <TB1> INFO: CalDel: 146 136 143 140 147 142 146 145 151 144 143 147 131 137 143 142
[17:07:02.153] <TB1> INFO: VthrComp: 51 51 53 51 51 51 51 51 51 51 51 51 51 51 51 51
[17:07:02.187] <TB1> INFO: enter test to run
[17:07:02.187] <TB1> INFO: test: pretest no parameter change
[17:07:02.187] <TB1> INFO: running: pretest
[17:07:02.187] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C0.dat
[17:07:02.187] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C1.dat
[17:07:02.187] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C2.dat
[17:07:02.187] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C3.dat
[17:07:02.187] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C4.dat
[17:07:02.188] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C5.dat
[17:07:02.188] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C6.dat
[17:07:02.188] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C7.dat
[17:07:02.188] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C8.dat
[17:07:02.188] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C9.dat
[17:07:02.188] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C10.dat
[17:07:02.188] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C11.dat
[17:07:02.188] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C12.dat
[17:07:02.188] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C13.dat
[17:07:02.188] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C14.dat
[17:07:02.188] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C15.dat
[17:07:02.189] <TB1> INFO: enter test to run
[17:07:02.189] <TB1> INFO: test: fulltest no parameter change
[17:07:02.189] <TB1> INFO: running: fulltest
[17:07:02.189] <TB1> INFO: ######################################################################
[17:07:02.189] <TB1> INFO: PixTestFullTest::doTest()
[17:07:02.189] <TB1> INFO: ######################################################################
[17:07:02.190] <TB1> INFO: ######################################################################
[17:07:02.190] <TB1> INFO: PixTestAlive::doTest()
[17:07:02.190] <TB1> INFO: ######################################################################
[17:07:02.191] <TB1> INFO: ----------------------------------------------------------------------
[17:07:02.191] <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)
[17:07:02.191] <TB1> INFO: ----------------------------------------------------------------------
[17:07:02.457] <TB1> INFO: Expecting 41600 events.
[17:07:06.218] <TB1> INFO: 41600 events read in total (3205ms).
[17:07:06.219] <TB1> INFO: Test took 4027ms.
[17:07:06.442] <TB1> INFO: PixTestAlive::aliveTest() done
[17:07:06.442] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0 1
[17:07:06.443] <TB1> INFO: ----------------------------------------------------------------------
[17:07:06.443] <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)
[17:07:06.443] <TB1> INFO: ----------------------------------------------------------------------
[17:07:06.717] <TB1> INFO: Expecting 41600 events.
[17:07:09.716] <TB1> INFO: 41600 events read in total (2443ms).
[17:07:09.717] <TB1> INFO: Test took 3272ms.
[17:07:09.717] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:07:09.955] <TB1> INFO: PixTestAlive::maskTest() done
[17:07:09.955] <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
[17:07:09.957] <TB1> INFO: ----------------------------------------------------------------------
[17:07:09.957] <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)
[17:07:09.957] <TB1> INFO: ----------------------------------------------------------------------
[17:07:10.232] <TB1> INFO: Expecting 41600 events.
[17:07:14.005] <TB1> INFO: 41600 events read in total (3217ms).
[17:07:14.005] <TB1> INFO: Test took 4047ms.
[17:07:14.236] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[17:07:14.236] <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
[17:07:14.237] <TB1> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[17:07:14.237] <TB1> INFO: Decoding statistics:
[17:07:14.237] <TB1> INFO: General information:
[17:07:14.237] <TB1> INFO: 16bit words read: 0
[17:07:14.237] <TB1> INFO: valid events total: 0
[17:07:14.237] <TB1> INFO: empty events: 0
[17:07:14.237] <TB1> INFO: valid events with pixels: 0
[17:07:14.237] <TB1> INFO: valid pixel hits: 0
[17:07:14.237] <TB1> INFO: Event errors: 0
[17:07:14.237] <TB1> INFO: start marker: 0
[17:07:14.237] <TB1> INFO: stop marker: 0
[17:07:14.237] <TB1> INFO: overflow: 0
[17:07:14.237] <TB1> INFO: invalid 5bit words: 0
[17:07:14.237] <TB1> INFO: invalid XOR eye diagram: 0
[17:07:14.237] <TB1> INFO: frame (failed synchr.): 0
[17:07:14.237] <TB1> INFO: idle data (no TBM trl): 0
[17:07:14.237] <TB1> INFO: no data (only TBM hdr): 0
[17:07:14.237] <TB1> INFO: TBM errors: 0
[17:07:14.237] <TB1> INFO: flawed TBM headers: 0
[17:07:14.237] <TB1> INFO: flawed TBM trailers: 0
[17:07:14.237] <TB1> INFO: event ID mismatches: 0
[17:07:14.237] <TB1> INFO: ROC errors: 0
[17:07:14.237] <TB1> INFO: missing ROC header(s): 0
[17:07:14.237] <TB1> INFO: misplaced readback start: 0
[17:07:14.237] <TB1> INFO: Pixel decoding errors: 0
[17:07:14.237] <TB1> INFO: pixel data incomplete: 0
[17:07:14.237] <TB1> INFO: pixel address: 0
[17:07:14.237] <TB1> INFO: pulse height fill bit: 0
[17:07:14.237] <TB1> INFO: buffer corruption: 0
[17:07:14.250] <TB1> INFO: ######################################################################
[17:07:14.250] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[17:07:14.250] <TB1> INFO: ######################################################################
[17:07:14.253] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[17:07:14.266] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[17:07:14.266] <TB1> INFO: run 1 of 1
[17:07:14.537] <TB1> INFO: Expecting 3120000 events.
[17:07:56.646] <TB1> INFO: 892175 events read in total (41552ms).
[17:08:37.646] <TB1> INFO: 1775600 events read in total (82552ms).
[17:09:19.276] <TB1> INFO: 2671895 events read in total (124183ms).
[17:09:40.011] <TB1> INFO: 3120000 events read in total (144917ms).
[17:09:40.061] <TB1> INFO: Test took 145795ms.
[17:10:06.106] <TB1> INFO: PixTestBBMap::doTest() done, duration: 171 seconds
[17:10:06.106] <TB1> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 2 0 1 70 1 3 0 6
[17:10:06.106] <TB1> INFO: separation cut (per ROC): 93 97 104 100 98 103 98 95 89 87 90 95 91 85 95 95
[17:10:06.106] <TB1> INFO: Decoding statistics:
[17:10:06.106] <TB1> INFO: General information:
[17:10:06.106] <TB1> INFO: 16bit words read: 0
[17:10:06.106] <TB1> INFO: valid events total: 0
[17:10:06.106] <TB1> INFO: empty events: 0
[17:10:06.106] <TB1> INFO: valid events with pixels: 0
[17:10:06.106] <TB1> INFO: valid pixel hits: 0
[17:10:06.106] <TB1> INFO: Event errors: 0
[17:10:06.106] <TB1> INFO: start marker: 0
[17:10:06.106] <TB1> INFO: stop marker: 0
[17:10:06.106] <TB1> INFO: overflow: 0
[17:10:06.106] <TB1> INFO: invalid 5bit words: 0
[17:10:06.106] <TB1> INFO: invalid XOR eye diagram: 0
[17:10:06.106] <TB1> INFO: frame (failed synchr.): 0
[17:10:06.106] <TB1> INFO: idle data (no TBM trl): 0
[17:10:06.106] <TB1> INFO: no data (only TBM hdr): 0
[17:10:06.106] <TB1> INFO: TBM errors: 0
[17:10:06.106] <TB1> INFO: flawed TBM headers: 0
[17:10:06.106] <TB1> INFO: flawed TBM trailers: 0
[17:10:06.106] <TB1> INFO: event ID mismatches: 0
[17:10:06.106] <TB1> INFO: ROC errors: 0
[17:10:06.106] <TB1> INFO: missing ROC header(s): 0
[17:10:06.106] <TB1> INFO: misplaced readback start: 0
[17:10:06.106] <TB1> INFO: Pixel decoding errors: 0
[17:10:06.106] <TB1> INFO: pixel data incomplete: 0
[17:10:06.106] <TB1> INFO: pixel address: 0
[17:10:06.106] <TB1> INFO: pulse height fill bit: 0
[17:10:06.106] <TB1> INFO: buffer corruption: 0
[17:10:06.160] <TB1> INFO: ######################################################################
[17:10:06.160] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[17:10:06.160] <TB1> INFO: ######################################################################
[17:10:06.160] <TB1> INFO: ----------------------------------------------------------------------
[17:10:06.160] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[17:10:06.160] <TB1> INFO: ----------------------------------------------------------------------
[17:10:06.160] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[17:10:06.172] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[17:10:06.172] <TB1> INFO: run 1 of 1
[17:10:06.515] <TB1> INFO: Expecting 26208000 events.
[17:10:40.435] <TB1> INFO: 945550 events read in total (33364ms).
[17:11:13.903] <TB1> INFO: 1875500 events read in total (66832ms).
[17:11:47.330] <TB1> INFO: 2804300 events read in total (100259ms).
[17:12:20.678] <TB1> INFO: 3733700 events read in total (133607ms).
[17:12:54.607] <TB1> INFO: 4660500 events read in total (167536ms).
[17:13:28.205] <TB1> INFO: 5586700 events read in total (201134ms).
[17:14:01.886] <TB1> INFO: 6512550 events read in total (234815ms).
[17:14:35.189] <TB1> INFO: 7437550 events read in total (268118ms).
[17:15:08.508] <TB1> INFO: 8360700 events read in total (301437ms).
[17:15:42.027] <TB1> INFO: 9287850 events read in total (334956ms).
[17:16:15.486] <TB1> INFO: 10210850 events read in total (368415ms).
[17:16:49.072] <TB1> INFO: 11131850 events read in total (402001ms).
[17:17:22.944] <TB1> INFO: 12054850 events read in total (435873ms).
[17:17:56.381] <TB1> INFO: 12974100 events read in total (469310ms).
[17:18:30.351] <TB1> INFO: 13885000 events read in total (503280ms).
[17:19:04.190] <TB1> INFO: 14795600 events read in total (537119ms).
[17:19:37.951] <TB1> INFO: 15704400 events read in total (570880ms).
[17:20:10.872] <TB1> INFO: 16610600 events read in total (603801ms).
[17:20:45.085] <TB1> INFO: 17514950 events read in total (638014ms).
[17:21:19.080] <TB1> INFO: 18421500 events read in total (672009ms).
[17:21:52.650] <TB1> INFO: 19328000 events read in total (705579ms).
[17:22:26.039] <TB1> INFO: 20233700 events read in total (738968ms).
[17:22:59.068] <TB1> INFO: 21135150 events read in total (771997ms).
[17:23:32.600] <TB1> INFO: 22038250 events read in total (805529ms).
[17:24:05.971] <TB1> INFO: 22943500 events read in total (838900ms).
[17:24:39.163] <TB1> INFO: 23850550 events read in total (872092ms).
[17:25:12.445] <TB1> INFO: 24757900 events read in total (905374ms).
[17:25:46.166] <TB1> INFO: 25664800 events read in total (939095ms).
[17:26:05.770] <TB1> INFO: 26208000 events read in total (958699ms).
[17:26:05.796] <TB1> INFO: Test took 959624ms.
[17:26:06.030] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:26:07.611] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:26:09.240] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:26:10.726] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:26:12.324] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:26:13.898] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:26:15.401] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:26:16.896] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:26:18.337] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:26:19.911] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:26:21.528] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:26:22.919] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:26:24.289] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:26:25.651] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:26:27.034] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:26:28.459] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[17:26:29.909] <TB1> INFO: PixTestScurves::scurves() done
[17:26:29.909] <TB1> INFO: Vcal mean: 104.08 93.76 106.91 101.13 97.90 105.45 103.35 96.53 99.53 93.10 94.42 102.76 101.74 86.08 94.50 96.87
[17:26:29.909] <TB1> INFO: Vcal RMS: 5.62 5.66 5.27 5.74 5.49 5.38 5.72 6.00 5.58 5.08 5.07 5.59 5.15 4.97 5.44 5.77
[17:26:29.909] <TB1> INFO: PixTestScurves::fullTest() done, duration: 983 seconds
[17:26:29.909] <TB1> INFO: Decoding statistics:
[17:26:29.909] <TB1> INFO: General information:
[17:26:29.909] <TB1> INFO: 16bit words read: 0
[17:26:29.909] <TB1> INFO: valid events total: 0
[17:26:29.909] <TB1> INFO: empty events: 0
[17:26:29.909] <TB1> INFO: valid events with pixels: 0
[17:26:29.909] <TB1> INFO: valid pixel hits: 0
[17:26:29.909] <TB1> INFO: Event errors: 0
[17:26:29.909] <TB1> INFO: start marker: 0
[17:26:29.909] <TB1> INFO: stop marker: 0
[17:26:29.909] <TB1> INFO: overflow: 0
[17:26:29.909] <TB1> INFO: invalid 5bit words: 0
[17:26:29.909] <TB1> INFO: invalid XOR eye diagram: 0
[17:26:29.909] <TB1> INFO: frame (failed synchr.): 0
[17:26:29.909] <TB1> INFO: idle data (no TBM trl): 0
[17:26:29.909] <TB1> INFO: no data (only TBM hdr): 0
[17:26:29.909] <TB1> INFO: TBM errors: 0
[17:26:29.909] <TB1> INFO: flawed TBM headers: 0
[17:26:29.909] <TB1> INFO: flawed TBM trailers: 0
[17:26:29.909] <TB1> INFO: event ID mismatches: 0
[17:26:29.909] <TB1> INFO: ROC errors: 0
[17:26:29.909] <TB1> INFO: missing ROC header(s): 0
[17:26:29.909] <TB1> INFO: misplaced readback start: 0
[17:26:29.909] <TB1> INFO: Pixel decoding errors: 0
[17:26:29.910] <TB1> INFO: pixel data incomplete: 0
[17:26:29.910] <TB1> INFO: pixel address: 0
[17:26:29.910] <TB1> INFO: pulse height fill bit: 0
[17:26:29.910] <TB1> INFO: buffer corruption: 0
[17:26:29.989] <TB1> INFO: ######################################################################
[17:26:29.989] <TB1> INFO: PixTestTrim::doTest()
[17:26:29.989] <TB1> INFO: ######################################################################
[17:26:29.992] <TB1> INFO: ----------------------------------------------------------------------
[17:26:29.992] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[17:26:29.992] <TB1> INFO: ----------------------------------------------------------------------
[17:26:30.072] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[17:26:30.072] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[17:26:30.080] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[17:26:30.080] <TB1> INFO: run 1 of 1
[17:26:30.354] <TB1> INFO: Expecting 6281600 events.
[17:27:10.576] <TB1> INFO: 1106400 events read in total (39666ms).
[17:27:50.302] <TB1> INFO: 2206620 events read in total (79392ms).
[17:28:29.726] <TB1> INFO: 3301500 events read in total (118816ms).
[17:29:09.943] <TB1> INFO: 4404950 events read in total (159033ms).
[17:29:50.243] <TB1> INFO: 5511600 events read in total (199333ms).
[17:30:17.889] <TB1> INFO: 6281600 events read in total (226979ms).
[17:30:17.912] <TB1> INFO: Test took 227832ms.
[17:30:37.300] <TB1> INFO: ROC 0 VthrComp = 96
[17:30:37.300] <TB1> INFO: ROC 1 VthrComp = 91
[17:30:37.300] <TB1> INFO: ROC 2 VthrComp = 105
[17:30:37.300] <TB1> INFO: ROC 3 VthrComp = 96
[17:30:37.300] <TB1> INFO: ROC 4 VthrComp = 94
[17:30:37.301] <TB1> INFO: ROC 5 VthrComp = 101
[17:30:37.301] <TB1> INFO: ROC 6 VthrComp = 98
[17:30:37.301] <TB1> INFO: ROC 7 VthrComp = 93
[17:30:37.301] <TB1> INFO: ROC 8 VthrComp = 96
[17:30:37.301] <TB1> INFO: ROC 9 VthrComp = 89
[17:30:37.301] <TB1> INFO: ROC 10 VthrComp = 91
[17:30:37.301] <TB1> INFO: ROC 11 VthrComp = 100
[17:30:37.301] <TB1> INFO: ROC 12 VthrComp = 99
[17:30:37.301] <TB1> INFO: ROC 13 VthrComp = 86
[17:30:37.302] <TB1> INFO: ROC 14 VthrComp = 96
[17:30:37.302] <TB1> INFO: ROC 15 VthrComp = 95
[17:30:37.302] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[17:30:37.302] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[17:30:37.311] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[17:30:37.311] <TB1> INFO: run 1 of 1
[17:30:37.573] <TB1> INFO: Expecting 6281600 events.
[17:31:13.048] <TB1> INFO: 756190 events read in total (34919ms).
[17:31:47.841] <TB1> INFO: 1510840 events read in total (69712ms).
[17:32:22.933] <TB1> INFO: 2265960 events read in total (104804ms).
[17:32:58.101] <TB1> INFO: 3021870 events read in total (139972ms).
[17:33:33.370] <TB1> INFO: 3771930 events read in total (175241ms).
[17:34:08.611] <TB1> INFO: 4518500 events read in total (210482ms).
[17:34:43.805] <TB1> INFO: 5262880 events read in total (245676ms).
[17:35:19.174] <TB1> INFO: 6007130 events read in total (281045ms).
[17:35:32.241] <TB1> INFO: 6281600 events read in total (294112ms).
[17:35:32.282] <TB1> INFO: Test took 294971ms.
[17:35:58.489] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 60.5109 for pixel 30/53 mean/min/max = 46.5475/32.547/60.5479
[17:35:58.489] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 61.8807 for pixel 12/32 mean/min/max = 47.3735/32.648/62.0989
[17:35:58.500] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 60.6062 for pixel 0/41 mean/min/max = 47.4435/34.1919/60.6951
[17:35:58.500] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 61.6492 for pixel 3/2 mean/min/max = 46.8821/31.9991/61.7651
[17:35:58.500] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 60.4059 for pixel 22/7 mean/min/max = 46.4301/32.341/60.5192
[17:35:58.500] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 60.2679 for pixel 20/1 mean/min/max = 46.4068/32.4571/60.3566
[17:35:58.501] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 61.2892 for pixel 17/0 mean/min/max = 46.8073/32.2799/61.3348
[17:35:58.501] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 59.9293 for pixel 12/77 mean/min/max = 46.5659/33.1298/60.002
[17:35:58.501] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 58.599 for pixel 51/7 mean/min/max = 45.4537/32.2257/58.6818
[17:35:58.502] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 59.8366 for pixel 18/79 mean/min/max = 46.9761/34.0321/59.9201
[17:35:58.502] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 58.3569 for pixel 51/15 mean/min/max = 46.3646/34.368/58.3613
[17:35:58.502] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 59.8701 for pixel 51/17 mean/min/max = 46.2212/32.5357/59.9067
[17:35:58.503] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 57.5001 for pixel 10/11 mean/min/max = 44.6489/31.7815/57.5163
[17:35:58.503] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 58.2568 for pixel 7/5 mean/min/max = 45.3592/32.3828/58.3355
[17:35:58.503] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 58.0596 for pixel 18/0 mean/min/max = 44.8431/31.6234/58.0628
[17:35:58.503] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 58.6037 for pixel 21/3 mean/min/max = 45.1952/31.4714/58.919
[17:35:58.504] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:35:58.592] <TB1> INFO: Expecting 514560 events.
[17:36:11.795] <TB1> INFO: 514560 events read in total (12647ms).
[17:36:11.801] <TB1> INFO: Expecting 514560 events.
[17:36:24.629] <TB1> INFO: 514560 events read in total (12423ms).
[17:36:24.635] <TB1> INFO: Expecting 514560 events.
[17:36:37.494] <TB1> INFO: 514560 events read in total (12445ms).
[17:36:37.504] <TB1> INFO: Expecting 514560 events.
[17:36:50.462] <TB1> INFO: 514560 events read in total (12547ms).
[17:36:50.472] <TB1> INFO: Expecting 514560 events.
[17:37:03.580] <TB1> INFO: 514560 events read in total (12697ms).
[17:37:03.593] <TB1> INFO: Expecting 514560 events.
[17:37:16.515] <TB1> INFO: 514560 events read in total (12520ms).
[17:37:16.529] <TB1> INFO: Expecting 514560 events.
[17:37:29.509] <TB1> INFO: 514560 events read in total (12568ms).
[17:37:29.525] <TB1> INFO: Expecting 514560 events.
[17:37:42.512] <TB1> INFO: 514560 events read in total (12577ms).
[17:37:42.532] <TB1> INFO: Expecting 514560 events.
[17:37:55.453] <TB1> INFO: 514560 events read in total (12512ms).
[17:37:55.472] <TB1> INFO: Expecting 514560 events.
[17:38:08.396] <TB1> INFO: 514560 events read in total (12510ms).
[17:38:08.419] <TB1> INFO: Expecting 514560 events.
[17:38:21.185] <TB1> INFO: 514560 events read in total (12359ms).
[17:38:21.210] <TB1> INFO: Expecting 514560 events.
[17:38:34.028] <TB1> INFO: 514560 events read in total (12416ms).
[17:38:34.054] <TB1> INFO: Expecting 514560 events.
[17:38:47.096] <TB1> INFO: 514560 events read in total (12637ms).
[17:38:47.123] <TB1> INFO: Expecting 514560 events.
[17:39:00.333] <TB1> INFO: 514560 events read in total (12808ms).
[17:39:00.365] <TB1> INFO: Expecting 514560 events.
[17:39:13.333] <TB1> INFO: 514560 events read in total (12566ms).
[17:39:13.364] <TB1> INFO: Expecting 514560 events.
[17:39:26.513] <TB1> INFO: 514560 events read in total (12748ms).
[17:39:26.547] <TB1> INFO: Test took 208043ms.
[17:39:27.458] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[17:39:27.467] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[17:39:27.467] <TB1> INFO: run 1 of 1
[17:39:27.737] <TB1> INFO: Expecting 6281600 events.
[17:40:03.833] <TB1> INFO: 742570 events read in total (35540ms).
[17:40:38.762] <TB1> INFO: 1483970 events read in total (70469ms).
[17:41:13.422] <TB1> INFO: 2226100 events read in total (105129ms).
[17:41:48.301] <TB1> INFO: 2968470 events read in total (140008ms).
[17:42:23.117] <TB1> INFO: 3704990 events read in total (174824ms).
[17:42:57.864] <TB1> INFO: 4437180 events read in total (209571ms).
[17:43:32.951] <TB1> INFO: 5166280 events read in total (244658ms).
[17:44:07.729] <TB1> INFO: 5893950 events read in total (279436ms).
[17:44:26.059] <TB1> INFO: 6281600 events read in total (297766ms).
[17:44:26.107] <TB1> INFO: Test took 298640ms.
[17:44:50.858] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.031044 .. 255.000000
[17:44:50.934] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[17:44:50.942] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[17:44:50.942] <TB1> INFO: run 1 of 1
[17:44:51.202] <TB1> INFO: Expecting 10649600 events.
[17:45:27.811] <TB1> INFO: 712500 events read in total (36053ms).
[17:46:02.393] <TB1> INFO: 1425110 events read in total (70635ms).
[17:46:36.517] <TB1> INFO: 2137890 events read in total (104759ms).
[17:47:11.022] <TB1> INFO: 2850560 events read in total (139264ms).
[17:47:45.206] <TB1> INFO: 3563350 events read in total (173448ms).
[17:48:20.166] <TB1> INFO: 4276330 events read in total (208408ms).
[17:48:54.487] <TB1> INFO: 4989210 events read in total (242729ms).
[17:49:29.325] <TB1> INFO: 5702310 events read in total (277567ms).
[17:50:03.565] <TB1> INFO: 6414650 events read in total (311807ms).
[17:50:37.774] <TB1> INFO: 7126270 events read in total (346016ms).
[17:51:12.391] <TB1> INFO: 7837960 events read in total (380633ms).
[17:51:47.715] <TB1> INFO: 8548800 events read in total (415957ms).
[17:52:22.361] <TB1> INFO: 9259480 events read in total (450603ms).
[17:52:57.263] <TB1> INFO: 9969930 events read in total (485505ms).
[17:53:30.328] <TB1> INFO: 10649600 events read in total (518570ms).
[17:53:30.459] <TB1> INFO: Test took 519517ms.
[17:54:01.572] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 15.456365 .. 46.589732
[17:54:01.648] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 5 .. 56 (-1/-1) hits flags = 528 (plus default)
[17:54:01.656] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[17:54:01.656] <TB1> INFO: run 1 of 1
[17:54:02.007] <TB1> INFO: Expecting 2163200 events.
[17:54:41.476] <TB1> INFO: 931060 events read in total (38913ms).
[17:55:19.488] <TB1> INFO: 1859950 events read in total (76925ms).
[17:55:32.166] <TB1> INFO: 2163200 events read in total (89603ms).
[17:55:32.183] <TB1> INFO: Test took 90527ms.
[17:55:45.856] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 1.409219 .. 44.679246
[17:55:45.931] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 1 .. 54 (-1/-1) hits flags = 528 (plus default)
[17:55:45.940] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[17:55:45.940] <TB1> INFO: run 1 of 1
[17:55:46.201] <TB1> INFO: Expecting 2246400 events.
[17:56:25.224] <TB1> INFO: 969250 events read in total (38467ms).
[17:57:03.593] <TB1> INFO: 1937600 events read in total (76836ms).
[17:57:16.211] <TB1> INFO: 2246400 events read in total (89454ms).
[17:57:16.221] <TB1> INFO: Test took 90281ms.
[17:57:31.154] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 1.409219 .. 44.679246
[17:57:31.250] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 1 .. 54 (-1/-1) hits flags = 528 (plus default)
[17:57:31.260] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[17:57:31.260] <TB1> INFO: run 1 of 1
[17:57:31.548] <TB1> INFO: Expecting 2246400 events.
[17:58:10.345] <TB1> INFO: 969710 events read in total (38241ms).
[17:58:48.242] <TB1> INFO: 1939220 events read in total (76138ms).
[17:59:00.798] <TB1> INFO: 2246400 events read in total (88695ms).
[17:59:00.816] <TB1> INFO: Test took 89556ms.
[17:59:13.727] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[17:59:13.727] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[17:59:13.735] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[17:59:13.735] <TB1> INFO: run 1 of 1
[17:59:14.005] <TB1> INFO: Expecting 1705600 events.
[17:59:51.921] <TB1> INFO: 879080 events read in total (37360ms).
[18:00:27.278] <TB1> INFO: 1705600 events read in total (72717ms).
[18:00:27.296] <TB1> INFO: Test took 73561ms.
[18:00:41.517] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C0.dat
[18:00:41.517] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C1.dat
[18:00:41.517] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C2.dat
[18:00:41.517] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C3.dat
[18:00:41.517] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C4.dat
[18:00:41.518] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C5.dat
[18:00:41.518] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C6.dat
[18:00:41.518] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C7.dat
[18:00:41.518] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C8.dat
[18:00:41.518] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C9.dat
[18:00:41.518] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C10.dat
[18:00:41.519] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C11.dat
[18:00:41.519] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C12.dat
[18:00:41.519] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C13.dat
[18:00:41.530] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C14.dat
[18:00:41.531] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C15.dat
[18:00:41.531] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C0.dat
[18:00:41.541] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C1.dat
[18:00:41.550] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C2.dat
[18:00:41.556] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C3.dat
[18:00:41.562] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C4.dat
[18:00:41.569] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C5.dat
[18:00:41.577] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C6.dat
[18:00:41.583] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C7.dat
[18:00:41.589] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C8.dat
[18:00:41.596] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C9.dat
[18:00:41.603] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C10.dat
[18:00:41.609] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C11.dat
[18:00:41.618] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C12.dat
[18:00:41.624] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C13.dat
[18:00:41.630] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C14.dat
[18:00:41.636] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C15.dat
[18:00:41.642] <TB1> INFO: PixTestTrim::trimTest() done
[18:00:41.642] <TB1> INFO: vtrim: 108 103 107 108 107 100 106 98 81 98 79 92 104 99 96 101
[18:00:41.642] <TB1> INFO: vthrcomp: 96 91 105 96 94 101 98 93 96 89 91 100 99 86 96 95
[18:00:41.642] <TB1> INFO: vcal mean: 34.93 34.99 34.95 34.98 35.03 34.99 34.94 34.98 35.01 34.98 35.01 34.95 34.94 34.97 34.95 34.96
[18:00:41.642] <TB1> INFO: vcal RMS: 1.26 0.86 0.82 1.07 0.89 0.91 0.90 0.84 0.86 0.88 0.83 0.99 0.87 0.83 0.90 1.06
[18:00:41.642] <TB1> INFO: bits mean: 9.55 8.93 8.46 9.25 9.44 9.26 9.34 9.12 9.16 9.03 8.69 8.61 9.80 9.63 9.94 10.11
[18:00:41.642] <TB1> INFO: bits RMS: 2.54 2.79 2.68 2.74 2.64 2.70 2.71 2.68 2.82 2.55 2.68 3.01 2.72 2.63 2.64 2.54
[18:00:41.648] <TB1> INFO: ----------------------------------------------------------------------
[18:00:41.648] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[18:00:41.648] <TB1> INFO: ----------------------------------------------------------------------
[18:00:41.651] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[18:00:41.660] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[18:00:41.660] <TB1> INFO: run 1 of 1
[18:00:41.949] <TB1> INFO: Expecting 4160000 events.
[18:01:24.608] <TB1> INFO: 968300 events read in total (42103ms).
[18:02:06.590] <TB1> INFO: 1928740 events read in total (84085ms).
[18:02:48.012] <TB1> INFO: 2878995 events read in total (125508ms).
[18:03:29.686] <TB1> INFO: 3826945 events read in total (167181ms).
[18:03:44.580] <TB1> INFO: 4160000 events read in total (182075ms).
[18:03:44.617] <TB1> INFO: Test took 182957ms.
[18:04:13.959] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 182 (-1/-1) hits flags = 528 (plus default)
[18:04:13.967] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[18:04:13.967] <TB1> INFO: run 1 of 1
[18:04:14.228] <TB1> INFO: Expecting 3806400 events.
[18:04:56.773] <TB1> INFO: 968085 events read in total (41989ms).
[18:05:38.455] <TB1> INFO: 1928855 events read in total (83671ms).
[18:06:19.988] <TB1> INFO: 2879135 events read in total (125206ms).
[18:07:01.175] <TB1> INFO: 3806400 events read in total (166391ms).
[18:07:01.215] <TB1> INFO: Test took 167248ms.
[18:07:29.514] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 171 (-1/-1) hits flags = 528 (plus default)
[18:07:29.523] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[18:07:29.523] <TB1> INFO: run 1 of 1
[18:07:29.787] <TB1> INFO: Expecting 3577600 events.
[18:08:13.153] <TB1> INFO: 996890 events read in total (42810ms).
[18:08:55.875] <TB1> INFO: 1983650 events read in total (85532ms).
[18:09:38.026] <TB1> INFO: 2959790 events read in total (127684ms).
[18:10:05.137] <TB1> INFO: 3577600 events read in total (154794ms).
[18:10:05.178] <TB1> INFO: Test took 155656ms.
[18:10:33.440] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 169 (-1/-1) hits flags = 528 (plus default)
[18:10:33.449] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[18:10:33.449] <TB1> INFO: run 1 of 1
[18:10:33.713] <TB1> INFO: Expecting 3536000 events.
[18:11:17.117] <TB1> INFO: 1002170 events read in total (42848ms).
[18:11:59.843] <TB1> INFO: 1993835 events read in total (85575ms).
[18:12:42.377] <TB1> INFO: 2974520 events read in total (128108ms).
[18:13:06.579] <TB1> INFO: 3536000 events read in total (152310ms).
[18:13:06.619] <TB1> INFO: Test took 153170ms.
[18:13:32.785] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 169 (-1/-1) hits flags = 528 (plus default)
[18:13:32.794] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[18:13:32.798] <TB1> INFO: run 1 of 1
[18:13:33.060] <TB1> INFO: Expecting 3536000 events.
[18:14:16.667] <TB1> INFO: 1001790 events read in total (43051ms).
[18:14:58.997] <TB1> INFO: 1992815 events read in total (85381ms).
[18:15:41.402] <TB1> INFO: 2973075 events read in total (127786ms).
[18:16:05.723] <TB1> INFO: 3536000 events read in total (152107ms).
[18:16:05.768] <TB1> INFO: Test took 152970ms.
[18:16:31.543] <TB1> INFO: PixTestTrim::trimBitTest() done
[18:16:31.544] <TB1> INFO: PixTestTrim::doTest() done, duration: 3001 seconds
[18:16:31.544] <TB1> INFO: Decoding statistics:
[18:16:31.544] <TB1> INFO: General information:
[18:16:31.544] <TB1> INFO: 16bit words read: 0
[18:16:31.544] <TB1> INFO: valid events total: 0
[18:16:31.544] <TB1> INFO: empty events: 0
[18:16:31.544] <TB1> INFO: valid events with pixels: 0
[18:16:31.544] <TB1> INFO: valid pixel hits: 0
[18:16:31.544] <TB1> INFO: Event errors: 0
[18:16:31.544] <TB1> INFO: start marker: 0
[18:16:31.544] <TB1> INFO: stop marker: 0
[18:16:31.544] <TB1> INFO: overflow: 0
[18:16:31.544] <TB1> INFO: invalid 5bit words: 0
[18:16:31.544] <TB1> INFO: invalid XOR eye diagram: 0
[18:16:31.544] <TB1> INFO: frame (failed synchr.): 0
[18:16:31.544] <TB1> INFO: idle data (no TBM trl): 0
[18:16:31.544] <TB1> INFO: no data (only TBM hdr): 0
[18:16:31.544] <TB1> INFO: TBM errors: 0
[18:16:31.544] <TB1> INFO: flawed TBM headers: 0
[18:16:31.544] <TB1> INFO: flawed TBM trailers: 0
[18:16:31.544] <TB1> INFO: event ID mismatches: 0
[18:16:31.544] <TB1> INFO: ROC errors: 0
[18:16:31.544] <TB1> INFO: missing ROC header(s): 0
[18:16:31.544] <TB1> INFO: misplaced readback start: 0
[18:16:31.544] <TB1> INFO: Pixel decoding errors: 0
[18:16:31.544] <TB1> INFO: pixel data incomplete: 0
[18:16:31.544] <TB1> INFO: pixel address: 0
[18:16:31.544] <TB1> INFO: pulse height fill bit: 0
[18:16:31.544] <TB1> INFO: buffer corruption: 0
[18:16:32.253] <TB1> INFO: ######################################################################
[18:16:32.253] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[18:16:32.253] <TB1> INFO: ######################################################################
[18:16:32.518] <TB1> INFO: Expecting 41600 events.
[18:16:36.375] <TB1> INFO: 41600 events read in total (3301ms).
[18:16:36.375] <TB1> INFO: Test took 4121ms.
[18:16:36.844] <TB1> INFO: Expecting 41600 events.
[18:16:40.704] <TB1> INFO: 41600 events read in total (3303ms).
[18:16:40.704] <TB1> INFO: Test took 4132ms.
[18:16:40.998] <TB1> INFO: Expecting 41600 events.
[18:16:44.850] <TB1> INFO: 41600 events read in total (3296ms).
[18:16:44.851] <TB1> INFO: Test took 4125ms.
[18:16:45.106] <TB1> INFO: Expecting 2560 events.
[18:16:45.961] <TB1> INFO: 2560 events read in total (299ms).
[18:16:45.961] <TB1> INFO: Test took 1098ms.
[18:16:46.266] <TB1> INFO: Expecting 2560 events.
[18:16:47.119] <TB1> INFO: 2560 events read in total (297ms).
[18:16:47.119] <TB1> INFO: Test took 1158ms.
[18:16:47.425] <TB1> INFO: Expecting 2560 events.
[18:16:48.276] <TB1> INFO: 2560 events read in total (295ms).
[18:16:48.276] <TB1> INFO: Test took 1156ms.
[18:16:48.581] <TB1> INFO: Expecting 2560 events.
[18:16:49.438] <TB1> INFO: 2560 events read in total (301ms).
[18:16:49.438] <TB1> INFO: Test took 1162ms.
[18:16:49.743] <TB1> INFO: Expecting 2560 events.
[18:16:50.596] <TB1> INFO: 2560 events read in total (297ms).
[18:16:50.596] <TB1> INFO: Test took 1157ms.
[18:16:50.901] <TB1> INFO: Expecting 2560 events.
[18:16:51.754] <TB1> INFO: 2560 events read in total (297ms).
[18:16:51.754] <TB1> INFO: Test took 1158ms.
[18:16:52.059] <TB1> INFO: Expecting 2560 events.
[18:16:52.912] <TB1> INFO: 2560 events read in total (297ms).
[18:16:52.912] <TB1> INFO: Test took 1158ms.
[18:16:53.218] <TB1> INFO: Expecting 2560 events.
[18:16:54.073] <TB1> INFO: 2560 events read in total (299ms).
[18:16:54.073] <TB1> INFO: Test took 1162ms.
[18:16:54.379] <TB1> INFO: Expecting 2560 events.
[18:16:55.234] <TB1> INFO: 2560 events read in total (299ms).
[18:16:55.234] <TB1> INFO: Test took 1159ms.
[18:16:55.539] <TB1> INFO: Expecting 2560 events.
[18:16:56.392] <TB1> INFO: 2560 events read in total (297ms).
[18:16:56.392] <TB1> INFO: Test took 1157ms.
[18:16:56.698] <TB1> INFO: Expecting 2560 events.
[18:16:57.553] <TB1> INFO: 2560 events read in total (299ms).
[18:16:57.554] <TB1> INFO: Test took 1161ms.
[18:16:57.858] <TB1> INFO: Expecting 2560 events.
[18:16:58.712] <TB1> INFO: 2560 events read in total (298ms).
[18:16:58.712] <TB1> INFO: Test took 1158ms.
[18:16:59.017] <TB1> INFO: Expecting 2560 events.
[18:16:59.870] <TB1> INFO: 2560 events read in total (297ms).
[18:16:59.870] <TB1> INFO: Test took 1158ms.
[18:17:00.175] <TB1> INFO: Expecting 2560 events.
[18:17:01.028] <TB1> INFO: 2560 events read in total (297ms).
[18:17:01.028] <TB1> INFO: Test took 1158ms.
[18:17:01.333] <TB1> INFO: Expecting 2560 events.
[18:17:02.186] <TB1> INFO: 2560 events read in total (297ms).
[18:17:02.186] <TB1> INFO: Test took 1158ms.
[18:17:02.491] <TB1> INFO: Expecting 2560 events.
[18:17:03.346] <TB1> INFO: 2560 events read in total (299ms).
[18:17:03.346] <TB1> INFO: Test took 1160ms.
[18:17:03.348] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:17:03.652] <TB1> INFO: Expecting 655360 events.
[18:17:20.630] <TB1> INFO: 655360 events read in total (16422ms).
[18:17:20.638] <TB1> INFO: Expecting 655360 events.
[18:17:37.308] <TB1> INFO: 655360 events read in total (16268ms).
[18:17:37.321] <TB1> INFO: Expecting 655360 events.
[18:17:53.999] <TB1> INFO: 655360 events read in total (16277ms).
[18:17:54.016] <TB1> INFO: Expecting 655360 events.
[18:18:10.553] <TB1> INFO: 655360 events read in total (16135ms).
[18:18:10.573] <TB1> INFO: Expecting 655360 events.
[18:18:27.156] <TB1> INFO: 655360 events read in total (16182ms).
[18:18:27.179] <TB1> INFO: Expecting 655360 events.
[18:18:43.943] <TB1> INFO: 655360 events read in total (16363ms).
[18:18:43.969] <TB1> INFO: Expecting 655360 events.
[18:19:00.801] <TB1> INFO: 655360 events read in total (16430ms).
[18:19:00.834] <TB1> INFO: Expecting 655360 events.
[18:19:17.707] <TB1> INFO: 655360 events read in total (16471ms).
[18:19:17.739] <TB1> INFO: Expecting 655360 events.
[18:19:34.458] <TB1> INFO: 655360 events read in total (16317ms).
[18:19:34.497] <TB1> INFO: Expecting 655360 events.
[18:19:51.358] <TB1> INFO: 655360 events read in total (16459ms).
[18:19:51.398] <TB1> INFO: Expecting 655360 events.
[18:20:08.214] <TB1> INFO: 655360 events read in total (16414ms).
[18:20:08.262] <TB1> INFO: Expecting 655360 events.
[18:20:24.768] <TB1> INFO: 655360 events read in total (16105ms).
[18:20:24.815] <TB1> INFO: Expecting 655360 events.
[18:20:41.391] <TB1> INFO: 655360 events read in total (16175ms).
[18:20:41.443] <TB1> INFO: Expecting 655360 events.
[18:20:58.216] <TB1> INFO: 655360 events read in total (16371ms).
[18:20:58.270] <TB1> INFO: Expecting 655360 events.
[18:21:14.852] <TB1> INFO: 655360 events read in total (16181ms).
[18:21:14.949] <TB1> INFO: Expecting 655360 events.
[18:21:31.680] <TB1> INFO: 655360 events read in total (16330ms).
[18:21:31.740] <TB1> INFO: Test took 268392ms.
[18:21:31.819] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:21:32.050] <TB1> INFO: Expecting 655360 events.
[18:21:48.697] <TB1> INFO: 655360 events read in total (16091ms).
[18:21:48.706] <TB1> INFO: Expecting 655360 events.
[18:22:05.351] <TB1> INFO: 655360 events read in total (16243ms).
[18:22:05.365] <TB1> INFO: Expecting 655360 events.
[18:22:22.085] <TB1> INFO: 655360 events read in total (16318ms).
[18:22:22.100] <TB1> INFO: Expecting 655360 events.
[18:22:38.749] <TB1> INFO: 655360 events read in total (16247ms).
[18:22:38.771] <TB1> INFO: Expecting 655360 events.
[18:22:55.526] <TB1> INFO: 655360 events read in total (16353ms).
[18:22:55.548] <TB1> INFO: Expecting 655360 events.
[18:23:12.320] <TB1> INFO: 655360 events read in total (16370ms).
[18:23:12.348] <TB1> INFO: Expecting 655360 events.
[18:23:28.986] <TB1> INFO: 655360 events read in total (16236ms).
[18:23:29.017] <TB1> INFO: Expecting 655360 events.
[18:23:45.513] <TB1> INFO: 655360 events read in total (16094ms).
[18:23:45.546] <TB1> INFO: Expecting 655360 events.
[18:24:01.987] <TB1> INFO: 655360 events read in total (16039ms).
[18:24:02.023] <TB1> INFO: Expecting 655360 events.
[18:24:18.927] <TB1> INFO: 655360 events read in total (16503ms).
[18:24:18.971] <TB1> INFO: Expecting 655360 events.
[18:24:35.708] <TB1> INFO: 655360 events read in total (16336ms).
[18:24:35.750] <TB1> INFO: Expecting 655360 events.
[18:24:52.544] <TB1> INFO: 655360 events read in total (16392ms).
[18:24:52.594] <TB1> INFO: Expecting 655360 events.
[18:25:09.457] <TB1> INFO: 655360 events read in total (16461ms).
[18:25:09.507] <TB1> INFO: Expecting 655360 events.
[18:25:26.336] <TB1> INFO: 655360 events read in total (16427ms).
[18:25:26.388] <TB1> INFO: Expecting 655360 events.
[18:25:43.220] <TB1> INFO: 655360 events read in total (16431ms).
[18:25:43.280] <TB1> INFO: Expecting 655360 events.
[18:26:00.378] <TB1> INFO: 655360 events read in total (16696ms).
[18:26:00.448] <TB1> INFO: Test took 268629ms.
[18:26:00.650] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:26:00.657] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:26:00.665] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:26:00.672] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:26:00.679] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:26:00.687] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:26:00.694] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[18:26:00.701] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[18:26:00.708] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[18:26:00.715] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:26:00.722] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:26:00.729] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:26:00.736] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:26:00.743] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[18:26:00.750] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[18:26:00.757] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[18:26:00.764] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[18:26:00.771] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[18:26:00.778] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[18:26:00.785] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[18:26:00.793] <TB1> INFO: safety margin for low PH: adding 8, margin is now 28
[18:26:00.800] <TB1> INFO: safety margin for low PH: adding 9, margin is now 29
[18:26:00.806] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:26:00.813] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:26:00.820] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:26:00.827] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:26:00.834] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:26:00.840] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:26:00.876] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C0.dat
[18:26:00.876] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C1.dat
[18:26:00.876] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C2.dat
[18:26:00.877] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C3.dat
[18:26:00.877] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C4.dat
[18:26:00.877] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C5.dat
[18:26:00.877] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C6.dat
[18:26:00.877] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C7.dat
[18:26:00.877] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C8.dat
[18:26:00.877] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C9.dat
[18:26:00.878] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C10.dat
[18:26:00.878] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C11.dat
[18:26:00.878] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C12.dat
[18:26:00.878] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C13.dat
[18:26:00.878] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C14.dat
[18:26:00.878] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C15.dat
[18:26:01.144] <TB1> INFO: Expecting 41600 events.
[18:26:04.615] <TB1> INFO: 41600 events read in total (2914ms).
[18:26:04.615] <TB1> INFO: Test took 3734ms.
[18:26:05.065] <TB1> INFO: Expecting 41600 events.
[18:26:08.554] <TB1> INFO: 41600 events read in total (2933ms).
[18:26:08.555] <TB1> INFO: Test took 3750ms.
[18:26:09.006] <TB1> INFO: Expecting 41600 events.
[18:26:12.549] <TB1> INFO: 41600 events read in total (2986ms).
[18:26:12.550] <TB1> INFO: Test took 3818ms.
[18:26:12.735] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:12.824] <TB1> INFO: Expecting 2560 events.
[18:26:13.683] <TB1> INFO: 2560 events read in total (303ms).
[18:26:13.684] <TB1> INFO: Test took 949ms.
[18:26:13.686] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:13.989] <TB1> INFO: Expecting 2560 events.
[18:26:14.847] <TB1> INFO: 2560 events read in total (301ms).
[18:26:14.847] <TB1> INFO: Test took 1161ms.
[18:26:14.852] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:15.153] <TB1> INFO: Expecting 2560 events.
[18:26:16.009] <TB1> INFO: 2560 events read in total (300ms).
[18:26:16.010] <TB1> INFO: Test took 1158ms.
[18:26:16.012] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:16.315] <TB1> INFO: Expecting 2560 events.
[18:26:17.173] <TB1> INFO: 2560 events read in total (302ms).
[18:26:17.173] <TB1> INFO: Test took 1161ms.
[18:26:17.175] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:17.479] <TB1> INFO: Expecting 2560 events.
[18:26:18.333] <TB1> INFO: 2560 events read in total (298ms).
[18:26:18.333] <TB1> INFO: Test took 1158ms.
[18:26:18.336] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:18.638] <TB1> INFO: Expecting 2560 events.
[18:26:19.491] <TB1> INFO: 2560 events read in total (296ms).
[18:26:19.492] <TB1> INFO: Test took 1156ms.
[18:26:19.494] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:19.798] <TB1> INFO: Expecting 2560 events.
[18:26:20.650] <TB1> INFO: 2560 events read in total (296ms).
[18:26:20.651] <TB1> INFO: Test took 1157ms.
[18:26:20.652] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:20.957] <TB1> INFO: Expecting 2560 events.
[18:26:21.815] <TB1> INFO: 2560 events read in total (302ms).
[18:26:21.815] <TB1> INFO: Test took 1163ms.
[18:26:21.817] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:22.121] <TB1> INFO: Expecting 2560 events.
[18:26:22.978] <TB1> INFO: 2560 events read in total (300ms).
[18:26:22.979] <TB1> INFO: Test took 1162ms.
[18:26:22.980] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:23.285] <TB1> INFO: Expecting 2560 events.
[18:26:24.139] <TB1> INFO: 2560 events read in total (298ms).
[18:26:24.139] <TB1> INFO: Test took 1159ms.
[18:26:24.142] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:24.445] <TB1> INFO: Expecting 2560 events.
[18:26:25.298] <TB1> INFO: 2560 events read in total (297ms).
[18:26:25.298] <TB1> INFO: Test took 1156ms.
[18:26:25.308] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:25.605] <TB1> INFO: Expecting 2560 events.
[18:26:26.459] <TB1> INFO: 2560 events read in total (298ms).
[18:26:26.460] <TB1> INFO: Test took 1152ms.
[18:26:26.462] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:26.766] <TB1> INFO: Expecting 2560 events.
[18:26:27.621] <TB1> INFO: 2560 events read in total (299ms).
[18:26:27.621] <TB1> INFO: Test took 1159ms.
[18:26:27.624] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:27.927] <TB1> INFO: Expecting 2560 events.
[18:26:28.786] <TB1> INFO: 2560 events read in total (303ms).
[18:26:28.786] <TB1> INFO: Test took 1162ms.
[18:26:28.789] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:29.092] <TB1> INFO: Expecting 2560 events.
[18:26:29.952] <TB1> INFO: 2560 events read in total (304ms).
[18:26:29.952] <TB1> INFO: Test took 1163ms.
[18:26:29.955] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:30.258] <TB1> INFO: Expecting 2560 events.
[18:26:31.116] <TB1> INFO: 2560 events read in total (302ms).
[18:26:31.117] <TB1> INFO: Test took 1163ms.
[18:26:31.119] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:31.422] <TB1> INFO: Expecting 2560 events.
[18:26:32.279] <TB1> INFO: 2560 events read in total (300ms).
[18:26:32.279] <TB1> INFO: Test took 1160ms.
[18:26:32.281] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:32.585] <TB1> INFO: Expecting 2560 events.
[18:26:33.439] <TB1> INFO: 2560 events read in total (298ms).
[18:26:33.439] <TB1> INFO: Test took 1158ms.
[18:26:33.441] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:33.746] <TB1> INFO: Expecting 2560 events.
[18:26:34.599] <TB1> INFO: 2560 events read in total (297ms).
[18:26:34.600] <TB1> INFO: Test took 1159ms.
[18:26:34.601] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:34.906] <TB1> INFO: Expecting 2560 events.
[18:26:35.761] <TB1> INFO: 2560 events read in total (299ms).
[18:26:35.761] <TB1> INFO: Test took 1160ms.
[18:26:35.765] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:36.067] <TB1> INFO: Expecting 2560 events.
[18:26:36.921] <TB1> INFO: 2560 events read in total (298ms).
[18:26:36.921] <TB1> INFO: Test took 1156ms.
[18:26:36.924] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:37.226] <TB1> INFO: Expecting 2560 events.
[18:26:38.086] <TB1> INFO: 2560 events read in total (304ms).
[18:26:38.086] <TB1> INFO: Test took 1162ms.
[18:26:38.088] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:38.391] <TB1> INFO: Expecting 2560 events.
[18:26:39.244] <TB1> INFO: 2560 events read in total (297ms).
[18:26:39.244] <TB1> INFO: Test took 1156ms.
[18:26:39.246] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:39.551] <TB1> INFO: Expecting 2560 events.
[18:26:40.404] <TB1> INFO: 2560 events read in total (297ms).
[18:26:40.404] <TB1> INFO: Test took 1158ms.
[18:26:40.407] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:40.709] <TB1> INFO: Expecting 2560 events.
[18:26:41.565] <TB1> INFO: 2560 events read in total (299ms).
[18:26:41.565] <TB1> INFO: Test took 1158ms.
[18:26:41.567] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:41.872] <TB1> INFO: Expecting 2560 events.
[18:26:42.724] <TB1> INFO: 2560 events read in total (296ms).
[18:26:42.724] <TB1> INFO: Test took 1157ms.
[18:26:42.726] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:43.031] <TB1> INFO: Expecting 2560 events.
[18:26:43.884] <TB1> INFO: 2560 events read in total (297ms).
[18:26:43.884] <TB1> INFO: Test took 1158ms.
[18:26:43.886] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:44.189] <TB1> INFO: Expecting 2560 events.
[18:26:45.047] <TB1> INFO: 2560 events read in total (302ms).
[18:26:45.047] <TB1> INFO: Test took 1161ms.
[18:26:45.049] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:45.355] <TB1> INFO: Expecting 2560 events.
[18:26:46.215] <TB1> INFO: 2560 events read in total (304ms).
[18:26:46.222] <TB1> INFO: Test took 1173ms.
[18:26:46.225] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:46.521] <TB1> INFO: Expecting 2560 events.
[18:26:47.378] <TB1> INFO: 2560 events read in total (301ms).
[18:26:47.378] <TB1> INFO: Test took 1153ms.
[18:26:47.382] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:47.684] <TB1> INFO: Expecting 2560 events.
[18:26:48.542] <TB1> INFO: 2560 events read in total (302ms).
[18:26:48.542] <TB1> INFO: Test took 1160ms.
[18:26:48.547] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:48.848] <TB1> INFO: Expecting 2560 events.
[18:26:49.703] <TB1> INFO: 2560 events read in total (299ms).
[18:26:49.703] <TB1> INFO: Test took 1158ms.
[18:26:50.165] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 617 seconds
[18:26:50.165] <TB1> INFO: PH scale (per ROC): 62 74 64 68 68 70 64 69 66 72 68 69 67 67 71 70
[18:26:50.165] <TB1> INFO: PH offset (per ROC): 192 180 189 187 181 189 192 187 189 176 186 184 193 176 180 181
[18:26:50.168] <TB1> INFO: Decoding statistics:
[18:26:50.168] <TB1> INFO: General information:
[18:26:50.168] <TB1> INFO: 16bit words read: 91928
[18:26:50.169] <TB1> INFO: valid events total: 10240
[18:26:50.169] <TB1> INFO: empty events: 7738
[18:26:50.169] <TB1> INFO: valid events with pixels: 2502
[18:26:50.169] <TB1> INFO: valid pixel hits: 2502
[18:26:50.169] <TB1> INFO: Event errors: 0
[18:26:50.169] <TB1> INFO: start marker: 0
[18:26:50.169] <TB1> INFO: stop marker: 0
[18:26:50.169] <TB1> INFO: overflow: 0
[18:26:50.169] <TB1> INFO: invalid 5bit words: 0
[18:26:50.169] <TB1> INFO: invalid XOR eye diagram: 0
[18:26:50.169] <TB1> INFO: frame (failed synchr.): 0
[18:26:50.169] <TB1> INFO: idle data (no TBM trl): 0
[18:26:50.169] <TB1> INFO: no data (only TBM hdr): 0
[18:26:50.169] <TB1> INFO: TBM errors: 0
[18:26:50.169] <TB1> INFO: flawed TBM headers: 0
[18:26:50.169] <TB1> INFO: flawed TBM trailers: 0
[18:26:50.169] <TB1> INFO: event ID mismatches: 0
[18:26:50.169] <TB1> INFO: ROC errors: 0
[18:26:50.169] <TB1> INFO: missing ROC header(s): 0
[18:26:50.169] <TB1> INFO: misplaced readback start: 0
[18:26:50.169] <TB1> INFO: Pixel decoding errors: 0
[18:26:50.169] <TB1> INFO: pixel data incomplete: 0
[18:26:50.169] <TB1> INFO: pixel address: 0
[18:26:50.169] <TB1> INFO: pulse height fill bit: 0
[18:26:50.169] <TB1> INFO: buffer corruption: 0
[18:26:50.336] <TB1> INFO: ######################################################################
[18:26:50.336] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[18:26:50.336] <TB1> INFO: ######################################################################
[18:26:50.346] <TB1> INFO: scanning low vcal = 10
[18:26:50.609] <TB1> INFO: Expecting 41600 events.
[18:26:54.138] <TB1> INFO: 41600 events read in total (2973ms).
[18:26:54.138] <TB1> INFO: Test took 3792ms.
[18:26:54.140] <TB1> INFO: scanning low vcal = 20
[18:26:54.443] <TB1> INFO: Expecting 41600 events.
[18:26:58.015] <TB1> INFO: 41600 events read in total (3016ms).
[18:26:58.015] <TB1> INFO: Test took 3875ms.
[18:26:58.017] <TB1> INFO: scanning low vcal = 30
[18:26:58.321] <TB1> INFO: Expecting 41600 events.
[18:27:01.888] <TB1> INFO: 41600 events read in total (3011ms).
[18:27:01.888] <TB1> INFO: Test took 3871ms.
[18:27:01.901] <TB1> INFO: scanning low vcal = 40
[18:27:02.189] <TB1> INFO: Expecting 41600 events.
[18:27:06.417] <TB1> INFO: 41600 events read in total (3672ms).
[18:27:06.418] <TB1> INFO: Test took 4517ms.
[18:27:06.421] <TB1> INFO: scanning low vcal = 50
[18:27:06.683] <TB1> INFO: Expecting 41600 events.
[18:27:10.889] <TB1> INFO: 41600 events read in total (3650ms).
[18:27:10.889] <TB1> INFO: Test took 4468ms.
[18:27:10.892] <TB1> INFO: scanning low vcal = 60
[18:27:11.168] <TB1> INFO: Expecting 41600 events.
[18:27:15.392] <TB1> INFO: 41600 events read in total (3668ms).
[18:27:15.392] <TB1> INFO: Test took 4500ms.
[18:27:15.395] <TB1> INFO: scanning low vcal = 70
[18:27:15.664] <TB1> INFO: Expecting 41600 events.
[18:27:19.887] <TB1> INFO: 41600 events read in total (3667ms).
[18:27:19.887] <TB1> INFO: Test took 4492ms.
[18:27:19.890] <TB1> INFO: scanning low vcal = 80
[18:27:20.151] <TB1> INFO: Expecting 41600 events.
[18:27:24.383] <TB1> INFO: 41600 events read in total (3676ms).
[18:27:24.384] <TB1> INFO: Test took 4494ms.
[18:27:24.386] <TB1> INFO: scanning low vcal = 90
[18:27:24.686] <TB1> INFO: Expecting 41600 events.
[18:27:28.912] <TB1> INFO: 41600 events read in total (3670ms).
[18:27:28.912] <TB1> INFO: Test took 4526ms.
[18:27:28.916] <TB1> INFO: scanning low vcal = 100
[18:27:29.216] <TB1> INFO: Expecting 41600 events.
[18:27:33.406] <TB1> INFO: 41600 events read in total (3634ms).
[18:27:33.407] <TB1> INFO: Test took 4491ms.
[18:27:33.410] <TB1> INFO: scanning low vcal = 110
[18:27:33.680] <TB1> INFO: Expecting 41600 events.
[18:27:37.922] <TB1> INFO: 41600 events read in total (3675ms).
[18:27:37.923] <TB1> INFO: Test took 4513ms.
[18:27:37.927] <TB1> INFO: scanning low vcal = 120
[18:27:38.188] <TB1> INFO: Expecting 41600 events.
[18:27:42.416] <TB1> INFO: 41600 events read in total (3671ms).
[18:27:42.418] <TB1> INFO: Test took 4491ms.
[18:27:42.421] <TB1> INFO: scanning low vcal = 130
[18:27:42.690] <TB1> INFO: Expecting 41600 events.
[18:27:46.879] <TB1> INFO: 41600 events read in total (3633ms).
[18:27:46.880] <TB1> INFO: Test took 4459ms.
[18:27:46.882] <TB1> INFO: scanning low vcal = 140
[18:27:47.160] <TB1> INFO: Expecting 41600 events.
[18:27:51.346] <TB1> INFO: 41600 events read in total (3630ms).
[18:27:51.347] <TB1> INFO: Test took 4465ms.
[18:27:51.349] <TB1> INFO: scanning low vcal = 150
[18:27:51.610] <TB1> INFO: Expecting 41600 events.
[18:27:55.811] <TB1> INFO: 41600 events read in total (3645ms).
[18:27:55.811] <TB1> INFO: Test took 4462ms.
[18:27:55.814] <TB1> INFO: scanning low vcal = 160
[18:27:56.081] <TB1> INFO: Expecting 41600 events.
[18:28:00.291] <TB1> INFO: 41600 events read in total (3654ms).
[18:28:00.291] <TB1> INFO: Test took 4477ms.
[18:28:00.294] <TB1> INFO: scanning low vcal = 170
[18:28:00.553] <TB1> INFO: Expecting 41600 events.
[18:28:04.778] <TB1> INFO: 41600 events read in total (3668ms).
[18:28:04.778] <TB1> INFO: Test took 4484ms.
[18:28:04.782] <TB1> INFO: scanning low vcal = 180
[18:28:05.050] <TB1> INFO: Expecting 41600 events.
[18:28:09.307] <TB1> INFO: 41600 events read in total (3701ms).
[18:28:09.308] <TB1> INFO: Test took 4526ms.
[18:28:09.310] <TB1> INFO: scanning low vcal = 190
[18:28:09.615] <TB1> INFO: Expecting 41600 events.
[18:28:13.856] <TB1> INFO: 41600 events read in total (3685ms).
[18:28:13.857] <TB1> INFO: Test took 4547ms.
[18:28:13.860] <TB1> INFO: scanning low vcal = 200
[18:28:14.146] <TB1> INFO: Expecting 41600 events.
[18:28:18.352] <TB1> INFO: 41600 events read in total (3650ms).
[18:28:18.352] <TB1> INFO: Test took 4492ms.
[18:28:18.355] <TB1> INFO: scanning low vcal = 210
[18:28:18.623] <TB1> INFO: Expecting 41600 events.
[18:28:22.807] <TB1> INFO: 41600 events read in total (3628ms).
[18:28:22.807] <TB1> INFO: Test took 4452ms.
[18:28:22.810] <TB1> INFO: scanning low vcal = 220
[18:28:23.069] <TB1> INFO: Expecting 41600 events.
[18:28:27.287] <TB1> INFO: 41600 events read in total (3662ms).
[18:28:27.288] <TB1> INFO: Test took 4478ms.
[18:28:27.296] <TB1> INFO: scanning low vcal = 230
[18:28:27.577] <TB1> INFO: Expecting 41600 events.
[18:28:31.785] <TB1> INFO: 41600 events read in total (3652ms).
[18:28:31.785] <TB1> INFO: Test took 4489ms.
[18:28:31.788] <TB1> INFO: scanning low vcal = 240
[18:28:32.079] <TB1> INFO: Expecting 41600 events.
[18:28:36.280] <TB1> INFO: 41600 events read in total (3645ms).
[18:28:36.281] <TB1> INFO: Test took 4493ms.
[18:28:36.283] <TB1> INFO: scanning low vcal = 250
[18:28:36.547] <TB1> INFO: Expecting 41600 events.
[18:28:40.781] <TB1> INFO: 41600 events read in total (3677ms).
[18:28:40.782] <TB1> INFO: Test took 4499ms.
[18:28:40.786] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[18:28:41.082] <TB1> INFO: Expecting 41600 events.
[18:28:45.333] <TB1> INFO: 41600 events read in total (3695ms).
[18:28:45.333] <TB1> INFO: Test took 4547ms.
[18:28:45.336] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[18:28:45.618] <TB1> INFO: Expecting 41600 events.
[18:28:49.790] <TB1> INFO: 41600 events read in total (3616ms).
[18:28:49.791] <TB1> INFO: Test took 4455ms.
[18:28:49.793] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[18:28:50.052] <TB1> INFO: Expecting 41600 events.
[18:28:54.246] <TB1> INFO: 41600 events read in total (3638ms).
[18:28:54.247] <TB1> INFO: Test took 4454ms.
[18:28:54.250] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[18:28:54.547] <TB1> INFO: Expecting 41600 events.
[18:28:58.711] <TB1> INFO: 41600 events read in total (3608ms).
[18:28:58.712] <TB1> INFO: Test took 4462ms.
[18:28:58.715] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[18:28:58.980] <TB1> INFO: Expecting 41600 events.
[18:29:03.138] <TB1> INFO: 41600 events read in total (3602ms).
[18:29:03.139] <TB1> INFO: Test took 4424ms.
[18:29:03.618] <TB1> INFO: PixTestGainPedestal::measure() done
[18:29:36.695] <TB1> INFO: PixTestGainPedestal::fit() done
[18:29:36.695] <TB1> INFO: non-linearity mean: 0.961 0.963 0.950 0.959 0.956 0.960 0.958 0.952 0.956 0.963 0.956 0.948 0.959 0.952 0.955 0.957
[18:29:36.695] <TB1> INFO: non-linearity RMS: 0.007 0.006 0.007 0.007 0.006 0.006 0.007 0.008 0.008 0.005 0.007 0.010 0.007 0.006 0.006 0.007
[18:29:36.695] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[18:29:36.715] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[18:29:36.734] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[18:29:36.753] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[18:29:36.773] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[18:29:36.793] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[18:29:36.819] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[18:29:36.839] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[18:29:36.858] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[18:29:36.877] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[18:29:36.897] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[18:29:36.916] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[18:29:36.941] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[18:29:36.960] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[18:29:36.979] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[18:29:36.999] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[18:29:37.019] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 166 seconds
[18:29:37.019] <TB1> INFO: Decoding statistics:
[18:29:37.019] <TB1> INFO: General information:
[18:29:37.019] <TB1> INFO: 16bit words read: 2662400
[18:29:37.019] <TB1> INFO: valid events total: 166400
[18:29:37.019] <TB1> INFO: empty events: 0
[18:29:37.019] <TB1> INFO: valid events with pixels: 166400
[18:29:37.019] <TB1> INFO: valid pixel hits: 665529
[18:29:37.019] <TB1> INFO: Event errors: 0
[18:29:37.019] <TB1> INFO: start marker: 0
[18:29:37.019] <TB1> INFO: stop marker: 0
[18:29:37.019] <TB1> INFO: overflow: 0
[18:29:37.019] <TB1> INFO: invalid 5bit words: 0
[18:29:37.019] <TB1> INFO: invalid XOR eye diagram: 0
[18:29:37.019] <TB1> INFO: frame (failed synchr.): 0
[18:29:37.019] <TB1> INFO: idle data (no TBM trl): 0
[18:29:37.019] <TB1> INFO: no data (only TBM hdr): 0
[18:29:37.019] <TB1> INFO: TBM errors: 0
[18:29:37.019] <TB1> INFO: flawed TBM headers: 0
[18:29:37.019] <TB1> INFO: flawed TBM trailers: 0
[18:29:37.019] <TB1> INFO: event ID mismatches: 0
[18:29:37.019] <TB1> INFO: ROC errors: 0
[18:29:37.019] <TB1> INFO: missing ROC header(s): 0
[18:29:37.019] <TB1> INFO: misplaced readback start: 0
[18:29:37.019] <TB1> INFO: Pixel decoding errors: 0
[18:29:37.019] <TB1> INFO: pixel data incomplete: 0
[18:29:37.019] <TB1> INFO: pixel address: 0
[18:29:37.019] <TB1> INFO: pulse height fill bit: 0
[18:29:37.019] <TB1> INFO: buffer corruption: 0
[18:29:37.027] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C15.dat
[18:29:37.042] <TB1> INFO: ######################################################################
[18:29:37.042] <TB1> INFO: PixTestReadback::doTest()
[18:29:37.042] <TB1> INFO: ######################################################################
[18:29:37.042] <TB1> INFO: ----------------------------------------------------------------------
[18:29:37.042] <TB1> INFO: PixTestReadback::CalibrateVd()
[18:29:37.042] <TB1> INFO: ----------------------------------------------------------------------
[18:29:46.530] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C0.dat
[18:29:46.531] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C1.dat
[18:29:46.531] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C2.dat
[18:29:46.531] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C3.dat
[18:29:46.531] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C4.dat
[18:29:46.531] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C5.dat
[18:29:46.531] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C6.dat
[18:29:46.531] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C7.dat
[18:29:46.531] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C8.dat
[18:29:46.531] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C9.dat
[18:29:46.531] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C10.dat
[18:29:46.531] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C11.dat
[18:29:46.531] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C12.dat
[18:29:46.531] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C13.dat
[18:29:46.532] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C14.dat
[18:29:46.532] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C15.dat
[18:29:46.561] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[18:29:46.561] <TB1> INFO: ----------------------------------------------------------------------
[18:29:46.561] <TB1> INFO: PixTestReadback::CalibrateVa()
[18:29:46.561] <TB1> INFO: ----------------------------------------------------------------------
[18:29:56.030] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C0.dat
[18:29:56.030] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C1.dat
[18:29:56.030] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C2.dat
[18:29:56.030] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C3.dat
[18:29:56.030] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C4.dat
[18:29:56.031] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C5.dat
[18:29:56.031] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C6.dat
[18:29:56.031] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C7.dat
[18:29:56.031] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C8.dat
[18:29:56.031] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C9.dat
[18:29:56.031] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C10.dat
[18:29:56.031] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C11.dat
[18:29:56.031] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C12.dat
[18:29:56.031] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C13.dat
[18:29:56.031] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C14.dat
[18:29:56.031] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C15.dat
[18:29:56.061] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[18:29:56.061] <TB1> INFO: ----------------------------------------------------------------------
[18:29:56.061] <TB1> INFO: PixTestReadback::readbackVbg()
[18:29:56.061] <TB1> INFO: ----------------------------------------------------------------------
[18:30:03.370] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[18:30:03.370] <TB1> INFO: ----------------------------------------------------------------------
[18:30:03.370] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[18:30:03.370] <TB1> INFO: ----------------------------------------------------------------------
[18:30:03.370] <TB1> INFO: Vbg will be calibrated using Vd calibration
[18:30:03.370] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 160.1calibrated Vbg = 1.20844 :::*/*/*/*/
[18:30:03.370] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 159.6calibrated Vbg = 1.21648 :::*/*/*/*/
[18:30:03.370] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 149.4calibrated Vbg = 1.20771 :::*/*/*/*/
[18:30:03.370] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 156.5calibrated Vbg = 1.2228 :::*/*/*/*/
[18:30:03.370] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 149.7calibrated Vbg = 1.22182 :::*/*/*/*/
[18:30:03.370] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 157.6calibrated Vbg = 1.22461 :::*/*/*/*/
[18:30:03.370] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 159.8calibrated Vbg = 1.22527 :::*/*/*/*/
[18:30:03.370] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 162.1calibrated Vbg = 1.22684 :::*/*/*/*/
[18:30:03.370] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 156.5calibrated Vbg = 1.22334 :::*/*/*/*/
[18:30:03.370] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 154.6calibrated Vbg = 1.2306 :::*/*/*/*/
[18:30:03.370] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 156.3calibrated Vbg = 1.22001 :::*/*/*/*/
[18:30:03.370] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 160.2calibrated Vbg = 1.20957 :::*/*/*/*/
[18:30:03.370] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 148.3calibrated Vbg = 1.20472 :::*/*/*/*/
[18:30:03.370] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 153.8calibrated Vbg = 1.21279 :::*/*/*/*/
[18:30:03.370] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 161.9calibrated Vbg = 1.21052 :::*/*/*/*/
[18:30:03.370] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 154.7calibrated Vbg = 1.21736 :::*/*/*/*/
[18:30:03.372] <TB1> INFO: ----------------------------------------------------------------------
[18:30:03.372] <TB1> INFO: PixTestReadback::CalibrateIa()
[18:30:03.372] <TB1> INFO: ----------------------------------------------------------------------
[18:32:38.578] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C0.dat
[18:32:38.579] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C1.dat
[18:32:38.579] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C2.dat
[18:32:38.579] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C3.dat
[18:32:38.579] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C4.dat
[18:32:38.579] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C5.dat
[18:32:38.579] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C6.dat
[18:32:38.579] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C7.dat
[18:32:38.579] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C8.dat
[18:32:38.579] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C9.dat
[18:32:38.579] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C10.dat
[18:32:38.579] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C11.dat
[18:32:38.579] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C12.dat
[18:32:38.579] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C13.dat
[18:32:38.580] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C14.dat
[18:32:38.580] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2153_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C15.dat
[18:32:38.610] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[18:32:38.610] <TB1> INFO: PixTestReadback::doTest() done
[18:32:38.610] <TB1> INFO: Decoding statistics:
[18:32:38.611] <TB1> INFO: General information:
[18:32:38.611] <TB1> INFO: 16bit words read: 1024
[18:32:38.611] <TB1> INFO: valid events total: 128
[18:32:38.611] <TB1> INFO: empty events: 128
[18:32:38.611] <TB1> INFO: valid events with pixels: 0
[18:32:38.611] <TB1> INFO: valid pixel hits: 0
[18:32:38.611] <TB1> INFO: Event errors: 0
[18:32:38.611] <TB1> INFO: start marker: 0
[18:32:38.611] <TB1> INFO: stop marker: 0
[18:32:38.611] <TB1> INFO: overflow: 0
[18:32:38.611] <TB1> INFO: invalid 5bit words: 0
[18:32:38.611] <TB1> INFO: invalid XOR eye diagram: 0
[18:32:38.611] <TB1> INFO: frame (failed synchr.): 0
[18:32:38.611] <TB1> INFO: idle data (no TBM trl): 0
[18:32:38.611] <TB1> INFO: no data (only TBM hdr): 0
[18:32:38.611] <TB1> INFO: TBM errors: 0
[18:32:38.611] <TB1> INFO: flawed TBM headers: 0
[18:32:38.611] <TB1> INFO: flawed TBM trailers: 0
[18:32:38.611] <TB1> INFO: event ID mismatches: 0
[18:32:38.611] <TB1> INFO: ROC errors: 0
[18:32:38.611] <TB1> INFO: missing ROC header(s): 0
[18:32:38.611] <TB1> INFO: misplaced readback start: 0
[18:32:38.611] <TB1> INFO: Pixel decoding errors: 0
[18:32:38.611] <TB1> INFO: pixel data incomplete: 0
[18:32:38.611] <TB1> INFO: pixel address: 0
[18:32:38.611] <TB1> INFO: pulse height fill bit: 0
[18:32:38.611] <TB1> INFO: buffer corruption: 0
[18:32:38.629] <TB1> INFO: Decoding statistics:
[18:32:38.629] <TB1> INFO: General information:
[18:32:38.629] <TB1> INFO: 16bit words read: 2755352
[18:32:38.629] <TB1> INFO: valid events total: 176768
[18:32:38.629] <TB1> INFO: empty events: 7866
[18:32:38.629] <TB1> INFO: valid events with pixels: 168902
[18:32:38.629] <TB1> INFO: valid pixel hits: 668031
[18:32:38.629] <TB1> INFO: Event errors: 0
[18:32:38.629] <TB1> INFO: start marker: 0
[18:32:38.629] <TB1> INFO: stop marker: 0
[18:32:38.630] <TB1> INFO: overflow: 0
[18:32:38.630] <TB1> INFO: invalid 5bit words: 0
[18:32:38.630] <TB1> INFO: invalid XOR eye diagram: 0
[18:32:38.630] <TB1> INFO: frame (failed synchr.): 0
[18:32:38.630] <TB1> INFO: idle data (no TBM trl): 0
[18:32:38.630] <TB1> INFO: no data (only TBM hdr): 0
[18:32:38.630] <TB1> INFO: TBM errors: 0
[18:32:38.630] <TB1> INFO: flawed TBM headers: 0
[18:32:38.630] <TB1> INFO: flawed TBM trailers: 0
[18:32:38.630] <TB1> INFO: event ID mismatches: 0
[18:32:38.630] <TB1> INFO: ROC errors: 0
[18:32:38.630] <TB1> INFO: missing ROC header(s): 0
[18:32:38.630] <TB1> INFO: misplaced readback start: 0
[18:32:38.630] <TB1> INFO: Pixel decoding errors: 0
[18:32:38.630] <TB1> INFO: pixel data incomplete: 0
[18:32:38.630] <TB1> INFO: pixel address: 0
[18:32:38.630] <TB1> INFO: pulse height fill bit: 0
[18:32:38.630] <TB1> INFO: buffer corruption: 0
[18:32:38.630] <TB1> INFO: enter test to run
[18:32:38.630] <TB1> INFO: test: exit no parameter change
[18:32:38.823] <TB1> QUIET: Connection to board 153 closed.
[18:32:38.902] <TB1> INFO: pXar: this is the end, my friend