[08:09:18.943] <TB3> INFO: *** Welcome to pxar ***
[08:09:18.943] <TB3> INFO: *** Today: 2016/09/08
[08:09:19.039] <TB3> INFO: *** Version: fe94
[08:09:19.039] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C15.dat
[08:09:19.040] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//tbmParameters_C0b.dat
[08:09:19.041] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//defaultMaskFile.dat
[08:09:19.041] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters_C15.dat
[08:09:19.116] <TB3> INFO: clk: 4
[08:09:19.116] <TB3> INFO: ctr: 4
[08:09:19.116] <TB3> INFO: sda: 19
[08:09:19.116] <TB3> INFO: tin: 9
[08:09:19.116] <TB3> INFO: level: 15
[08:09:19.116] <TB3> INFO: triggerdelay: 0
[08:09:19.116] <TB3> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[08:09:19.116] <TB3> INFO: Log level: INFO
[08:09:19.123] <TB3> INFO: Found DTB DTB_WZ4I6J
[08:09:19.131] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[08:09:19.133] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[08:09:19.135] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[08:09:20.683] <TB3> INFO: DUT info:
[08:09:20.683] <TB3> INFO: The DUT currently contains the following objects:
[08:09:20.683] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[08:09:20.683] <TB3> INFO: TBM Core alpha (0): 7 registers set
[08:09:20.683] <TB3> INFO: TBM Core beta (1): 7 registers set
[08:09:20.683] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:09:20.683] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:20.683] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:20.683] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:20.684] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:20.684] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:20.684] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:20.684] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:20.684] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:20.684] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:20.684] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:20.684] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:20.684] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:20.684] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:20.684] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:20.684] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:20.684] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:09:21.085] <TB3> INFO: enter 'restricted' command line mode
[08:09:21.085] <TB3> INFO: enter test to run
[08:09:21.085] <TB3> INFO: test: pretest no parameter change
[08:09:21.085] <TB3> INFO: running: pretest
[08:09:21.091] <TB3> INFO: ----------------------------------------------------------------------
[08:09:21.091] <TB3> INFO: PixTestPretest::programROC()
[08:09:21.091] <TB3> INFO: ----------------------------------------------------------------------
[08:09:39.104] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[08:09:39.104] <TB3> INFO: IA differences per ROC: 18.5 20.1 17.7 21.7 20.1 20.1 20.1 17.7 19.3 19.3 20.9 21.7 19.3 19.3 18.5 20.1
[08:09:39.198] <TB3> INFO: enter test to run
[08:09:39.198] <TB3> INFO: test: pretest no parameter change
[08:09:39.198] <TB3> INFO: running: pretest
[08:09:39.199] <TB3> INFO: ----------------------------------------------------------------------
[08:09:39.199] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[08:09:39.199] <TB3> INFO: ----------------------------------------------------------------------
[08:09:46.287] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 393.9 mA = 24.6187 mA/ROC
[08:09:46.287] <TB3> INFO: i(loss) [mA/ROC]: 20.1 19.3 19.3 19.3 19.3 19.3 19.3 18.5 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3
[08:09:46.308] <TB3> INFO: enter test to run
[08:09:46.308] <TB3> INFO: test: pretest no parameter change
[08:09:46.308] <TB3> INFO: running: pretest
[08:09:46.308] <TB3> INFO: ----------------------------------------------------------------------
[08:09:46.308] <TB3> INFO: PixTestPretest::findTiming()
[08:09:46.308] <TB3> INFO: ----------------------------------------------------------------------
[08:09:46.308] <TB3> INFO: PixTestCmd::init()

[08:11:15.229] <TB3> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[08:11:15.229] <TB3> INFO: (success/tries = 100/100), width = 5
[08:11:15.229] <TB3> INFO: enter test to run
[08:11:15.229] <TB3> INFO: test: pretest no parameter change
[08:11:15.229] <TB3> INFO: running: pretest
[08:11:15.230] <TB3> INFO: ----------------------------------------------------------------------
[08:11:15.230] <TB3> INFO: PixTestPretest::findWorkingPixel()
[08:11:15.230] <TB3> INFO: ----------------------------------------------------------------------
[08:11:15.321] <TB3> INFO: Expecting 231680 events.


[08:11:25.467] <TB3> INFO: 231680 events read in total (9590ms).
[08:11:25.471] <TB3> INFO: Test took 10238ms.
[08:11:25.692] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[08:11:25.757] <TB3> INFO: enter test to run
[08:11:25.757] <TB3> INFO: test: pretest no parameter change
[08:11:25.757] <TB3> INFO: running: pretest
[08:11:25.758] <TB3> INFO: ----------------------------------------------------------------------
[08:11:25.758] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[08:11:25.758] <TB3> INFO: ----------------------------------------------------------------------
[08:11:25.850] <TB3> INFO: Expecting 231680 events.
[08:11:36.041] <TB3> INFO: 231680 events read in total (9635ms).
[08:11:36.045] <TB3> INFO: Test took 10283ms.
[08:11:36.296] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[08:11:36.296] <TB3> INFO: CalDel: 154 143 153 143 145 154 148 156 153 123 151 158 133 152 123 139
[08:11:36.296] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[08:11:36.330] <TB3> INFO: enter test to run
[08:11:36.330] <TB3> INFO: test: pretest no parameter change
[08:11:36.330] <TB3> INFO: running: pretest
[08:11:36.331] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C0.dat
[08:11:36.331] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C1.dat
[08:11:36.331] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C2.dat
[08:11:36.331] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C3.dat
[08:11:36.331] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C4.dat
[08:11:36.331] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C5.dat
[08:11:36.331] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C6.dat
[08:11:36.331] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C7.dat
[08:11:36.331] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C8.dat
[08:11:36.331] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C9.dat
[08:11:36.331] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C10.dat
[08:11:36.332] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C11.dat
[08:11:36.332] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C12.dat
[08:11:36.332] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C13.dat
[08:11:36.332] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C14.dat
[08:11:36.332] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters_C15.dat
[08:11:36.332] <TB3> INFO: enter test to run
[08:11:36.332] <TB3> INFO: test: fulltest no parameter change
[08:11:36.332] <TB3> INFO: running: fulltest
[08:11:36.332] <TB3> INFO: ######################################################################
[08:11:36.332] <TB3> INFO: PixTestFullTest::doTest()
[08:11:36.332] <TB3> INFO: ######################################################################
[08:11:36.333] <TB3> INFO: ######################################################################
[08:11:36.333] <TB3> INFO: PixTestAlive::doTest()
[08:11:36.333] <TB3> INFO: ######################################################################
[08:11:36.335] <TB3> INFO: ----------------------------------------------------------------------
[08:11:36.335] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:11:36.335] <TB3> INFO: ----------------------------------------------------------------------
[08:11:36.600] <TB3> INFO: Expecting 41600 events.
[08:11:40.382] <TB3> INFO: 41600 events read in total (3225ms).
[08:11:40.383] <TB3> INFO: Test took 4047ms.
[08:11:40.620] <TB3> INFO: PixTestAlive::aliveTest() done
[08:11:40.620] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 2 0 0 0 0 0 0 0 0 0 0
[08:11:40.622] <TB3> INFO: ----------------------------------------------------------------------
[08:11:40.622] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:11:40.622] <TB3> INFO: ----------------------------------------------------------------------
[08:11:40.918] <TB3> INFO: Expecting 41600 events.
[08:11:43.866] <TB3> INFO: 41600 events read in total (2392ms).
[08:11:43.866] <TB3> INFO: Test took 3242ms.
[08:11:43.868] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:11:44.111] <TB3> INFO: PixTestAlive::maskTest() done
[08:11:44.111] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:11:44.112] <TB3> INFO: ----------------------------------------------------------------------
[08:11:44.112] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:11:44.112] <TB3> INFO: ----------------------------------------------------------------------
[08:11:44.377] <TB3> INFO: Expecting 41600 events.
[08:11:48.111] <TB3> INFO: 41600 events read in total (3178ms).
[08:11:48.112] <TB3> INFO: Test took 3999ms.
[08:11:48.334] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[08:11:48.334] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:11:48.334] <TB3> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[08:11:48.334] <TB3> INFO: Decoding statistics:
[08:11:48.334] <TB3> INFO: General information:
[08:11:48.334] <TB3> INFO: 16bit words read: 0
[08:11:48.334] <TB3> INFO: valid events total: 0
[08:11:48.334] <TB3> INFO: empty events: 0
[08:11:48.334] <TB3> INFO: valid events with pixels: 0
[08:11:48.334] <TB3> INFO: valid pixel hits: 0
[08:11:48.334] <TB3> INFO: Event errors: 0
[08:11:48.334] <TB3> INFO: start marker: 0
[08:11:48.335] <TB3> INFO: stop marker: 0
[08:11:48.335] <TB3> INFO: overflow: 0
[08:11:48.335] <TB3> INFO: invalid 5bit words: 0
[08:11:48.335] <TB3> INFO: invalid XOR eye diagram: 0
[08:11:48.335] <TB3> INFO: frame (failed synchr.): 0
[08:11:48.335] <TB3> INFO: idle data (no TBM trl): 0
[08:11:48.335] <TB3> INFO: no data (only TBM hdr): 0
[08:11:48.335] <TB3> INFO: TBM errors: 0
[08:11:48.335] <TB3> INFO: flawed TBM headers: 0
[08:11:48.335] <TB3> INFO: flawed TBM trailers: 0
[08:11:48.335] <TB3> INFO: event ID mismatches: 0
[08:11:48.335] <TB3> INFO: ROC errors: 0
[08:11:48.335] <TB3> INFO: missing ROC header(s): 0
[08:11:48.335] <TB3> INFO: misplaced readback start: 0
[08:11:48.335] <TB3> INFO: Pixel decoding errors: 0
[08:11:48.335] <TB3> INFO: pixel data incomplete: 0
[08:11:48.335] <TB3> INFO: pixel address: 0
[08:11:48.335] <TB3> INFO: pulse height fill bit: 0
[08:11:48.335] <TB3> INFO: buffer corruption: 0
[08:11:48.343] <TB3> INFO: ######################################################################
[08:11:48.343] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[08:11:48.343] <TB3> INFO: ######################################################################
[08:11:48.345] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[08:11:48.356] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[08:11:48.356] <TB3> INFO: run 1 of 1
[08:11:48.615] <TB3> INFO: Expecting 3120000 events.
[08:12:29.411] <TB3> INFO: 850085 events read in total (40240ms).
[08:13:10.284] <TB3> INFO: 1689585 events read in total (81114ms).
[08:13:50.731] <TB3> INFO: 2539820 events read in total (121561ms).
[08:14:19.405] <TB3> INFO: 3120000 events read in total (150234ms).
[08:14:19.477] <TB3> INFO: Test took 151121ms.
[08:14:44.161] <TB3> INFO: PixTestBBMap::doTest() done with 1 decoding errors: , duration: 175 seconds
[08:14:44.161] <TB3> INFO: number of dead bumps (per ROC): 0 0 0 0 0 2 1 0 0 0 1 2 1 1 0 1
[08:14:44.161] <TB3> INFO: separation cut (per ROC): 70 84 84 80 74 81 97 65 66 83 98 77 82 83 84 77
[08:14:44.162] <TB3> INFO: Decoding statistics:
[08:14:44.162] <TB3> INFO: General information:
[08:14:44.162] <TB3> INFO: 16bit words read: 0
[08:14:44.162] <TB3> INFO: valid events total: 0
[08:14:44.162] <TB3> INFO: empty events: 0
[08:14:44.162] <TB3> INFO: valid events with pixels: 0
[08:14:44.162] <TB3> INFO: valid pixel hits: 0
[08:14:44.162] <TB3> INFO: Event errors: 0
[08:14:44.162] <TB3> INFO: start marker: 0
[08:14:44.162] <TB3> INFO: stop marker: 0
[08:14:44.162] <TB3> INFO: overflow: 0
[08:14:44.162] <TB3> INFO: invalid 5bit words: 0
[08:14:44.162] <TB3> INFO: invalid XOR eye diagram: 0
[08:14:44.162] <TB3> INFO: frame (failed synchr.): 0
[08:14:44.162] <TB3> INFO: idle data (no TBM trl): 0
[08:14:44.162] <TB3> INFO: no data (only TBM hdr): 0
[08:14:44.162] <TB3> INFO: TBM errors: 0
[08:14:44.162] <TB3> INFO: flawed TBM headers: 0
[08:14:44.162] <TB3> INFO: flawed TBM trailers: 0
[08:14:44.162] <TB3> INFO: event ID mismatches: 0
[08:14:44.162] <TB3> INFO: ROC errors: 0
[08:14:44.162] <TB3> INFO: missing ROC header(s): 0
[08:14:44.162] <TB3> INFO: misplaced readback start: 0
[08:14:44.162] <TB3> INFO: Pixel decoding errors: 0
[08:14:44.162] <TB3> INFO: pixel data incomplete: 0
[08:14:44.162] <TB3> INFO: pixel address: 0
[08:14:44.162] <TB3> INFO: pulse height fill bit: 0
[08:14:44.162] <TB3> INFO: buffer corruption: 0
[08:14:44.236] <TB3> INFO: ######################################################################
[08:14:44.236] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:14:44.236] <TB3> INFO: ######################################################################
[08:14:44.236] <TB3> INFO: ----------------------------------------------------------------------
[08:14:44.236] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:14:44.236] <TB3> INFO: ----------------------------------------------------------------------
[08:14:44.236] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[08:14:44.244] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[08:14:44.244] <TB3> INFO: run 1 of 1
[08:14:44.503] <TB3> INFO: Expecting 26208000 events.
[08:15:18.004] <TB3> INFO: 878600 events read in total (32944ms).
[08:15:51.035] <TB3> INFO: 1743200 events read in total (65975ms).
[08:16:23.560] <TB3> INFO: 2605650 events read in total (98500ms).
[08:16:56.363] <TB3> INFO: 3467400 events read in total (131303ms).
[08:17:28.890] <TB3> INFO: 4327250 events read in total (163830ms).
[08:18:01.557] <TB3> INFO: 5186450 events read in total (196497ms).
[08:18:34.086] <TB3> INFO: 6046900 events read in total (229026ms).
[08:19:06.925] <TB3> INFO: 6905850 events read in total (261865ms).
[08:19:39.538] <TB3> INFO: 7766150 events read in total (294478ms).
[08:20:12.704] <TB3> INFO: 8626500 events read in total (327644ms).
[08:20:45.308] <TB3> INFO: 9485300 events read in total (360248ms).
[08:21:17.973] <TB3> INFO: 10342800 events read in total (392913ms).
[08:21:50.553] <TB3> INFO: 11200800 events read in total (425493ms).
[08:22:23.612] <TB3> INFO: 12057600 events read in total (458552ms).
[08:22:56.226] <TB3> INFO: 12913800 events read in total (491166ms).
[08:23:29.263] <TB3> INFO: 13764100 events read in total (524203ms).
[08:24:02.125] <TB3> INFO: 14612950 events read in total (557065ms).
[08:24:34.893] <TB3> INFO: 15460900 events read in total (589833ms).
[08:25:07.556] <TB3> INFO: 16307300 events read in total (622496ms).
[08:25:39.802] <TB3> INFO: 17153050 events read in total (654742ms).
[08:26:12.602] <TB3> INFO: 17997650 events read in total (687542ms).
[08:26:45.128] <TB3> INFO: 18843100 events read in total (720068ms).
[08:27:18.102] <TB3> INFO: 19686900 events read in total (753042ms).
[08:27:50.736] <TB3> INFO: 20530800 events read in total (785676ms).
[08:28:23.416] <TB3> INFO: 21375500 events read in total (818356ms).
[08:28:55.782] <TB3> INFO: 22219000 events read in total (850722ms).
[08:29:28.058] <TB3> INFO: 23063050 events read in total (882998ms).
[08:30:00.694] <TB3> INFO: 23908200 events read in total (915634ms).
[08:30:33.268] <TB3> INFO: 24752450 events read in total (948208ms).
[08:31:05.568] <TB3> INFO: 25595900 events read in total (980508ms).
[08:31:29.243] <TB3> INFO: 26208000 events read in total (1004183ms).
[08:31:29.279] <TB3> INFO: Test took 1005035ms.
[08:31:29.538] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:31:31.082] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:31:32.489] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:31:33.936] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:31:35.469] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:31:37.157] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:31:38.629] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:31:40.245] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:31:41.794] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:31:43.386] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:31:44.856] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:31:46.306] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:31:47.784] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:31:49.293] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:31:50.784] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:31:52.183] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:31:53.610] <TB3> INFO: PixTestScurves::scurves() done
[08:31:53.610] <TB3> INFO: Vcal mean: 76.91 86.83 83.46 79.86 78.18 86.41 101.38 76.26 76.76 87.42 89.80 81.88 77.59 80.94 82.04 82.91
[08:31:53.610] <TB3> INFO: Vcal RMS: 4.46 5.14 4.88 4.18 4.05 5.32 5.41 4.43 4.47 5.42 5.58 4.29 3.77 4.32 4.52 4.21
[08:31:53.610] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1029 seconds
[08:31:53.610] <TB3> INFO: Decoding statistics:
[08:31:53.610] <TB3> INFO: General information:
[08:31:53.610] <TB3> INFO: 16bit words read: 0
[08:31:53.610] <TB3> INFO: valid events total: 0
[08:31:53.610] <TB3> INFO: empty events: 0
[08:31:53.610] <TB3> INFO: valid events with pixels: 0
[08:31:53.610] <TB3> INFO: valid pixel hits: 0
[08:31:53.610] <TB3> INFO: Event errors: 0
[08:31:53.610] <TB3> INFO: start marker: 0
[08:31:53.610] <TB3> INFO: stop marker: 0
[08:31:53.610] <TB3> INFO: overflow: 0
[08:31:53.610] <TB3> INFO: invalid 5bit words: 0
[08:31:53.610] <TB3> INFO: invalid XOR eye diagram: 0
[08:31:53.610] <TB3> INFO: frame (failed synchr.): 0
[08:31:53.610] <TB3> INFO: idle data (no TBM trl): 0
[08:31:53.610] <TB3> INFO: no data (only TBM hdr): 0
[08:31:53.610] <TB3> INFO: TBM errors: 0
[08:31:53.610] <TB3> INFO: flawed TBM headers: 0
[08:31:53.610] <TB3> INFO: flawed TBM trailers: 0
[08:31:53.610] <TB3> INFO: event ID mismatches: 0
[08:31:53.610] <TB3> INFO: ROC errors: 0
[08:31:53.610] <TB3> INFO: missing ROC header(s): 0
[08:31:53.610] <TB3> INFO: misplaced readback start: 0
[08:31:53.610] <TB3> INFO: Pixel decoding errors: 0
[08:31:53.610] <TB3> INFO: pixel data incomplete: 0
[08:31:53.610] <TB3> INFO: pixel address: 0
[08:31:53.610] <TB3> INFO: pulse height fill bit: 0
[08:31:53.610] <TB3> INFO: buffer corruption: 0
[08:31:53.687] <TB3> INFO: ######################################################################
[08:31:53.687] <TB3> INFO: PixTestTrim::doTest()
[08:31:53.687] <TB3> INFO: ######################################################################
[08:31:53.688] <TB3> INFO: ----------------------------------------------------------------------
[08:31:53.688] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[08:31:53.688] <TB3> INFO: ----------------------------------------------------------------------
[08:31:53.766] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[08:31:53.766] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[08:31:53.775] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[08:31:53.775] <TB3> INFO: run 1 of 1
[08:31:54.039] <TB3> INFO: Expecting 6281600 events.
[08:32:33.575] <TB3> INFO: 1065440 events read in total (38978ms).
[08:33:12.262] <TB3> INFO: 2122610 events read in total (77665ms).
[08:33:50.886] <TB3> INFO: 3173900 events read in total (116289ms).
[08:34:29.544] <TB3> INFO: 4233310 events read in total (154947ms).
[08:35:08.147] <TB3> INFO: 5295440 events read in total (193550ms).
[08:35:44.691] <TB3> INFO: 6281600 events read in total (230094ms).
[08:35:44.728] <TB3> INFO: Test took 230954ms.
[08:36:04.775] <TB3> INFO: ROC 0 VthrComp = 81
[08:36:04.775] <TB3> INFO: ROC 1 VthrComp = 93
[08:36:04.775] <TB3> INFO: ROC 2 VthrComp = 89
[08:36:04.775] <TB3> INFO: ROC 3 VthrComp = 90
[08:36:04.775] <TB3> INFO: ROC 4 VthrComp = 84
[08:36:04.775] <TB3> INFO: ROC 5 VthrComp = 90
[08:36:04.775] <TB3> INFO: ROC 6 VthrComp = 104
[08:36:04.775] <TB3> INFO: ROC 7 VthrComp = 77
[08:36:04.775] <TB3> INFO: ROC 8 VthrComp = 84
[08:36:04.776] <TB3> INFO: ROC 9 VthrComp = 90
[08:36:04.776] <TB3> INFO: ROC 10 VthrComp = 97
[08:36:04.776] <TB3> INFO: ROC 11 VthrComp = 89
[08:36:04.776] <TB3> INFO: ROC 12 VthrComp = 88
[08:36:04.776] <TB3> INFO: ROC 13 VthrComp = 89
[08:36:04.776] <TB3> INFO: ROC 14 VthrComp = 89
[08:36:04.776] <TB3> INFO: ROC 15 VthrComp = 92
[08:36:04.776] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[08:36:04.776] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[08:36:04.784] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[08:36:04.784] <TB3> INFO: run 1 of 1
[08:36:05.054] <TB3> INFO: Expecting 6281600 events.
[08:36:40.401] <TB3> INFO: 755460 events read in total (34790ms).
[08:37:15.159] <TB3> INFO: 1508590 events read in total (69549ms).
[08:37:49.722] <TB3> INFO: 2262090 events read in total (104111ms).
[08:38:24.153] <TB3> INFO: 3015690 events read in total (138542ms).
[08:38:59.010] <TB3> INFO: 3764300 events read in total (173399ms).
[08:39:33.479] <TB3> INFO: 4509450 events read in total (207868ms).
[08:40:07.922] <TB3> INFO: 5252590 events read in total (242311ms).
[08:40:42.194] <TB3> INFO: 5995280 events read in total (276583ms).
[08:40:55.684] <TB3> INFO: 6281600 events read in total (290073ms).
[08:40:55.730] <TB3> INFO: Test took 290946ms.
[08:41:19.003] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 57.6244 for pixel 2/14 mean/min/max = 44.8043/31.9586/57.65
[08:41:19.008] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 58.8462 for pixel 1/8 mean/min/max = 45.6689/32.4089/58.9289
[08:41:19.008] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 58.3263 for pixel 8/0 mean/min/max = 45.3058/32.2421/58.3696
[08:41:19.009] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 56.1466 for pixel 0/10 mean/min/max = 44.6186/32.7792/56.4581
[08:41:19.009] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 56.4534 for pixel 6/73 mean/min/max = 44.1969/31.9147/56.4791
[08:41:19.009] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 58.5134 for pixel 0/77 mean/min/max = 45.584/32.6466/58.5213
[08:41:19.009] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 60.2833 for pixel 17/5 mean/min/max = 47.0609/33.8268/60.2951
[08:41:19.010] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 60.1079 for pixel 12/28 mean/min/max = 47.5999/35.0178/60.182
[08:41:19.010] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 56.6634 for pixel 0/2 mean/min/max = 44.3257/31.9239/56.7276
[08:41:19.010] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 59.0361 for pixel 0/54 mean/min/max = 46.0975/33.0713/59.1238
[08:41:19.010] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 58.3187 for pixel 22/1 mean/min/max = 45.1818/31.6198/58.7439
[08:41:19.010] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 57.3878 for pixel 7/9 mean/min/max = 45.0745/32.6705/57.4784
[08:41:19.011] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 54.7803 for pixel 0/6 mean/min/max = 44.0857/32.9822/55.1893
[08:41:19.011] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 56.6097 for pixel 14/79 mean/min/max = 44.6766/32.6646/56.6886
[08:41:19.011] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 58.302 for pixel 0/19 mean/min/max = 45.5406/32.7407/58.3405
[08:41:19.011] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 55.8421 for pixel 2/79 mean/min/max = 44.1369/32.2857/55.988
[08:41:19.012] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:41:19.100] <TB3> INFO: Expecting 514560 events.
[08:41:32.075] <TB3> INFO: 514560 events read in total (12419ms).
[08:41:32.080] <TB3> INFO: Expecting 514560 events.
[08:41:45.074] <TB3> INFO: 514560 events read in total (12573ms).
[08:41:45.083] <TB3> INFO: Expecting 514560 events.
[08:41:58.055] <TB3> INFO: 514560 events read in total (12563ms).
[08:41:58.065] <TB3> INFO: Expecting 514560 events.
[08:42:10.892] <TB3> INFO: 514560 events read in total (12420ms).
[08:42:10.903] <TB3> INFO: Expecting 514560 events.
[08:42:23.587] <TB3> INFO: 514560 events read in total (12272ms).
[08:42:23.600] <TB3> INFO: Expecting 514560 events.
[08:42:36.261] <TB3> INFO: 514560 events read in total (12246ms).
[08:42:36.279] <TB3> INFO: Expecting 514560 events.
[08:42:49.005] <TB3> INFO: 514560 events read in total (12313ms).
[08:42:49.024] <TB3> INFO: Expecting 514560 events.
[08:43:01.751] <TB3> INFO: 514560 events read in total (12322ms).
[08:43:01.778] <TB3> INFO: Expecting 514560 events.
[08:43:14.395] <TB3> INFO: 514560 events read in total (12216ms).
[08:43:14.415] <TB3> INFO: Expecting 514560 events.
[08:43:27.037] <TB3> INFO: 514560 events read in total (12213ms).
[08:43:27.060] <TB3> INFO: Expecting 514560 events.
[08:43:40.023] <TB3> INFO: 514560 events read in total (12557ms).
[08:43:40.048] <TB3> INFO: Expecting 514560 events.
[08:43:53.006] <TB3> INFO: 514560 events read in total (12556ms).
[08:43:53.032] <TB3> INFO: Expecting 514560 events.
[08:44:05.811] <TB3> INFO: 514560 events read in total (12377ms).
[08:44:05.840] <TB3> INFO: Expecting 514560 events.
[08:44:18.726] <TB3> INFO: 514560 events read in total (12484ms).
[08:44:18.755] <TB3> INFO: Expecting 514560 events.
[08:44:31.533] <TB3> INFO: 514560 events read in total (12377ms).
[08:44:31.567] <TB3> INFO: Expecting 514560 events.
[08:44:44.237] <TB3> INFO: 514560 events read in total (12268ms).
[08:44:44.269] <TB3> INFO: Test took 205257ms.
[08:44:45.267] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[08:44:45.275] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[08:44:45.275] <TB3> INFO: run 1 of 1
[08:44:45.538] <TB3> INFO: Expecting 6281600 events.
[08:45:20.853] <TB3> INFO: 744360 events read in total (34759ms).
[08:45:55.605] <TB3> INFO: 1486940 events read in total (69511ms).
[08:46:30.427] <TB3> INFO: 2229840 events read in total (104333ms).
[08:47:04.750] <TB3> INFO: 2972700 events read in total (138656ms).
[08:47:39.094] <TB3> INFO: 3710030 events read in total (173000ms).
[08:48:13.433] <TB3> INFO: 4443330 events read in total (207339ms).
[08:48:47.611] <TB3> INFO: 5174500 events read in total (241517ms).
[08:49:22.216] <TB3> INFO: 5904200 events read in total (276122ms).
[08:49:40.383] <TB3> INFO: 6281600 events read in total (294289ms).
[08:49:40.426] <TB3> INFO: Test took 295150ms.
[08:50:04.842] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 2.024126 .. 255.000000
[08:50:04.917] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 2 .. 255 (-1/-1) hits flags = 528 (plus default)
[08:50:04.925] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[08:50:04.925] <TB3> INFO: run 1 of 1
[08:50:05.185] <TB3> INFO: Expecting 10566400 events.
[08:50:39.291] <TB3> INFO: 709860 events read in total (33549ms).
[08:51:12.872] <TB3> INFO: 1419780 events read in total (67130ms).
[08:51:46.818] <TB3> INFO: 2129990 events read in total (101076ms).
[08:52:20.874] <TB3> INFO: 2840090 events read in total (135132ms).
[08:52:55.129] <TB3> INFO: 3550060 events read in total (169387ms).
[08:53:29.153] <TB3> INFO: 4260300 events read in total (203411ms).
[08:54:03.184] <TB3> INFO: 4970380 events read in total (237442ms).
[08:54:36.950] <TB3> INFO: 5680770 events read in total (271208ms).
[08:55:10.963] <TB3> INFO: 6390490 events read in total (305221ms).
[08:55:44.858] <TB3> INFO: 7099920 events read in total (339116ms).
[08:56:19.025] <TB3> INFO: 7809040 events read in total (373283ms).
[08:56:52.854] <TB3> INFO: 8517570 events read in total (407112ms).
[08:57:26.410] <TB3> INFO: 9225890 events read in total (440668ms).
[08:57:59.871] <TB3> INFO: 9933940 events read in total (474129ms).
[08:58:30.811] <TB3> INFO: 10566400 events read in total (505069ms).
[08:58:30.913] <TB3> INFO: Test took 505988ms.
[08:58:59.338] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 9.784604 .. 45.389771
[08:58:59.413] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 9 .. 55 (-1/-1) hits flags = 528 (plus default)
[08:58:59.421] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[08:58:59.421] <TB3> INFO: run 1 of 1
[08:58:59.683] <TB3> INFO: Expecting 1955200 events.
[08:59:37.529] <TB3> INFO: 916320 events read in total (37289ms).
[09:00:14.542] <TB3> INFO: 1830030 events read in total (74302ms).
[09:00:20.005] <TB3> INFO: 1955200 events read in total (79765ms).
[09:00:20.025] <TB3> INFO: Test took 80604ms.
[09:00:35.288] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 9.784604 .. 42.359975
[09:00:35.367] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 9 .. 52 (-1/-1) hits flags = 528 (plus default)
[09:00:35.376] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:00:35.376] <TB3> INFO: run 1 of 1
[09:00:35.638] <TB3> INFO: Expecting 1830400 events.
[09:01:14.330] <TB3> INFO: 943410 events read in total (38135ms).
[09:01:49.874] <TB3> INFO: 1830400 events read in total (73679ms).
[09:01:49.890] <TB3> INFO: Test took 74515ms.
[09:02:03.561] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 3.050806 .. 42.359975
[09:02:03.640] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 3 .. 52 (-1/-1) hits flags = 528 (plus default)
[09:02:03.649] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:02:03.649] <TB3> INFO: run 1 of 1
[09:02:03.946] <TB3> INFO: Expecting 2080000 events.
[09:02:42.603] <TB3> INFO: 977400 events read in total (38101ms).
[09:03:20.522] <TB3> INFO: 1953980 events read in total (76020ms).
[09:03:25.697] <TB3> INFO: 2080000 events read in total (81196ms).
[09:03:25.709] <TB3> INFO: Test took 82061ms.
[09:03:39.246] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[09:03:39.246] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:03:39.254] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[09:03:39.254] <TB3> INFO: run 1 of 1
[09:03:39.515] <TB3> INFO: Expecting 1705600 events.
[09:04:17.305] <TB3> INFO: 880060 events read in total (37234ms).
[09:04:51.963] <TB3> INFO: 1705600 events read in total (71892ms).
[09:04:51.976] <TB3> INFO: Test took 72723ms.
[09:05:05.878] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:05:05.878] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:05:05.879] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:05:05.879] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:05:05.879] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:05:05.879] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:05:05.879] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:05:05.879] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:05:05.879] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:05:05.879] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:05:05.879] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:05:05.879] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:05:05.880] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:05:05.880] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:05:05.880] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:05:05.880] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:05:05.880] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C0.dat
[09:05:05.886] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C1.dat
[09:05:05.892] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C2.dat
[09:05:05.898] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C3.dat
[09:05:05.904] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C4.dat
[09:05:05.910] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C5.dat
[09:05:05.916] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C6.dat
[09:05:05.922] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C7.dat
[09:05:05.928] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C8.dat
[09:05:05.934] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C9.dat
[09:05:05.940] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C10.dat
[09:05:05.946] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C11.dat
[09:05:05.952] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C12.dat
[09:05:05.958] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C13.dat
[09:05:05.964] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C14.dat
[09:05:05.970] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//trimParameters35_C15.dat
[09:05:05.976] <TB3> INFO: PixTestTrim::trimTest() done
[09:05:05.976] <TB3> INFO: vtrim: 100 113 115 100 91 96 107 102 86 96 99 105 81 86 101 90
[09:05:05.976] <TB3> INFO: vthrcomp: 81 93 89 90 84 90 104 77 84 90 97 89 88 89 89 92
[09:05:05.976] <TB3> INFO: vcal mean: 35.00 34.97 34.96 35.01 34.92 34.95 34.99 34.97 34.93 35.00 34.98 34.99 34.99 34.99 34.98 34.95
[09:05:05.976] <TB3> INFO: vcal RMS: 0.80 0.85 0.82 0.75 0.79 1.03 0.83 0.83 0.80 0.83 0.82 0.92 0.74 0.79 0.80 0.80
[09:05:05.976] <TB3> INFO: bits mean: 9.61 9.57 9.59 9.05 9.66 9.29 8.69 8.56 9.22 8.85 9.57 9.65 9.57 9.62 9.18 9.66
[09:05:05.976] <TB3> INFO: bits RMS: 2.69 2.59 2.63 2.85 2.68 2.72 2.72 2.56 2.87 2.80 2.76 2.56 2.60 2.60 2.74 2.64
[09:05:05.982] <TB3> INFO: ----------------------------------------------------------------------
[09:05:05.982] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[09:05:05.982] <TB3> INFO: ----------------------------------------------------------------------
[09:05:05.984] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[09:05:05.992] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[09:05:05.992] <TB3> INFO: run 1 of 1
[09:05:06.253] <TB3> INFO: Expecting 4160000 events.
[09:05:48.977] <TB3> INFO: 906890 events read in total (42167ms).
[09:06:29.784] <TB3> INFO: 1808680 events read in total (82974ms).
[09:07:11.003] <TB3> INFO: 2702925 events read in total (124193ms).
[09:07:51.496] <TB3> INFO: 3593975 events read in total (164686ms).
[09:08:17.431] <TB3> INFO: 4160000 events read in total (190621ms).
[09:08:17.472] <TB3> INFO: Test took 191479ms.
[09:08:46.479] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 159 (-1/-1) hits flags = 528 (plus default)
[09:08:46.487] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[09:08:46.487] <TB3> INFO: run 1 of 1
[09:08:46.750] <TB3> INFO: Expecting 3328000 events.
[09:09:29.898] <TB3> INFO: 954445 events read in total (42592ms).
[09:10:11.550] <TB3> INFO: 1899225 events read in total (84244ms).
[09:10:53.171] <TB3> INFO: 2833685 events read in total (125865ms).
[09:11:15.538] <TB3> INFO: 3328000 events read in total (148232ms).
[09:11:15.575] <TB3> INFO: Test took 149087ms.
[09:11:42.043] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 151 (-1/-1) hits flags = 528 (plus default)
[09:11:42.052] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[09:11:42.052] <TB3> INFO: run 1 of 1
[09:11:42.339] <TB3> INFO: Expecting 3161600 events.
[09:12:25.735] <TB3> INFO: 975915 events read in total (42840ms).
[09:13:08.739] <TB3> INFO: 1939510 events read in total (85844ms).
[09:13:51.110] <TB3> INFO: 2893820 events read in total (128215ms).
[09:14:03.156] <TB3> INFO: 3161600 events read in total (140261ms).
[09:14:03.185] <TB3> INFO: Test took 141133ms.
[09:14:28.837] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 151 (-1/-1) hits flags = 528 (plus default)
[09:14:28.849] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[09:14:28.849] <TB3> INFO: run 1 of 1
[09:14:29.143] <TB3> INFO: Expecting 3161600 events.
[09:15:12.214] <TB3> INFO: 974990 events read in total (42514ms).
[09:15:54.487] <TB3> INFO: 1937540 events read in total (84787ms).
[09:16:36.411] <TB3> INFO: 2890815 events read in total (126711ms).
[09:16:48.471] <TB3> INFO: 3161600 events read in total (138771ms).
[09:16:48.503] <TB3> INFO: Test took 139654ms.
[09:17:12.311] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:17:12.319] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[09:17:12.319] <TB3> INFO: run 1 of 1
[09:17:12.579] <TB3> INFO: Expecting 3140800 events.
[09:17:55.152] <TB3> INFO: 976890 events read in total (42016ms).
[09:18:37.174] <TB3> INFO: 1940985 events read in total (84038ms).
[09:19:19.307] <TB3> INFO: 2895910 events read in total (126171ms).
[09:19:30.240] <TB3> INFO: 3140800 events read in total (137104ms).
[09:19:30.272] <TB3> INFO: Test took 137953ms.
[09:19:54.907] <TB3> INFO: PixTestTrim::trimBitTest() done
[09:19:54.908] <TB3> INFO: PixTestTrim::doTest() done, duration: 2881 seconds
[09:19:54.908] <TB3> INFO: Decoding statistics:
[09:19:54.908] <TB3> INFO: General information:
[09:19:54.908] <TB3> INFO: 16bit words read: 0
[09:19:54.908] <TB3> INFO: valid events total: 0
[09:19:54.908] <TB3> INFO: empty events: 0
[09:19:54.908] <TB3> INFO: valid events with pixels: 0
[09:19:54.908] <TB3> INFO: valid pixel hits: 0
[09:19:54.908] <TB3> INFO: Event errors: 0
[09:19:54.908] <TB3> INFO: start marker: 0
[09:19:54.908] <TB3> INFO: stop marker: 0
[09:19:54.908] <TB3> INFO: overflow: 0
[09:19:54.908] <TB3> INFO: invalid 5bit words: 0
[09:19:54.908] <TB3> INFO: invalid XOR eye diagram: 0
[09:19:54.909] <TB3> INFO: frame (failed synchr.): 0
[09:19:54.909] <TB3> INFO: idle data (no TBM trl): 0
[09:19:54.909] <TB3> INFO: no data (only TBM hdr): 0
[09:19:54.909] <TB3> INFO: TBM errors: 0
[09:19:54.909] <TB3> INFO: flawed TBM headers: 0
[09:19:54.909] <TB3> INFO: flawed TBM trailers: 0
[09:19:54.909] <TB3> INFO: event ID mismatches: 0
[09:19:54.909] <TB3> INFO: ROC errors: 0
[09:19:54.909] <TB3> INFO: missing ROC header(s): 0
[09:19:54.909] <TB3> INFO: misplaced readback start: 0
[09:19:54.909] <TB3> INFO: Pixel decoding errors: 0
[09:19:54.909] <TB3> INFO: pixel data incomplete: 0
[09:19:54.909] <TB3> INFO: pixel address: 0
[09:19:54.909] <TB3> INFO: pulse height fill bit: 0
[09:19:54.909] <TB3> INFO: buffer corruption: 0
[09:19:55.845] <TB3> INFO: ######################################################################
[09:19:55.845] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[09:19:55.845] <TB3> INFO: ######################################################################
[09:19:56.192] <TB3> INFO: Expecting 41600 events.
[09:20:00.228] <TB3> INFO: 41600 events read in total (3480ms).
[09:20:00.229] <TB3> INFO: Test took 4383ms.
[09:20:00.766] <TB3> INFO: Expecting 41600 events.
[09:20:04.649] <TB3> INFO: 41600 events read in total (3326ms).
[09:20:04.649] <TB3> INFO: Test took 4226ms.
[09:20:04.930] <TB3> INFO: Expecting 41600 events.
[09:20:08.719] <TB3> INFO: 41600 events read in total (3233ms).
[09:20:08.720] <TB3> INFO: Test took 4049ms.
[09:20:08.975] <TB3> INFO: Expecting 2560 events.
[09:20:09.828] <TB3> INFO: 2560 events read in total (297ms).
[09:20:09.828] <TB3> INFO: Test took 1095ms.
[09:20:10.132] <TB3> INFO: Expecting 2560 events.
[09:20:10.985] <TB3> INFO: 2560 events read in total (296ms).
[09:20:10.985] <TB3> INFO: Test took 1157ms.
[09:20:11.290] <TB3> INFO: Expecting 2560 events.
[09:20:12.143] <TB3> INFO: 2560 events read in total (297ms).
[09:20:12.143] <TB3> INFO: Test took 1158ms.
[09:20:12.448] <TB3> INFO: Expecting 2560 events.
[09:20:13.300] <TB3> INFO: 2560 events read in total (296ms).
[09:20:13.300] <TB3> INFO: Test took 1157ms.
[09:20:13.605] <TB3> INFO: Expecting 2560 events.
[09:20:14.458] <TB3> INFO: 2560 events read in total (297ms).
[09:20:14.458] <TB3> INFO: Test took 1157ms.
[09:20:14.763] <TB3> INFO: Expecting 2560 events.
[09:20:15.615] <TB3> INFO: 2560 events read in total (296ms).
[09:20:15.616] <TB3> INFO: Test took 1158ms.
[09:20:15.920] <TB3> INFO: Expecting 2560 events.
[09:20:16.772] <TB3> INFO: 2560 events read in total (295ms).
[09:20:16.773] <TB3> INFO: Test took 1157ms.
[09:20:17.077] <TB3> INFO: Expecting 2560 events.
[09:20:17.931] <TB3> INFO: 2560 events read in total (297ms).
[09:20:17.931] <TB3> INFO: Test took 1158ms.
[09:20:18.237] <TB3> INFO: Expecting 2560 events.
[09:20:19.095] <TB3> INFO: 2560 events read in total (302ms).
[09:20:19.095] <TB3> INFO: Test took 1164ms.
[09:20:19.401] <TB3> INFO: Expecting 2560 events.
[09:20:20.254] <TB3> INFO: 2560 events read in total (297ms).
[09:20:20.255] <TB3> INFO: Test took 1159ms.
[09:20:20.559] <TB3> INFO: Expecting 2560 events.
[09:20:21.417] <TB3> INFO: 2560 events read in total (302ms).
[09:20:21.417] <TB3> INFO: Test took 1162ms.
[09:20:21.722] <TB3> INFO: Expecting 2560 events.
[09:20:22.578] <TB3> INFO: 2560 events read in total (300ms).
[09:20:22.579] <TB3> INFO: Test took 1161ms.
[09:20:22.884] <TB3> INFO: Expecting 2560 events.
[09:20:23.737] <TB3> INFO: 2560 events read in total (297ms).
[09:20:23.737] <TB3> INFO: Test took 1158ms.
[09:20:24.042] <TB3> INFO: Expecting 2560 events.
[09:20:24.896] <TB3> INFO: 2560 events read in total (297ms).
[09:20:24.896] <TB3> INFO: Test took 1159ms.
[09:20:25.201] <TB3> INFO: Expecting 2560 events.
[09:20:26.054] <TB3> INFO: 2560 events read in total (297ms).
[09:20:26.054] <TB3> INFO: Test took 1157ms.
[09:20:26.360] <TB3> INFO: Expecting 2560 events.
[09:20:27.214] <TB3> INFO: 2560 events read in total (298ms).
[09:20:27.214] <TB3> INFO: Test took 1160ms.
[09:20:27.217] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:20:27.520] <TB3> INFO: Expecting 655360 events.
[09:20:44.489] <TB3> INFO: 655360 events read in total (16412ms).
[09:20:44.498] <TB3> INFO: Expecting 655360 events.
[09:21:01.365] <TB3> INFO: 655360 events read in total (16465ms).
[09:21:01.377] <TB3> INFO: Expecting 655360 events.
[09:21:18.250] <TB3> INFO: 655360 events read in total (16472ms).
[09:21:18.266] <TB3> INFO: Expecting 655360 events.
[09:21:35.157] <TB3> INFO: 655360 events read in total (16489ms).
[09:21:35.179] <TB3> INFO: Expecting 655360 events.
[09:21:51.905] <TB3> INFO: 655360 events read in total (16324ms).
[09:21:51.928] <TB3> INFO: Expecting 655360 events.
[09:22:08.782] <TB3> INFO: 655360 events read in total (16452ms).
[09:22:08.808] <TB3> INFO: Expecting 655360 events.
[09:22:25.614] <TB3> INFO: 655360 events read in total (16404ms).
[09:22:25.645] <TB3> INFO: Expecting 655360 events.
[09:22:42.544] <TB3> INFO: 655360 events read in total (16497ms).
[09:22:42.582] <TB3> INFO: Expecting 655360 events.
[09:22:59.354] <TB3> INFO: 655360 events read in total (16371ms).
[09:22:59.389] <TB3> INFO: Expecting 655360 events.
[09:23:16.196] <TB3> INFO: 655360 events read in total (16405ms).
[09:23:16.240] <TB3> INFO: Expecting 655360 events.
[09:23:33.137] <TB3> INFO: 655360 events read in total (16495ms).
[09:23:33.183] <TB3> INFO: Expecting 655360 events.
[09:23:49.967] <TB3> INFO: 655360 events read in total (16383ms).
[09:23:50.020] <TB3> INFO: Expecting 655360 events.
[09:24:06.926] <TB3> INFO: 655360 events read in total (16504ms).
[09:24:06.979] <TB3> INFO: Expecting 655360 events.
[09:24:23.829] <TB3> INFO: 655360 events read in total (16449ms).
[09:24:23.894] <TB3> INFO: Expecting 655360 events.
[09:24:40.715] <TB3> INFO: 655360 events read in total (16420ms).
[09:24:40.772] <TB3> INFO: Expecting 655360 events.
[09:24:57.635] <TB3> INFO: 655360 events read in total (16462ms).
[09:24:57.704] <TB3> INFO: Test took 270487ms.
[09:24:57.788] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:24:58.018] <TB3> INFO: Expecting 655360 events.
[09:25:14.940] <TB3> INFO: 655360 events read in total (16366ms).
[09:25:14.950] <TB3> INFO: Expecting 655360 events.
[09:25:31.658] <TB3> INFO: 655360 events read in total (16306ms).
[09:25:31.671] <TB3> INFO: Expecting 655360 events.
[09:25:48.444] <TB3> INFO: 655360 events read in total (16371ms).
[09:25:48.461] <TB3> INFO: Expecting 655360 events.
[09:26:05.210] <TB3> INFO: 655360 events read in total (16348ms).
[09:26:05.229] <TB3> INFO: Expecting 655360 events.
[09:26:22.095] <TB3> INFO: 655360 events read in total (16464ms).
[09:26:22.119] <TB3> INFO: Expecting 655360 events.
[09:26:38.817] <TB3> INFO: 655360 events read in total (16296ms).
[09:26:38.848] <TB3> INFO: Expecting 655360 events.
[09:26:55.629] <TB3> INFO: 655360 events read in total (16379ms).
[09:26:55.659] <TB3> INFO: Expecting 655360 events.
[09:27:12.420] <TB3> INFO: 655360 events read in total (16360ms).
[09:27:12.456] <TB3> INFO: Expecting 655360 events.
[09:27:29.266] <TB3> INFO: 655360 events read in total (16409ms).
[09:27:29.306] <TB3> INFO: Expecting 655360 events.
[09:27:46.006] <TB3> INFO: 655360 events read in total (16298ms).
[09:27:46.046] <TB3> INFO: Expecting 655360 events.
[09:28:02.848] <TB3> INFO: 655360 events read in total (16400ms).
[09:28:02.895] <TB3> INFO: Expecting 655360 events.
[09:28:19.604] <TB3> INFO: 655360 events read in total (16308ms).
[09:28:19.659] <TB3> INFO: Expecting 655360 events.
[09:28:36.575] <TB3> INFO: 655360 events read in total (16515ms).
[09:28:36.631] <TB3> INFO: Expecting 655360 events.
[09:28:53.444] <TB3> INFO: 655360 events read in total (16412ms).
[09:28:53.508] <TB3> INFO: Expecting 655360 events.
[09:29:10.265] <TB3> INFO: 655360 events read in total (16355ms).
[09:29:10.325] <TB3> INFO: Expecting 655360 events.
[09:29:27.018] <TB3> INFO: 655360 events read in total (16291ms).
[09:29:27.088] <TB3> INFO: Test took 269300ms.
[09:29:27.290] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:27.297] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:27.304] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[09:29:27.311] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[09:29:27.318] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[09:29:27.325] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:27.333] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:27.341] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:27.348] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:27.354] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:27.361] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:27.368] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:27.375] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:27.382] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:27.389] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:27.399] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:27.406] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:27.413] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:27.420] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[09:29:27.456] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:29:27.456] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:29:27.456] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:29:27.456] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:29:27.456] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:29:27.456] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:29:27.457] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:29:27.457] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:29:27.457] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:29:27.457] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:29:27.457] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:29:27.457] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:29:27.457] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:29:27.457] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:29:27.458] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:29:27.458] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:29:27.734] <TB3> INFO: Expecting 41600 events.
[09:29:31.202] <TB3> INFO: 41600 events read in total (2913ms).
[09:29:31.203] <TB3> INFO: Test took 3742ms.
[09:29:31.640] <TB3> INFO: Expecting 41600 events.
[09:29:35.152] <TB3> INFO: 41600 events read in total (2955ms).
[09:29:35.152] <TB3> INFO: Test took 3773ms.
[09:29:35.600] <TB3> INFO: Expecting 41600 events.
[09:29:39.059] <TB3> INFO: 41600 events read in total (2903ms).
[09:29:39.060] <TB3> INFO: Test took 3731ms.
[09:29:39.245] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:39.333] <TB3> INFO: Expecting 2560 events.
[09:29:40.192] <TB3> INFO: 2560 events read in total (302ms).
[09:29:40.193] <TB3> INFO: Test took 948ms.
[09:29:40.195] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:40.499] <TB3> INFO: Expecting 2560 events.
[09:29:41.357] <TB3> INFO: 2560 events read in total (302ms).
[09:29:41.357] <TB3> INFO: Test took 1162ms.
[09:29:41.361] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:41.662] <TB3> INFO: Expecting 2560 events.
[09:29:42.520] <TB3> INFO: 2560 events read in total (302ms).
[09:29:42.520] <TB3> INFO: Test took 1159ms.
[09:29:42.522] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:42.826] <TB3> INFO: Expecting 2560 events.
[09:29:43.680] <TB3> INFO: 2560 events read in total (298ms).
[09:29:43.680] <TB3> INFO: Test took 1158ms.
[09:29:43.683] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:43.985] <TB3> INFO: Expecting 2560 events.
[09:29:44.847] <TB3> INFO: 2560 events read in total (302ms).
[09:29:44.847] <TB3> INFO: Test took 1164ms.
[09:29:44.849] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:45.152] <TB3> INFO: Expecting 2560 events.
[09:29:46.010] <TB3> INFO: 2560 events read in total (302ms).
[09:29:46.010] <TB3> INFO: Test took 1161ms.
[09:29:46.012] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:46.316] <TB3> INFO: Expecting 2560 events.
[09:29:47.169] <TB3> INFO: 2560 events read in total (297ms).
[09:29:47.170] <TB3> INFO: Test took 1158ms.
[09:29:47.171] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:47.476] <TB3> INFO: Expecting 2560 events.
[09:29:48.335] <TB3> INFO: 2560 events read in total (303ms).
[09:29:48.335] <TB3> INFO: Test took 1164ms.
[09:29:48.338] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:48.641] <TB3> INFO: Expecting 2560 events.
[09:29:49.496] <TB3> INFO: 2560 events read in total (299ms).
[09:29:49.496] <TB3> INFO: Test took 1158ms.
[09:29:49.499] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:49.801] <TB3> INFO: Expecting 2560 events.
[09:29:50.657] <TB3> INFO: 2560 events read in total (300ms).
[09:29:50.657] <TB3> INFO: Test took 1158ms.
[09:29:50.659] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:50.962] <TB3> INFO: Expecting 2560 events.
[09:29:51.818] <TB3> INFO: 2560 events read in total (299ms).
[09:29:51.818] <TB3> INFO: Test took 1159ms.
[09:29:51.821] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:52.125] <TB3> INFO: Expecting 2560 events.
[09:29:52.983] <TB3> INFO: 2560 events read in total (302ms).
[09:29:52.983] <TB3> INFO: Test took 1162ms.
[09:29:52.986] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:53.289] <TB3> INFO: Expecting 2560 events.
[09:29:54.146] <TB3> INFO: 2560 events read in total (301ms).
[09:29:54.147] <TB3> INFO: Test took 1161ms.
[09:29:54.161] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:54.453] <TB3> INFO: Expecting 2560 events.
[09:29:55.309] <TB3> INFO: 2560 events read in total (300ms).
[09:29:55.310] <TB3> INFO: Test took 1150ms.
[09:29:55.312] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:55.615] <TB3> INFO: Expecting 2560 events.
[09:29:56.469] <TB3> INFO: 2560 events read in total (297ms).
[09:29:56.470] <TB3> INFO: Test took 1158ms.
[09:29:56.472] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:56.775] <TB3> INFO: Expecting 2560 events.
[09:29:57.634] <TB3> INFO: 2560 events read in total (303ms).
[09:29:57.634] <TB3> INFO: Test took 1162ms.
[09:29:57.636] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:57.941] <TB3> INFO: Expecting 2560 events.
[09:29:58.795] <TB3> INFO: 2560 events read in total (298ms).
[09:29:58.795] <TB3> INFO: Test took 1159ms.
[09:29:58.798] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:29:59.101] <TB3> INFO: Expecting 2560 events.
[09:29:59.958] <TB3> INFO: 2560 events read in total (301ms).
[09:29:59.958] <TB3> INFO: Test took 1161ms.
[09:29:59.961] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:00.264] <TB3> INFO: Expecting 2560 events.
[09:30:01.122] <TB3> INFO: 2560 events read in total (302ms).
[09:30:01.123] <TB3> INFO: Test took 1162ms.
[09:30:01.125] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:01.428] <TB3> INFO: Expecting 2560 events.
[09:30:02.288] <TB3> INFO: 2560 events read in total (303ms).
[09:30:02.288] <TB3> INFO: Test took 1163ms.
[09:30:02.291] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:02.594] <TB3> INFO: Expecting 2560 events.
[09:30:03.450] <TB3> INFO: 2560 events read in total (300ms).
[09:30:03.451] <TB3> INFO: Test took 1160ms.
[09:30:03.453] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:03.755] <TB3> INFO: Expecting 2560 events.
[09:30:04.615] <TB3> INFO: 2560 events read in total (304ms).
[09:30:04.616] <TB3> INFO: Test took 1163ms.
[09:30:04.630] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:04.921] <TB3> INFO: Expecting 2560 events.
[09:30:05.778] <TB3> INFO: 2560 events read in total (301ms).
[09:30:05.779] <TB3> INFO: Test took 1150ms.
[09:30:05.781] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:06.085] <TB3> INFO: Expecting 2560 events.
[09:30:06.943] <TB3> INFO: 2560 events read in total (302ms).
[09:30:06.944] <TB3> INFO: Test took 1163ms.
[09:30:06.946] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:07.249] <TB3> INFO: Expecting 2560 events.
[09:30:08.110] <TB3> INFO: 2560 events read in total (302ms).
[09:30:08.110] <TB3> INFO: Test took 1164ms.
[09:30:08.112] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:08.416] <TB3> INFO: Expecting 2560 events.
[09:30:09.275] <TB3> INFO: 2560 events read in total (303ms).
[09:30:09.275] <TB3> INFO: Test took 1163ms.
[09:30:09.278] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:09.581] <TB3> INFO: Expecting 2560 events.
[09:30:10.438] <TB3> INFO: 2560 events read in total (301ms).
[09:30:10.438] <TB3> INFO: Test took 1160ms.
[09:30:10.440] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:10.744] <TB3> INFO: Expecting 2560 events.
[09:30:11.599] <TB3> INFO: 2560 events read in total (299ms).
[09:30:11.599] <TB3> INFO: Test took 1159ms.
[09:30:11.602] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:11.904] <TB3> INFO: Expecting 2560 events.
[09:30:12.759] <TB3> INFO: 2560 events read in total (299ms).
[09:30:12.760] <TB3> INFO: Test took 1159ms.
[09:30:12.762] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:13.065] <TB3> INFO: Expecting 2560 events.
[09:30:13.923] <TB3> INFO: 2560 events read in total (301ms).
[09:30:13.924] <TB3> INFO: Test took 1162ms.
[09:30:13.926] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:14.230] <TB3> INFO: Expecting 2560 events.
[09:30:15.088] <TB3> INFO: 2560 events read in total (302ms).
[09:30:15.088] <TB3> INFO: Test took 1162ms.
[09:30:15.091] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:15.394] <TB3> INFO: Expecting 2560 events.
[09:30:16.250] <TB3> INFO: 2560 events read in total (300ms).
[09:30:16.250] <TB3> INFO: Test took 1159ms.
[09:30:16.716] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 620 seconds
[09:30:16.716] <TB3> INFO: PH scale (per ROC): 81 80 86 93 84 76 85 78 89 90 80 80 84 82 98 92
[09:30:16.716] <TB3> INFO: PH offset (per ROC): 155 163 151 141 155 160 154 151 142 155 159 152 149 150 144 154
[09:30:16.724] <TB3> INFO: Decoding statistics:
[09:30:16.725] <TB3> INFO: General information:
[09:30:16.725] <TB3> INFO: 16bit words read: 91932
[09:30:16.725] <TB3> INFO: valid events total: 10240
[09:30:16.725] <TB3> INFO: empty events: 7737
[09:30:16.725] <TB3> INFO: valid events with pixels: 2503
[09:30:16.725] <TB3> INFO: valid pixel hits: 2503
[09:30:16.725] <TB3> INFO: Event errors: 0
[09:30:16.725] <TB3> INFO: start marker: 0
[09:30:16.725] <TB3> INFO: stop marker: 0
[09:30:16.725] <TB3> INFO: overflow: 0
[09:30:16.725] <TB3> INFO: invalid 5bit words: 0
[09:30:16.725] <TB3> INFO: invalid XOR eye diagram: 0
[09:30:16.725] <TB3> INFO: frame (failed synchr.): 0
[09:30:16.725] <TB3> INFO: idle data (no TBM trl): 0
[09:30:16.725] <TB3> INFO: no data (only TBM hdr): 0
[09:30:16.725] <TB3> INFO: TBM errors: 0
[09:30:16.725] <TB3> INFO: flawed TBM headers: 0
[09:30:16.725] <TB3> INFO: flawed TBM trailers: 0
[09:30:16.725] <TB3> INFO: event ID mismatches: 0
[09:30:16.725] <TB3> INFO: ROC errors: 0
[09:30:16.725] <TB3> INFO: missing ROC header(s): 0
[09:30:16.725] <TB3> INFO: misplaced readback start: 0
[09:30:16.725] <TB3> INFO: Pixel decoding errors: 0
[09:30:16.725] <TB3> INFO: pixel data incomplete: 0
[09:30:16.725] <TB3> INFO: pixel address: 0
[09:30:16.725] <TB3> INFO: pulse height fill bit: 0
[09:30:16.725] <TB3> INFO: buffer corruption: 0
[09:30:16.905] <TB3> INFO: ######################################################################
[09:30:16.905] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[09:30:16.905] <TB3> INFO: ######################################################################
[09:30:16.916] <TB3> INFO: scanning low vcal = 10
[09:30:17.180] <TB3> INFO: Expecting 41600 events.
[09:30:20.719] <TB3> INFO: 41600 events read in total (2983ms).
[09:30:20.719] <TB3> INFO: Test took 3803ms.
[09:30:20.720] <TB3> INFO: scanning low vcal = 20
[09:30:21.025] <TB3> INFO: Expecting 41600 events.
[09:30:24.587] <TB3> INFO: 41600 events read in total (3006ms).
[09:30:24.587] <TB3> INFO: Test took 3866ms.
[09:30:24.590] <TB3> INFO: scanning low vcal = 30
[09:30:24.892] <TB3> INFO: Expecting 41600 events.
[09:30:28.442] <TB3> INFO: 41600 events read in total (2994ms).
[09:30:28.442] <TB3> INFO: Test took 3852ms.
[09:30:28.444] <TB3> INFO: scanning low vcal = 40
[09:30:28.743] <TB3> INFO: Expecting 41600 events.
[09:30:32.912] <TB3> INFO: 41600 events read in total (3612ms).
[09:30:32.913] <TB3> INFO: Test took 4469ms.
[09:30:32.916] <TB3> INFO: scanning low vcal = 50
[09:30:33.201] <TB3> INFO: Expecting 41600 events.
[09:30:37.422] <TB3> INFO: 41600 events read in total (3665ms).
[09:30:37.422] <TB3> INFO: Test took 4506ms.
[09:30:37.437] <TB3> INFO: scanning low vcal = 60
[09:30:37.703] <TB3> INFO: Expecting 41600 events.
[09:30:41.913] <TB3> INFO: 41600 events read in total (3654ms).
[09:30:41.914] <TB3> INFO: Test took 4477ms.
[09:30:41.916] <TB3> INFO: scanning low vcal = 70
[09:30:42.199] <TB3> INFO: Expecting 41600 events.
[09:30:46.459] <TB3> INFO: 41600 events read in total (3703ms).
[09:30:46.459] <TB3> INFO: Test took 4543ms.
[09:30:46.461] <TB3> INFO: scanning low vcal = 80
[09:30:46.751] <TB3> INFO: Expecting 41600 events.
[09:30:50.951] <TB3> INFO: 41600 events read in total (3644ms).
[09:30:50.952] <TB3> INFO: Test took 4490ms.
[09:30:50.954] <TB3> INFO: scanning low vcal = 90
[09:30:51.217] <TB3> INFO: Expecting 41600 events.
[09:30:55.443] <TB3> INFO: 41600 events read in total (3670ms).
[09:30:55.443] <TB3> INFO: Test took 4489ms.
[09:30:55.447] <TB3> INFO: scanning low vcal = 100
[09:30:55.711] <TB3> INFO: Expecting 41600 events.
[09:30:59.936] <TB3> INFO: 41600 events read in total (3668ms).
[09:30:59.936] <TB3> INFO: Test took 4489ms.
[09:30:59.939] <TB3> INFO: scanning low vcal = 110
[09:31:00.213] <TB3> INFO: Expecting 41600 events.
[09:31:04.441] <TB3> INFO: 41600 events read in total (3672ms).
[09:31:04.441] <TB3> INFO: Test took 4502ms.
[09:31:04.444] <TB3> INFO: scanning low vcal = 120
[09:31:04.706] <TB3> INFO: Expecting 41600 events.
[09:31:08.925] <TB3> INFO: 41600 events read in total (3662ms).
[09:31:08.925] <TB3> INFO: Test took 4481ms.
[09:31:08.927] <TB3> INFO: scanning low vcal = 130
[09:31:09.190] <TB3> INFO: Expecting 41600 events.
[09:31:13.389] <TB3> INFO: 41600 events read in total (3643ms).
[09:31:13.389] <TB3> INFO: Test took 4462ms.
[09:31:13.392] <TB3> INFO: scanning low vcal = 140
[09:31:13.664] <TB3> INFO: Expecting 41600 events.
[09:31:17.884] <TB3> INFO: 41600 events read in total (3664ms).
[09:31:17.885] <TB3> INFO: Test took 4493ms.
[09:31:17.899] <TB3> INFO: scanning low vcal = 150
[09:31:18.161] <TB3> INFO: Expecting 41600 events.
[09:31:22.433] <TB3> INFO: 41600 events read in total (3715ms).
[09:31:22.433] <TB3> INFO: Test took 4534ms.
[09:31:22.436] <TB3> INFO: scanning low vcal = 160
[09:31:22.726] <TB3> INFO: Expecting 41600 events.
[09:31:26.954] <TB3> INFO: 41600 events read in total (3672ms).
[09:31:26.954] <TB3> INFO: Test took 4518ms.
[09:31:26.957] <TB3> INFO: scanning low vcal = 170
[09:31:27.228] <TB3> INFO: Expecting 41600 events.
[09:31:31.466] <TB3> INFO: 41600 events read in total (3682ms).
[09:31:31.467] <TB3> INFO: Test took 4510ms.
[09:31:31.470] <TB3> INFO: scanning low vcal = 180
[09:31:31.761] <TB3> INFO: Expecting 41600 events.
[09:31:36.012] <TB3> INFO: 41600 events read in total (3694ms).
[09:31:36.013] <TB3> INFO: Test took 4543ms.
[09:31:36.015] <TB3> INFO: scanning low vcal = 190
[09:31:36.304] <TB3> INFO: Expecting 41600 events.
[09:31:40.544] <TB3> INFO: 41600 events read in total (3684ms).
[09:31:40.544] <TB3> INFO: Test took 4529ms.
[09:31:40.547] <TB3> INFO: scanning low vcal = 200
[09:31:40.812] <TB3> INFO: Expecting 41600 events.
[09:31:45.070] <TB3> INFO: 41600 events read in total (3702ms).
[09:31:45.070] <TB3> INFO: Test took 4523ms.
[09:31:45.073] <TB3> INFO: scanning low vcal = 210
[09:31:45.340] <TB3> INFO: Expecting 41600 events.
[09:31:49.611] <TB3> INFO: 41600 events read in total (3715ms).
[09:31:49.612] <TB3> INFO: Test took 4539ms.
[09:31:49.614] <TB3> INFO: scanning low vcal = 220
[09:31:49.904] <TB3> INFO: Expecting 41600 events.
[09:31:54.139] <TB3> INFO: 41600 events read in total (3678ms).
[09:31:54.139] <TB3> INFO: Test took 4525ms.
[09:31:54.142] <TB3> INFO: scanning low vcal = 230
[09:31:54.410] <TB3> INFO: Expecting 41600 events.
[09:31:58.653] <TB3> INFO: 41600 events read in total (3687ms).
[09:31:58.654] <TB3> INFO: Test took 4512ms.
[09:31:58.656] <TB3> INFO: scanning low vcal = 240
[09:31:58.922] <TB3> INFO: Expecting 41600 events.
[09:32:03.142] <TB3> INFO: 41600 events read in total (3664ms).
[09:32:03.143] <TB3> INFO: Test took 4487ms.
[09:32:03.145] <TB3> INFO: scanning low vcal = 250
[09:32:03.407] <TB3> INFO: Expecting 41600 events.
[09:32:07.678] <TB3> INFO: 41600 events read in total (3714ms).
[09:32:07.679] <TB3> INFO: Test took 4534ms.
[09:32:07.695] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[09:32:07.955] <TB3> INFO: Expecting 41600 events.
[09:32:12.202] <TB3> INFO: 41600 events read in total (3690ms).
[09:32:12.203] <TB3> INFO: Test took 4507ms.
[09:32:12.206] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[09:32:12.482] <TB3> INFO: Expecting 41600 events.
[09:32:16.733] <TB3> INFO: 41600 events read in total (3695ms).
[09:32:16.734] <TB3> INFO: Test took 4528ms.
[09:32:16.736] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[09:32:17.017] <TB3> INFO: Expecting 41600 events.
[09:32:21.268] <TB3> INFO: 41600 events read in total (3694ms).
[09:32:21.269] <TB3> INFO: Test took 4533ms.
[09:32:21.271] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[09:32:21.534] <TB3> INFO: Expecting 41600 events.
[09:32:25.760] <TB3> INFO: 41600 events read in total (3670ms).
[09:32:25.760] <TB3> INFO: Test took 4488ms.
[09:32:25.763] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[09:32:26.034] <TB3> INFO: Expecting 41600 events.
[09:32:30.277] <TB3> INFO: 41600 events read in total (3686ms).
[09:32:30.278] <TB3> INFO: Test took 4515ms.
[09:32:30.822] <TB3> INFO: PixTestGainPedestal::measure() done
[09:33:05.254] <TB3> INFO: PixTestGainPedestal::fit() done
[09:33:05.254] <TB3> INFO: non-linearity mean: 0.948 0.956 0.955 0.953 0.958 0.951 0.957 0.956 0.953 0.956 0.954 0.956 0.954 0.952 0.954 0.955
[09:33:05.254] <TB3> INFO: non-linearity RMS: 0.006 0.004 0.005 0.006 0.005 0.006 0.005 0.005 0.006 0.005 0.005 0.005 0.006 0.006 0.005 0.005
[09:33:05.254] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[09:33:05.273] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[09:33:05.291] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[09:33:05.309] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[09:33:05.327] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[09:33:05.345] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[09:33:05.363] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[09:33:05.381] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[09:33:05.399] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[09:33:05.417] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[09:33:05.435] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[09:33:05.453] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[09:33:05.471] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[09:33:05.489] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[09:33:05.507] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[09:33:05.525] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[09:33:05.543] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 168 seconds
[09:33:05.543] <TB3> INFO: Decoding statistics:
[09:33:05.543] <TB3> INFO: General information:
[09:33:05.543] <TB3> INFO: 16bit words read: 2662400
[09:33:05.543] <TB3> INFO: valid events total: 166400
[09:33:05.543] <TB3> INFO: empty events: 0
[09:33:05.543] <TB3> INFO: valid events with pixels: 166400
[09:33:05.543] <TB3> INFO: valid pixel hits: 665593
[09:33:05.543] <TB3> INFO: Event errors: 0
[09:33:05.543] <TB3> INFO: start marker: 0
[09:33:05.543] <TB3> INFO: stop marker: 0
[09:33:05.543] <TB3> INFO: overflow: 0
[09:33:05.543] <TB3> INFO: invalid 5bit words: 0
[09:33:05.543] <TB3> INFO: invalid XOR eye diagram: 0
[09:33:05.543] <TB3> INFO: frame (failed synchr.): 0
[09:33:05.543] <TB3> INFO: idle data (no TBM trl): 0
[09:33:05.543] <TB3> INFO: no data (only TBM hdr): 0
[09:33:05.543] <TB3> INFO: TBM errors: 0
[09:33:05.543] <TB3> INFO: flawed TBM headers: 0
[09:33:05.543] <TB3> INFO: flawed TBM trailers: 0
[09:33:05.543] <TB3> INFO: event ID mismatches: 0
[09:33:05.543] <TB3> INFO: ROC errors: 0
[09:33:05.543] <TB3> INFO: missing ROC header(s): 0
[09:33:05.543] <TB3> INFO: misplaced readback start: 0
[09:33:05.543] <TB3> INFO: Pixel decoding errors: 0
[09:33:05.543] <TB3> INFO: pixel data incomplete: 0
[09:33:05.543] <TB3> INFO: pixel address: 0
[09:33:05.543] <TB3> INFO: pulse height fill bit: 0
[09:33:05.543] <TB3> INFO: buffer corruption: 0
[09:33:05.551] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C15.dat
[09:33:05.552] <TB3> INFO: ######################################################################
[09:33:05.552] <TB3> INFO: PixTestReadback::doTest()
[09:33:05.552] <TB3> INFO: ######################################################################
[09:33:05.552] <TB3> INFO: ----------------------------------------------------------------------
[09:33:05.552] <TB3> INFO: PixTestReadback::CalibrateVd()
[09:33:05.552] <TB3> INFO: ----------------------------------------------------------------------
[09:33:14.999] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C0.dat
[09:33:14.999] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C1.dat
[09:33:14.999] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C2.dat
[09:33:14.999] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C3.dat
[09:33:14.999] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C4.dat
[09:33:14.999] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C5.dat
[09:33:14.999] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C6.dat
[09:33:14.999] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C7.dat
[09:33:14.999] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C8.dat
[09:33:15.000] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C9.dat
[09:33:15.000] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C10.dat
[09:33:15.000] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C11.dat
[09:33:15.000] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C12.dat
[09:33:15.000] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C13.dat
[09:33:15.000] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C14.dat
[09:33:15.000] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C15.dat
[09:33:15.029] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[09:33:15.029] <TB3> INFO: ----------------------------------------------------------------------
[09:33:15.029] <TB3> INFO: PixTestReadback::CalibrateVa()
[09:33:15.029] <TB3> INFO: ----------------------------------------------------------------------
[09:33:24.492] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C0.dat
[09:33:24.492] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C1.dat
[09:33:24.493] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C2.dat
[09:33:24.493] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C3.dat
[09:33:24.493] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C4.dat
[09:33:24.493] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C5.dat
[09:33:24.493] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C6.dat
[09:33:24.493] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C7.dat
[09:33:24.493] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C8.dat
[09:33:24.493] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C9.dat
[09:33:24.493] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C10.dat
[09:33:24.493] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C11.dat
[09:33:24.493] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C12.dat
[09:33:24.493] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C13.dat
[09:33:24.493] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C14.dat
[09:33:24.494] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C15.dat
[09:33:24.525] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[09:33:24.525] <TB3> INFO: ----------------------------------------------------------------------
[09:33:24.525] <TB3> INFO: PixTestReadback::readbackVbg()
[09:33:24.525] <TB3> INFO: ----------------------------------------------------------------------
[09:33:31.834] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[09:33:31.834] <TB3> INFO: ----------------------------------------------------------------------
[09:33:31.834] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[09:33:31.834] <TB3> INFO: ----------------------------------------------------------------------
[09:33:31.834] <TB3> INFO: Vbg will be calibrated using Vd calibration
[09:33:31.834] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 156.2calibrated Vbg = 1.20847 :::*/*/*/*/
[09:33:31.835] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 156.6calibrated Vbg = 1.20313 :::*/*/*/*/
[09:33:31.835] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 155.5calibrated Vbg = 1.20992 :::*/*/*/*/
[09:33:31.835] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 148.9calibrated Vbg = 1.22095 :::*/*/*/*/
[09:33:31.835] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 157.3calibrated Vbg = 1.22171 :::*/*/*/*/
[09:33:31.835] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 157calibrated Vbg = 1.21798 :::*/*/*/*/
[09:33:31.835] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 155calibrated Vbg = 1.22484 :::*/*/*/*/
[09:33:31.835] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 153.1calibrated Vbg = 1.22667 :::*/*/*/*/
[09:33:31.835] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 147.8calibrated Vbg = 1.22615 :::*/*/*/*/
[09:33:31.835] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 161calibrated Vbg = 1.22943 :::*/*/*/*/
[09:33:31.835] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 142.4calibrated Vbg = 1.22683 :::*/*/*/*/
[09:33:31.835] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 144.7calibrated Vbg = 1.20872 :::*/*/*/*/
[09:33:31.835] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 153.3calibrated Vbg = 1.20766 :::*/*/*/*/
[09:33:31.835] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 153.6calibrated Vbg = 1.20294 :::*/*/*/*/
[09:33:31.835] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 154.5calibrated Vbg = 1.20635 :::*/*/*/*/
[09:33:31.835] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 151.1calibrated Vbg = 1.20653 :::*/*/*/*/
[09:33:31.838] <TB3> INFO: ----------------------------------------------------------------------
[09:33:31.838] <TB3> INFO: PixTestReadback::CalibrateIa()
[09:33:31.838] <TB3> INFO: ----------------------------------------------------------------------
[09:36:07.084] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C0.dat
[09:36:07.084] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C1.dat
[09:36:07.084] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C2.dat
[09:36:07.085] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C3.dat
[09:36:07.085] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C4.dat
[09:36:07.085] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C5.dat
[09:36:07.085] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C6.dat
[09:36:07.085] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C7.dat
[09:36:07.085] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C8.dat
[09:36:07.085] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C9.dat
[09:36:07.085] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C10.dat
[09:36:07.085] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C11.dat
[09:36:07.085] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C12.dat
[09:36:07.086] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C13.dat
[09:36:07.086] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C14.dat
[09:36:07.086] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2318_FullQualification_2016-09-08_09h56m_1473321409//000_FulltestPxar_m20//readbackCal_C15.dat
[09:36:07.116] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[09:36:07.119] <TB3> INFO: PixTestReadback::doTest() done
[09:36:07.119] <TB3> INFO: Decoding statistics:
[09:36:07.119] <TB3> INFO: General information:
[09:36:07.119] <TB3> INFO: 16bit words read: 1024
[09:36:07.119] <TB3> INFO: valid events total: 128
[09:36:07.119] <TB3> INFO: empty events: 128
[09:36:07.119] <TB3> INFO: valid events with pixels: 0
[09:36:07.119] <TB3> INFO: valid pixel hits: 0
[09:36:07.119] <TB3> INFO: Event errors: 0
[09:36:07.119] <TB3> INFO: start marker: 0
[09:36:07.119] <TB3> INFO: stop marker: 0
[09:36:07.119] <TB3> INFO: overflow: 0
[09:36:07.119] <TB3> INFO: invalid 5bit words: 0
[09:36:07.119] <TB3> INFO: invalid XOR eye diagram: 0
[09:36:07.119] <TB3> INFO: frame (failed synchr.): 0
[09:36:07.119] <TB3> INFO: idle data (no TBM trl): 0
[09:36:07.119] <TB3> INFO: no data (only TBM hdr): 0
[09:36:07.119] <TB3> INFO: TBM errors: 0
[09:36:07.119] <TB3> INFO: flawed TBM headers: 0
[09:36:07.119] <TB3> INFO: flawed TBM trailers: 0
[09:36:07.119] <TB3> INFO: event ID mismatches: 0
[09:36:07.119] <TB3> INFO: ROC errors: 0
[09:36:07.119] <TB3> INFO: missing ROC header(s): 0
[09:36:07.120] <TB3> INFO: misplaced readback start: 0
[09:36:07.120] <TB3> INFO: Pixel decoding errors: 0
[09:36:07.120] <TB3> INFO: pixel data incomplete: 0
[09:36:07.120] <TB3> INFO: pixel address: 0
[09:36:07.120] <TB3> INFO: pulse height fill bit: 0
[09:36:07.120] <TB3> INFO: buffer corruption: 0
[09:36:07.145] <TB3> INFO: Decoding statistics:
[09:36:07.145] <TB3> INFO: General information:
[09:36:07.145] <TB3> INFO: 16bit words read: 2755356
[09:36:07.145] <TB3> INFO: valid events total: 176768
[09:36:07.145] <TB3> INFO: empty events: 7865
[09:36:07.145] <TB3> INFO: valid events with pixels: 168903
[09:36:07.145] <TB3> INFO: valid pixel hits: 668096
[09:36:07.145] <TB3> INFO: Event errors: 0
[09:36:07.145] <TB3> INFO: start marker: 0
[09:36:07.145] <TB3> INFO: stop marker: 0
[09:36:07.145] <TB3> INFO: overflow: 0
[09:36:07.145] <TB3> INFO: invalid 5bit words: 0
[09:36:07.145] <TB3> INFO: invalid XOR eye diagram: 0
[09:36:07.145] <TB3> INFO: frame (failed synchr.): 0
[09:36:07.145] <TB3> INFO: idle data (no TBM trl): 0
[09:36:07.145] <TB3> INFO: no data (only TBM hdr): 0
[09:36:07.145] <TB3> INFO: TBM errors: 0
[09:36:07.145] <TB3> INFO: flawed TBM headers: 0
[09:36:07.145] <TB3> INFO: flawed TBM trailers: 0
[09:36:07.145] <TB3> INFO: event ID mismatches: 0
[09:36:07.145] <TB3> INFO: ROC errors: 0
[09:36:07.145] <TB3> INFO: missing ROC header(s): 0
[09:36:07.145] <TB3> INFO: misplaced readback start: 0
[09:36:07.145] <TB3> INFO: Pixel decoding errors: 0
[09:36:07.145] <TB3> INFO: pixel data incomplete: 0
[09:36:07.145] <TB3> INFO: pixel address: 0
[09:36:07.145] <TB3> INFO: pulse height fill bit: 0
[09:36:07.145] <TB3> INFO: buffer corruption: 0
[09:36:07.145] <TB3> INFO: enter test to run
[09:36:07.145] <TB3> INFO: test: exit no parameter change
[09:36:07.364] <TB3> QUIET: Connection to board 170 closed.
[09:36:07.443] <TB3> INFO: pXar: this is the end, my friend