[09:22:00.411] <TB1> INFO: *** Welcome to pxar ***
[09:22:00.411] <TB1> INFO: *** Today: 2016/09/20
[09:22:01.656] <TB1> INFO: *** Version: fe94
[09:22:01.656] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C15.dat
[09:22:01.657] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//tbmParameters_C0b.dat
[09:22:01.657] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//defaultMaskFile.dat
[09:22:01.657] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters_C15.dat
[09:22:01.722] <TB1> INFO: clk: 4
[09:22:01.722] <TB1> INFO: ctr: 4
[09:22:01.722] <TB1> INFO: sda: 19
[09:22:01.722] <TB1> INFO: tin: 9
[09:22:01.722] <TB1> INFO: level: 15
[09:22:01.722] <TB1> INFO: triggerdelay: 0
[09:22:01.722] <TB1> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[09:22:01.722] <TB1> INFO: Log level: INFO
[09:22:01.729] <TB1> INFO: Found DTB DTB_WXBYFL
[09:22:01.738] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[09:22:01.740] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[09:22:01.741] <TB1> INFO: RPC call hashes of host and DTB match: 486171790
[09:22:03.242] <TB1> INFO: DUT info:
[09:22:03.242] <TB1> INFO: The DUT currently contains the following objects:
[09:22:03.242] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[09:22:03.242] <TB1> INFO: TBM Core alpha (0): 7 registers set
[09:22:03.242] <TB1> INFO: TBM Core beta (1): 7 registers set
[09:22:03.242] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:22:03.242] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:03.242] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:03.242] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:03.242] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:03.242] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:03.242] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:03.242] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:03.242] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:03.242] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:03.242] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:03.242] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:03.242] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:03.242] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:03.242] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:03.242] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:03.242] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:22:03.643] <TB1> INFO: enter 'restricted' command line mode
[09:22:03.643] <TB1> INFO: enter test to run
[09:22:03.643] <TB1> INFO: test: pretest no parameter change
[09:22:03.643] <TB1> INFO: running: pretest
[09:22:03.648] <TB1> INFO: ----------------------------------------------------------------------
[09:22:03.648] <TB1> INFO: PixTestPretest::programROC()
[09:22:03.648] <TB1> INFO: ----------------------------------------------------------------------
[09:22:21.664] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[09:22:21.664] <TB1> INFO: IA differences per ROC: 17.7 16.9 16.9 14.5 20.1 18.5 17.7 16.9 17.7 19.3 18.5 16.9 20.1 21.7 19.3 18.5
[09:22:21.756] <TB1> INFO: enter test to run
[09:22:21.756] <TB1> INFO: test: pretest no parameter change
[09:22:21.756] <TB1> INFO: running: pretest
[09:22:21.757] <TB1> INFO: ----------------------------------------------------------------------
[09:22:21.758] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[09:22:21.758] <TB1> INFO: ----------------------------------------------------------------------
[09:22:43.033] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 388.3 mA = 24.2687 mA/ROC
[09:22:43.033] <TB1> INFO: i(loss) [mA/ROC]: 20.1 20.1 20.1 20.1 19.3 20.1 20.1 19.3 19.3 19.3 19.3 20.1 18.5 18.5 19.3 20.1
[09:22:43.064] <TB1> INFO: enter test to run
[09:22:43.064] <TB1> INFO: test: pretest no parameter change
[09:22:43.064] <TB1> INFO: running: pretest
[09:22:43.064] <TB1> INFO: ----------------------------------------------------------------------
[09:22:43.064] <TB1> INFO: PixTestPretest::findTiming()
[09:22:43.064] <TB1> INFO: ----------------------------------------------------------------------
[09:22:43.064] <TB1> INFO: PixTestCmd::init()

[09:24:13.145] <TB1> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[09:24:13.145] <TB1> INFO: (success/tries = 100/100), width = 5
[09:24:13.146] <TB1> INFO: enter test to run
[09:24:13.146] <TB1> INFO: test: pretest no parameter change
[09:24:13.146] <TB1> INFO: running: pretest
[09:24:13.147] <TB1> INFO: ----------------------------------------------------------------------
[09:24:13.147] <TB1> INFO: PixTestPretest::findWorkingPixel()
[09:24:13.147] <TB1> INFO: ----------------------------------------------------------------------
[09:24:13.238] <TB1> INFO: Expecting 231680 events.


[09:24:37.274] <TB1> INFO: 231680 events read in total (23480ms).
[09:24:37.281] <TB1> INFO: Test took 24131ms.
[09:24:37.484] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[09:24:37.547] <TB1> INFO: enter test to run
[09:24:37.547] <TB1> INFO: test: pretest no parameter change
[09:24:37.547] <TB1> INFO: running: pretest
[09:24:37.548] <TB1> INFO: ----------------------------------------------------------------------
[09:24:37.548] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[09:24:37.548] <TB1> INFO: ----------------------------------------------------------------------
[09:24:37.641] <TB1> INFO: Expecting 231680 events.
[09:25:01.678] <TB1> INFO: 231680 events read in total (23481ms).
[09:25:01.684] <TB1> INFO: Test took 24132ms.
[09:25:01.927] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[09:25:01.927] <TB1> INFO: CalDel: 122 143 130 126 136 133 170 144 141 148 156 129 154 152 165 144
[09:25:01.927] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[09:25:01.970] <TB1> INFO: enter test to run
[09:25:01.970] <TB1> INFO: test: pretest no parameter change
[09:25:01.970] <TB1> INFO: running: pretest
[09:25:01.971] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C0.dat
[09:25:01.971] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C1.dat
[09:25:01.971] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C2.dat
[09:25:01.971] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C3.dat
[09:25:01.971] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C4.dat
[09:25:01.971] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C5.dat
[09:25:01.971] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C6.dat
[09:25:01.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C7.dat
[09:25:01.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C8.dat
[09:25:01.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C9.dat
[09:25:01.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C10.dat
[09:25:01.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C11.dat
[09:25:01.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C12.dat
[09:25:01.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C13.dat
[09:25:01.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C14.dat
[09:25:01.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters_C15.dat
[09:25:01.972] <TB1> INFO: enter test to run
[09:25:01.972] <TB1> INFO: test: fulltest no parameter change
[09:25:01.973] <TB1> INFO: running: fulltest
[09:25:01.973] <TB1> INFO: ######################################################################
[09:25:01.973] <TB1> INFO: PixTestFullTest::doTest()
[09:25:01.973] <TB1> INFO: ######################################################################
[09:25:01.974] <TB1> INFO: ######################################################################
[09:25:01.974] <TB1> INFO: PixTestAlive::doTest()
[09:25:01.974] <TB1> INFO: ######################################################################
[09:25:01.975] <TB1> INFO: ----------------------------------------------------------------------
[09:25:01.975] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:25:01.975] <TB1> INFO: ----------------------------------------------------------------------
[09:25:02.236] <TB1> INFO: Expecting 41600 events.
[09:25:10.072] <TB1> INFO: 41600 events read in total (7280ms).
[09:25:10.073] <TB1> INFO: Test took 8097ms.
[09:25:10.291] <TB1> INFO: PixTestAlive::aliveTest() done
[09:25:10.291] <TB1> INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:25:10.292] <TB1> INFO: ----------------------------------------------------------------------
[09:25:10.292] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:25:10.292] <TB1> INFO: ----------------------------------------------------------------------
[09:25:10.560] <TB1> INFO: Expecting 41600 events.
[09:25:15.573] <TB1> INFO: 41600 events read in total (4457ms).
[09:25:15.573] <TB1> INFO: Test took 5280ms.
[09:25:15.573] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:25:15.812] <TB1> INFO: PixTestAlive::maskTest() done
[09:25:15.812] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:25:15.813] <TB1> INFO: ----------------------------------------------------------------------
[09:25:15.813] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:25:15.813] <TB1> INFO: ----------------------------------------------------------------------
[09:25:16.119] <TB1> INFO: Expecting 41600 events.
[09:25:24.089] <TB1> INFO: 41600 events read in total (7414ms).
[09:25:24.090] <TB1> INFO: Test took 8275ms.
[09:25:24.309] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[09:25:24.309] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:25:24.310] <TB1> INFO: PixTestAlive::doTest() done, duration: 22 seconds
[09:25:24.310] <TB1> INFO: Decoding statistics:
[09:25:24.310] <TB1> INFO: General information:
[09:25:24.310] <TB1> INFO: 16bit words read: 0
[09:25:24.310] <TB1> INFO: valid events total: 0
[09:25:24.310] <TB1> INFO: empty events: 0
[09:25:24.310] <TB1> INFO: valid events with pixels: 0
[09:25:24.310] <TB1> INFO: valid pixel hits: 0
[09:25:24.310] <TB1> INFO: Event errors: 0
[09:25:24.310] <TB1> INFO: start marker: 0
[09:25:24.310] <TB1> INFO: stop marker: 0
[09:25:24.310] <TB1> INFO: overflow: 0
[09:25:24.310] <TB1> INFO: invalid 5bit words: 0
[09:25:24.310] <TB1> INFO: invalid XOR eye diagram: 0
[09:25:24.310] <TB1> INFO: frame (failed synchr.): 0
[09:25:24.310] <TB1> INFO: idle data (no TBM trl): 0
[09:25:24.310] <TB1> INFO: no data (only TBM hdr): 0
[09:25:24.310] <TB1> INFO: TBM errors: 0
[09:25:24.310] <TB1> INFO: flawed TBM headers: 0
[09:25:24.310] <TB1> INFO: flawed TBM trailers: 0
[09:25:24.310] <TB1> INFO: event ID mismatches: 0
[09:25:24.310] <TB1> INFO: ROC errors: 0
[09:25:24.310] <TB1> INFO: missing ROC header(s): 0
[09:25:24.310] <TB1> INFO: misplaced readback start: 0
[09:25:24.310] <TB1> INFO: Pixel decoding errors: 0
[09:25:24.310] <TB1> INFO: pixel data incomplete: 0
[09:25:24.310] <TB1> INFO: pixel address: 0
[09:25:24.310] <TB1> INFO: pulse height fill bit: 0
[09:25:24.310] <TB1> INFO: buffer corruption: 0
[09:25:24.318] <TB1> INFO: ######################################################################
[09:25:24.318] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[09:25:24.318] <TB1> INFO: ######################################################################
[09:25:24.321] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[09:25:24.333] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[09:25:24.333] <TB1> INFO: run 1 of 1
[09:25:24.593] <TB1> INFO: Expecting 3120000 events.
[09:27:11.973] <TB1> INFO: 846740 events read in total (106824ms).
[09:28:57.725] <TB1> INFO: 1674790 events read in total (212576ms).
[09:30:43.233] <TB1> INFO: 2509815 events read in total (318084ms).
[09:31:59.298] <TB1> INFO: 3120000 events read in total (394149ms).
[09:31:59.361] <TB1> INFO: Test took 395029ms.
[09:32:22.861] <TB1> INFO: PixTestBBMap::doTest() done, duration: 418 seconds
[09:32:22.861] <TB1> INFO: number of dead bumps (per ROC): 3 0 0 0 0 0 0 1 0 2 0 3 0 0 0 9
[09:32:22.861] <TB1> INFO: separation cut (per ROC): 93 79 114 75 72 72 79 76 70 79 79 83 81 91 83 82
[09:32:22.861] <TB1> INFO: Decoding statistics:
[09:32:22.861] <TB1> INFO: General information:
[09:32:22.861] <TB1> INFO: 16bit words read: 0
[09:32:22.861] <TB1> INFO: valid events total: 0
[09:32:22.861] <TB1> INFO: empty events: 0
[09:32:22.861] <TB1> INFO: valid events with pixels: 0
[09:32:22.861] <TB1> INFO: valid pixel hits: 0
[09:32:22.861] <TB1> INFO: Event errors: 0
[09:32:22.861] <TB1> INFO: start marker: 0
[09:32:22.861] <TB1> INFO: stop marker: 0
[09:32:22.861] <TB1> INFO: overflow: 0
[09:32:22.861] <TB1> INFO: invalid 5bit words: 0
[09:32:22.861] <TB1> INFO: invalid XOR eye diagram: 0
[09:32:22.861] <TB1> INFO: frame (failed synchr.): 0
[09:32:22.861] <TB1> INFO: idle data (no TBM trl): 0
[09:32:22.861] <TB1> INFO: no data (only TBM hdr): 0
[09:32:22.861] <TB1> INFO: TBM errors: 0
[09:32:22.861] <TB1> INFO: flawed TBM headers: 0
[09:32:22.861] <TB1> INFO: flawed TBM trailers: 0
[09:32:22.861] <TB1> INFO: event ID mismatches: 0
[09:32:22.861] <TB1> INFO: ROC errors: 0
[09:32:22.861] <TB1> INFO: missing ROC header(s): 0
[09:32:22.861] <TB1> INFO: misplaced readback start: 0
[09:32:22.861] <TB1> INFO: Pixel decoding errors: 0
[09:32:22.861] <TB1> INFO: pixel data incomplete: 0
[09:32:22.861] <TB1> INFO: pixel address: 0
[09:32:22.861] <TB1> INFO: pulse height fill bit: 0
[09:32:22.861] <TB1> INFO: buffer corruption: 0
[09:32:22.932] <TB1> INFO: ######################################################################
[09:32:22.932] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:32:22.932] <TB1> INFO: ######################################################################
[09:32:22.932] <TB1> INFO: ----------------------------------------------------------------------
[09:32:22.932] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:32:22.932] <TB1> INFO: ----------------------------------------------------------------------
[09:32:22.932] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[09:32:22.941] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[09:32:22.941] <TB1> INFO: run 1 of 1
[09:32:23.213] <TB1> INFO: Expecting 36608000 events.
[09:34:01.684] <TB1> INFO: 807350 events read in total (97914ms).
[09:35:40.607] <TB1> INFO: 1601450 events read in total (196837ms).
[09:37:18.578] <TB1> INFO: 2398400 events read in total (294808ms).
[09:38:56.514] <TB1> INFO: 3193200 events read in total (392744ms).
[09:40:34.783] <TB1> INFO: 3989550 events read in total (491013ms).
[09:42:13.452] <TB1> INFO: 4784650 events read in total (589682ms).
[09:43:52.645] <TB1> INFO: 5578300 events read in total (688875ms).
[09:45:31.563] <TB1> INFO: 6375950 events read in total (787793ms).
[09:47:10.095] <TB1> INFO: 7169550 events read in total (886325ms).
[09:48:48.838] <TB1> INFO: 7964100 events read in total (985068ms).
[09:50:27.259] <TB1> INFO: 8759050 events read in total (1083489ms).
[09:52:05.774] <TB1> INFO: 9552700 events read in total (1182004ms).
[09:53:44.813] <TB1> INFO: 10345900 events read in total (1281043ms).
[09:55:23.187] <TB1> INFO: 11138650 events read in total (1379417ms).
[09:57:01.571] <TB1> INFO: 11931850 events read in total (1477801ms).
[09:58:40.980] <TB1> INFO: 12727650 events read in total (1577210ms).
[10:00:19.894] <TB1> INFO: 13522350 events read in total (1676124ms).
[10:01:58.308] <TB1> INFO: 14315450 events read in total (1774538ms).
[10:03:36.733] <TB1> INFO: 15108100 events read in total (1872963ms).
[10:05:15.525] <TB1> INFO: 15901200 events read in total (1971755ms).
[10:06:54.140] <TB1> INFO: 16694700 events read in total (2070370ms).
[10:08:32.763] <TB1> INFO: 17489900 events read in total (2168993ms).
[10:10:12.284] <TB1> INFO: 18282100 events read in total (2268514ms).
[10:11:50.522] <TB1> INFO: 19069600 events read in total (2366752ms).
[10:13:29.867] <TB1> INFO: 19859550 events read in total (2466097ms).
[10:15:08.545] <TB1> INFO: 20648700 events read in total (2564775ms).
[10:16:47.672] <TB1> INFO: 21436350 events read in total (2663902ms).
[10:18:25.714] <TB1> INFO: 22224100 events read in total (2761944ms).
[10:20:04.161] <TB1> INFO: 23010250 events read in total (2860391ms).
[10:21:42.416] <TB1> INFO: 23797400 events read in total (2958646ms).
[10:23:21.221] <TB1> INFO: 24584900 events read in total (3057451ms).
[10:24:58.912] <TB1> INFO: 25370300 events read in total (3155142ms).
[10:26:38.377] <TB1> INFO: 26159650 events read in total (3254607ms).
[10:28:17.040] <TB1> INFO: 26945000 events read in total (3353270ms).
[10:29:55.788] <TB1> INFO: 27731450 events read in total (3452018ms).
[10:31:34.321] <TB1> INFO: 28517450 events read in total (3550551ms).
[10:33:13.021] <TB1> INFO: 29303600 events read in total (3649251ms).
[10:34:51.510] <TB1> INFO: 30091550 events read in total (3747740ms).
[10:36:29.859] <TB1> INFO: 30876900 events read in total (3846089ms).
[10:38:08.574] <TB1> INFO: 31665600 events read in total (3944804ms).
[10:39:47.008] <TB1> INFO: 32451950 events read in total (4043238ms).
[10:41:25.528] <TB1> INFO: 33241300 events read in total (4141758ms).
[10:43:04.835] <TB1> INFO: 34027500 events read in total (4241065ms).
[10:44:43.446] <TB1> INFO: 34816000 events read in total (4339677ms).
[10:46:22.258] <TB1> INFO: 35603600 events read in total (4438488ms).
[10:48:01.518] <TB1> INFO: 36401100 events read in total (4537748ms).
[10:48:27.593] <TB1> INFO: 36608000 events read in total (4563823ms).
[10:48:27.650] <TB1> INFO: Test took 4564709ms.
[10:48:28.085] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:48:29.805] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:48:31.552] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:48:33.246] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:48:34.972] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:48:36.733] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:48:38.581] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:48:40.521] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:48:42.394] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:48:44.103] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:48:45.860] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:48:47.535] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:48:49.229] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:48:50.898] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:48:52.659] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:48:54.476] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:48:56.357] <TB1> INFO: PixTestScurves::scurves() done
[10:48:56.357] <TB1> INFO: Vcal mean: 97.94 82.40 87.32 82.38 76.26 77.76 85.96 82.50 87.69 84.38 84.58 80.22 81.10 88.53 86.74 82.41
[10:48:56.357] <TB1> INFO: Vcal RMS: 5.73 4.21 5.01 4.05 4.10 3.87 4.44 4.44 5.23 5.15 5.29 4.46 4.14 5.36 4.72 4.28
[10:48:56.357] <TB1> INFO: PixTestScurves::fullTest() done, duration: 4593 seconds
[10:48:56.357] <TB1> INFO: Decoding statistics:
[10:48:56.357] <TB1> INFO: General information:
[10:48:56.357] <TB1> INFO: 16bit words read: 0
[10:48:56.357] <TB1> INFO: valid events total: 0
[10:48:56.357] <TB1> INFO: empty events: 0
[10:48:56.357] <TB1> INFO: valid events with pixels: 0
[10:48:56.357] <TB1> INFO: valid pixel hits: 0
[10:48:56.357] <TB1> INFO: Event errors: 0
[10:48:56.357] <TB1> INFO: start marker: 0
[10:48:56.357] <TB1> INFO: stop marker: 0
[10:48:56.357] <TB1> INFO: overflow: 0
[10:48:56.357] <TB1> INFO: invalid 5bit words: 0
[10:48:56.357] <TB1> INFO: invalid XOR eye diagram: 0
[10:48:56.357] <TB1> INFO: frame (failed synchr.): 0
[10:48:56.357] <TB1> INFO: idle data (no TBM trl): 0
[10:48:56.357] <TB1> INFO: no data (only TBM hdr): 0
[10:48:56.357] <TB1> INFO: TBM errors: 0
[10:48:56.357] <TB1> INFO: flawed TBM headers: 0
[10:48:56.357] <TB1> INFO: flawed TBM trailers: 0
[10:48:56.357] <TB1> INFO: event ID mismatches: 0
[10:48:56.357] <TB1> INFO: ROC errors: 0
[10:48:56.357] <TB1> INFO: missing ROC header(s): 0
[10:48:56.357] <TB1> INFO: misplaced readback start: 0
[10:48:56.357] <TB1> INFO: Pixel decoding errors: 0
[10:48:56.357] <TB1> INFO: pixel data incomplete: 0
[10:48:56.357] <TB1> INFO: pixel address: 0
[10:48:56.357] <TB1> INFO: pulse height fill bit: 0
[10:48:56.357] <TB1> INFO: buffer corruption: 0
[10:48:56.426] <TB1> INFO: ######################################################################
[10:48:56.426] <TB1> INFO: PixTestTrim::doTest()
[10:48:56.426] <TB1> INFO: ######################################################################
[10:48:56.427] <TB1> INFO: ----------------------------------------------------------------------
[10:48:56.427] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:48:56.427] <TB1> INFO: ----------------------------------------------------------------------
[10:48:56.505] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:48:56.505] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:48:56.513] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:48:56.513] <TB1> INFO: run 1 of 1
[10:48:56.773] <TB1> INFO: Expecting 6281600 events.
[10:50:44.170] <TB1> INFO: 1097320 events read in total (106840ms).
[10:52:31.015] <TB1> INFO: 2187630 events read in total (213685ms).
[10:54:17.545] <TB1> INFO: 3269210 events read in total (320215ms).
[10:56:04.369] <TB1> INFO: 4357200 events read in total (427039ms).
[10:57:52.116] <TB1> INFO: 5447650 events read in total (534786ms).
[10:59:13.773] <TB1> INFO: 6281600 events read in total (616443ms).
[10:59:13.818] <TB1> INFO: Test took 617305ms.
[10:59:34.566] <TB1> INFO: ROC 0 VthrComp = 104
[10:59:34.566] <TB1> INFO: ROC 1 VthrComp = 91
[10:59:34.566] <TB1> INFO: ROC 2 VthrComp = 99
[10:59:34.566] <TB1> INFO: ROC 3 VthrComp = 90
[10:59:34.566] <TB1> INFO: ROC 4 VthrComp = 83
[10:59:34.566] <TB1> INFO: ROC 5 VthrComp = 85
[10:59:34.567] <TB1> INFO: ROC 6 VthrComp = 92
[10:59:34.567] <TB1> INFO: ROC 7 VthrComp = 91
[10:59:34.567] <TB1> INFO: ROC 8 VthrComp = 87
[10:59:34.567] <TB1> INFO: ROC 9 VthrComp = 87
[10:59:34.567] <TB1> INFO: ROC 10 VthrComp = 88
[10:59:34.567] <TB1> INFO: ROC 11 VthrComp = 85
[10:59:34.567] <TB1> INFO: ROC 12 VthrComp = 87
[10:59:34.567] <TB1> INFO: ROC 13 VthrComp = 95
[10:59:34.567] <TB1> INFO: ROC 14 VthrComp = 93
[10:59:34.568] <TB1> INFO: ROC 15 VthrComp = 89
[10:59:34.568] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:59:34.568] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:59:34.576] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:59:34.576] <TB1> INFO: run 1 of 1
[10:59:34.845] <TB1> INFO: Expecting 6281600 events.
[11:01:16.943] <TB1> INFO: 756830 events read in total (101541ms).
[11:02:58.726] <TB1> INFO: 1512030 events read in total (203324ms).
[11:04:40.902] <TB1> INFO: 2267430 events read in total (305500ms).
[11:06:23.355] <TB1> INFO: 3024100 events read in total (407953ms).
[11:08:04.882] <TB1> INFO: 3775970 events read in total (509480ms).
[11:09:47.012] <TB1> INFO: 4524550 events read in total (611610ms).
[11:11:29.024] <TB1> INFO: 5272080 events read in total (713622ms).
[11:13:11.416] <TB1> INFO: 6019280 events read in total (816014ms).
[11:13:47.283] <TB1> INFO: 6281600 events read in total (851881ms).
[11:13:47.334] <TB1> INFO: Test took 852758ms.
[11:14:11.871] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 60.4032 for pixel 17/5 mean/min/max = 47.0543/33.6255/60.4831
[11:14:11.871] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 56.3495 for pixel 0/65 mean/min/max = 44.5021/32.504/56.5002
[11:14:11.872] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 57.064 for pixel 0/3 mean/min/max = 44.4045/31.6285/57.1804
[11:14:11.872] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 55.2543 for pixel 0/50 mean/min/max = 43.9279/32.5304/55.3253
[11:14:11.872] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 55.6887 for pixel 8/22 mean/min/max = 44.2144/32.3168/56.112
[11:14:11.872] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 55.5978 for pixel 6/79 mean/min/max = 44.1412/32.5166/55.7659
[11:14:11.873] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 56.4068 for pixel 46/21 mean/min/max = 45.0318/33.6482/56.4154
[11:14:11.873] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 56.9905 for pixel 4/4 mean/min/max = 44.4825/31.9255/57.0394
[11:14:11.873] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 60.2538 for pixel 25/72 mean/min/max = 46.3481/32.0588/60.6374
[11:14:11.873] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 60.8957 for pixel 21/0 mean/min/max = 46.3793/31.7256/61.033
[11:14:11.873] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 61.0423 for pixel 24/45 mean/min/max = 46.4496/31.8287/61.0704
[11:14:11.874] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 57.5847 for pixel 4/44 mean/min/max = 44.6812/31.7692/57.5932
[11:14:11.874] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 56.6342 for pixel 10/16 mean/min/max = 44.4302/32.1736/56.6868
[11:14:11.874] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 58.6949 for pixel 2/19 mean/min/max = 45.2054/31.5906/58.8202
[11:14:11.874] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 56.2151 for pixel 24/0 mean/min/max = 44.3305/32.2962/56.3647
[11:14:11.875] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 57.1906 for pixel 48/5 mean/min/max = 45.0745/32.8914/57.2576
[11:14:11.875] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:14:11.964] <TB1> INFO: Expecting 514560 events.
[11:14:50.951] <TB1> INFO: 514560 events read in total (38431ms).
[11:14:50.958] <TB1> INFO: Expecting 514560 events.
[11:15:30.043] <TB1> INFO: 514560 events read in total (38683ms).
[11:15:30.054] <TB1> INFO: Expecting 514560 events.
[11:16:09.503] <TB1> INFO: 514560 events read in total (39047ms).
[11:16:09.515] <TB1> INFO: Expecting 514560 events.
[11:16:48.556] <TB1> INFO: 514560 events read in total (38639ms).
[11:16:48.570] <TB1> INFO: Expecting 514560 events.
[11:17:27.979] <TB1> INFO: 514560 events read in total (39007ms).
[11:17:27.996] <TB1> INFO: Expecting 514560 events.
[11:18:07.148] <TB1> INFO: 514560 events read in total (38750ms).
[11:18:07.171] <TB1> INFO: Expecting 514560 events.
[11:18:46.503] <TB1> INFO: 514560 events read in total (38930ms).
[11:18:46.526] <TB1> INFO: Expecting 514560 events.
[11:19:25.754] <TB1> INFO: 514560 events read in total (38827ms).
[11:19:25.779] <TB1> INFO: Expecting 514560 events.
[11:20:04.996] <TB1> INFO: 514560 events read in total (38815ms).
[11:20:05.023] <TB1> INFO: Expecting 514560 events.
[11:20:44.534] <TB1> INFO: 514560 events read in total (39109ms).
[11:20:44.564] <TB1> INFO: Expecting 514560 events.
[11:21:24.042] <TB1> INFO: 514560 events read in total (39076ms).
[11:21:24.077] <TB1> INFO: Expecting 514560 events.
[11:22:03.538] <TB1> INFO: 514560 events read in total (39059ms).
[11:22:03.576] <TB1> INFO: Expecting 514560 events.
[11:22:43.168] <TB1> INFO: 514560 events read in total (39190ms).
[11:22:43.240] <TB1> INFO: Expecting 514560 events.
[11:23:22.553] <TB1> INFO: 514560 events read in total (38911ms).
[11:23:22.613] <TB1> INFO: Expecting 514560 events.
[11:24:01.876] <TB1> INFO: 514560 events read in total (38861ms).
[11:24:01.919] <TB1> INFO: Expecting 514560 events.
[11:24:41.207] <TB1> INFO: 514560 events read in total (38887ms).
[11:24:41.268] <TB1> INFO: Test took 629393ms.
[11:24:42.500] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[11:24:42.510] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:24:42.510] <TB1> INFO: run 1 of 1
[11:24:42.800] <TB1> INFO: Expecting 6281600 events.
[11:26:26.282] <TB1> INFO: 745660 events read in total (102926ms).
[11:28:08.676] <TB1> INFO: 1490320 events read in total (205320ms).
[11:29:50.930] <TB1> INFO: 2234780 events read in total (307574ms).
[11:31:33.077] <TB1> INFO: 2979910 events read in total (409721ms).
[11:33:15.758] <TB1> INFO: 3718580 events read in total (512403ms).
[11:34:57.051] <TB1> INFO: 4452980 events read in total (613695ms).
[11:36:38.343] <TB1> INFO: 5185320 events read in total (714987ms).
[11:38:19.019] <TB1> INFO: 5916510 events read in total (815663ms).
[11:39:09.500] <TB1> INFO: 6281600 events read in total (866144ms).
[11:39:09.557] <TB1> INFO: Test took 867047ms.
[11:39:32.937] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 11.627529 .. 52.031752
[11:39:33.211] <TB1> INFO: Expecting 208000 events.
[11:40:05.161] <TB1> INFO: 208000 events read in total (31393ms).
[11:40:05.162] <TB1> INFO: Test took 32224ms.
[11:40:05.248] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 1 .. 62 (-1/-1) hits flags = 528 (plus default)
[11:40:05.257] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:40:05.257] <TB1> INFO: run 1 of 1
[11:40:05.528] <TB1> INFO: Expecting 2579200 events.
[11:41:50.416] <TB1> INFO: 932260 events read in total (104332ms).
[11:43:36.460] <TB1> INFO: 1861140 events read in total (210376ms).
[11:44:58.371] <TB1> INFO: 2579200 events read in total (292287ms).
[11:44:58.395] <TB1> INFO: Test took 293138ms.
[11:45:13.115] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 21.352302 .. 46.553861
[11:45:13.400] <TB1> INFO: Expecting 208000 events.
[11:45:45.285] <TB1> INFO: 208000 events read in total (31329ms).
[11:45:45.286] <TB1> INFO: Test took 32169ms.
[11:45:45.370] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 11 .. 56 (-1/-1) hits flags = 528 (plus default)
[11:45:45.378] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:45:45.378] <TB1> INFO: run 1 of 1
[11:45:45.638] <TB1> INFO: Expecting 1913600 events.
[11:47:30.835] <TB1> INFO: 912950 events read in total (104641ms).
[11:49:16.673] <TB1> INFO: 1823320 events read in total (210480ms).
[11:49:27.411] <TB1> INFO: 1913600 events read in total (221217ms).
[11:49:27.428] <TB1> INFO: Test took 222051ms.
[11:49:41.986] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 21.651656 .. 44.113688
[11:49:42.247] <TB1> INFO: Expecting 208000 events.
[11:50:14.309] <TB1> INFO: 208000 events read in total (31506ms).
[11:50:14.310] <TB1> INFO: Test took 32323ms.
[11:50:14.415] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 11 .. 54 (-1/-1) hits flags = 528 (plus default)
[11:50:14.424] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:50:14.424] <TB1> INFO: run 1 of 1
[11:50:14.718] <TB1> INFO: Expecting 1830400 events.
[11:52:00.095] <TB1> INFO: 928900 events read in total (104821ms).
[11:53:42.721] <TB1> INFO: 1830400 events read in total (207447ms).
[11:53:42.743] <TB1> INFO: Test took 208319ms.
[11:53:57.440] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 24.096567 .. 42.618899
[11:53:57.702] <TB1> INFO: Expecting 208000 events.
[11:54:29.895] <TB1> INFO: 208000 events read in total (31636ms).
[11:54:29.896] <TB1> INFO: Test took 32454ms.
[11:54:29.997] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 14 .. 52 (-1/-1) hits flags = 528 (plus default)
[11:54:30.006] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:54:30.006] <TB1> INFO: run 1 of 1
[11:54:30.283] <TB1> INFO: Expecting 1622400 events.
[11:56:15.820] <TB1> INFO: 927340 events read in total (104981ms).
[11:57:35.120] <TB1> INFO: 1622400 events read in total (184281ms).
[11:57:35.137] <TB1> INFO: Test took 185131ms.
[11:57:47.668] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:57:47.668] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:57:47.676] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:57:47.676] <TB1> INFO: run 1 of 1
[11:57:47.950] <TB1> INFO: Expecting 1705600 events.
[11:59:33.327] <TB1> INFO: 894200 events read in total (104821ms).
[12:01:09.151] <TB1> INFO: 1705600 events read in total (200645ms).
[12:01:09.169] <TB1> INFO: Test took 201493ms.
[12:01:24.344] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C0.dat
[12:01:24.344] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C1.dat
[12:01:24.344] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C2.dat
[12:01:24.345] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C3.dat
[12:01:24.345] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C4.dat
[12:01:24.345] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C5.dat
[12:01:24.345] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C6.dat
[12:01:24.345] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C7.dat
[12:01:24.346] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C8.dat
[12:01:24.346] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C9.dat
[12:01:24.346] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C10.dat
[12:01:24.346] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C11.dat
[12:01:24.346] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C12.dat
[12:01:24.346] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C13.dat
[12:01:24.346] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C14.dat
[12:01:24.347] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C15.dat
[12:01:24.347] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C0.dat
[12:01:24.358] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C1.dat
[12:01:24.366] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C2.dat
[12:01:24.372] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C3.dat
[12:01:24.381] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C4.dat
[12:01:24.390] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C5.dat
[12:01:24.397] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C6.dat
[12:01:24.403] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C7.dat
[12:01:24.411] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C8.dat
[12:01:24.418] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C9.dat
[12:01:24.427] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C10.dat
[12:01:24.437] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C11.dat
[12:01:24.447] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C12.dat
[12:01:24.458] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C13.dat
[12:01:24.465] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C14.dat
[12:01:24.471] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//trimParameters35_C15.dat
[12:01:24.477] <TB1> INFO: PixTestTrim::trimTest() done
[12:01:24.477] <TB1> INFO: vtrim: 106 100 117 89 98 87 104 92 114 125 118 110 106 102 104 101
[12:01:24.477] <TB1> INFO: vthrcomp: 104 91 99 90 83 85 92 91 87 87 88 85 87 95 93 89
[12:01:24.477] <TB1> INFO: vcal mean: 35.00 35.00 35.00 34.96 35.01 35.00 34.99 34.98 34.99 34.98 35.01 34.98 34.98 34.99 34.99 34.96
[12:01:24.477] <TB1> INFO: vcal RMS: 1.13 0.82 0.82 0.79 0.80 0.77 0.80 0.81 0.90 0.88 0.85 0.80 0.81 0.84 0.85 0.77
[12:01:24.477] <TB1> INFO: bits mean: 8.69 9.56 9.57 9.58 10.06 9.54 9.32 9.59 9.86 9.69 9.52 9.89 9.74 9.26 10.03 9.36
[12:01:24.477] <TB1> INFO: bits RMS: 2.67 2.66 2.76 2.64 2.42 2.69 2.53 2.72 2.44 2.60 2.67 2.59 2.63 2.86 2.45 2.61
[12:01:24.484] <TB1> INFO: ----------------------------------------------------------------------
[12:01:24.484] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[12:01:24.484] <TB1> INFO: ----------------------------------------------------------------------
[12:01:24.488] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[12:01:24.496] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[12:01:24.496] <TB1> INFO: run 1 of 1
[12:01:24.798] <TB1> INFO: Expecting 4160000 events.
[12:03:12.388] <TB1> INFO: 906900 events read in total (107034ms).
[12:04:59.682] <TB1> INFO: 1808725 events read in total (214328ms).
[12:06:46.906] <TB1> INFO: 2704750 events read in total (321553ms).
[12:08:33.767] <TB1> INFO: 3597985 events read in total (428413ms).
[12:09:41.453] <TB1> INFO: 4160000 events read in total (496099ms).
[12:09:41.512] <TB1> INFO: Test took 497015ms.
[12:10:10.031] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 157 (-1/-1) hits flags = 528 (plus default)
[12:10:10.039] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[12:10:10.039] <TB1> INFO: run 1 of 1
[12:10:10.322] <TB1> INFO: Expecting 3286400 events.
[12:11:57.990] <TB1> INFO: 962075 events read in total (107111ms).
[12:13:45.672] <TB1> INFO: 1915690 events read in total (214793ms).
[12:15:33.629] <TB1> INFO: 2860470 events read in total (322750ms).
[12:16:22.193] <TB1> INFO: 3286400 events read in total (371314ms).
[12:16:22.245] <TB1> INFO: Test took 372206ms.
[12:16:48.087] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 146 (-1/-1) hits flags = 528 (plus default)
[12:16:48.097] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[12:16:48.097] <TB1> INFO: run 1 of 1
[12:16:48.371] <TB1> INFO: Expecting 3057600 events.
[12:18:37.939] <TB1> INFO: 994310 events read in total (109012ms).
[12:20:26.457] <TB1> INFO: 1977090 events read in total (217530ms).
[12:22:15.074] <TB1> INFO: 2952885 events read in total (326147ms).
[12:22:26.773] <TB1> INFO: 3057600 events read in total (337846ms).
[12:22:26.811] <TB1> INFO: Test took 338714ms.
[12:22:49.149] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 146 (-1/-1) hits flags = 528 (plus default)
[12:22:49.158] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[12:22:49.158] <TB1> INFO: run 1 of 1
[12:22:49.434] <TB1> INFO: Expecting 3057600 events.
[12:24:39.161] <TB1> INFO: 992550 events read in total (109171ms).
[12:26:27.861] <TB1> INFO: 1974005 events read in total (217871ms).
[12:28:16.645] <TB1> INFO: 2948550 events read in total (326655ms).
[12:28:28.692] <TB1> INFO: 3057600 events read in total (338702ms).
[12:28:28.729] <TB1> INFO: Test took 339571ms.
[12:28:51.951] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 528 (plus default)
[12:28:51.960] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[12:28:51.960] <TB1> INFO: run 1 of 1
[12:28:52.220] <TB1> INFO: Expecting 3120000 events.
[12:30:41.227] <TB1> INFO: 981745 events read in total (108451ms).
[12:32:29.384] <TB1> INFO: 1952930 events read in total (216608ms).
[12:34:16.932] <TB1> INFO: 2916625 events read in total (324156ms).
[12:34:39.660] <TB1> INFO: 3120000 events read in total (346884ms).
[12:34:39.700] <TB1> INFO: Test took 347740ms.
[12:35:04.254] <TB1> INFO: PixTestTrim::trimBitTest() done
[12:35:04.255] <TB1> INFO: PixTestTrim::doTest() done, duration: 6367 seconds
[12:35:04.255] <TB1> INFO: Decoding statistics:
[12:35:04.255] <TB1> INFO: General information:
[12:35:04.255] <TB1> INFO: 16bit words read: 0
[12:35:04.255] <TB1> INFO: valid events total: 0
[12:35:04.255] <TB1> INFO: empty events: 0
[12:35:04.255] <TB1> INFO: valid events with pixels: 0
[12:35:04.255] <TB1> INFO: valid pixel hits: 0
[12:35:04.255] <TB1> INFO: Event errors: 0
[12:35:04.255] <TB1> INFO: start marker: 0
[12:35:04.255] <TB1> INFO: stop marker: 0
[12:35:04.255] <TB1> INFO: overflow: 0
[12:35:04.255] <TB1> INFO: invalid 5bit words: 0
[12:35:04.255] <TB1> INFO: invalid XOR eye diagram: 0
[12:35:04.255] <TB1> INFO: frame (failed synchr.): 0
[12:35:04.255] <TB1> INFO: idle data (no TBM trl): 0
[12:35:04.255] <TB1> INFO: no data (only TBM hdr): 0
[12:35:04.255] <TB1> INFO: TBM errors: 0
[12:35:04.255] <TB1> INFO: flawed TBM headers: 0
[12:35:04.255] <TB1> INFO: flawed TBM trailers: 0
[12:35:04.255] <TB1> INFO: event ID mismatches: 0
[12:35:04.255] <TB1> INFO: ROC errors: 0
[12:35:04.255] <TB1> INFO: missing ROC header(s): 0
[12:35:04.255] <TB1> INFO: misplaced readback start: 0
[12:35:04.255] <TB1> INFO: Pixel decoding errors: 0
[12:35:04.255] <TB1> INFO: pixel data incomplete: 0
[12:35:04.255] <TB1> INFO: pixel address: 0
[12:35:04.255] <TB1> INFO: pulse height fill bit: 0
[12:35:04.255] <TB1> INFO: buffer corruption: 0
[12:35:04.939] <TB1> INFO: ######################################################################
[12:35:04.939] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[12:35:04.939] <TB1> INFO: ######################################################################
[12:35:05.206] <TB1> INFO: Expecting 41600 events.
[12:35:13.161] <TB1> INFO: 41600 events read in total (7398ms).
[12:35:13.162] <TB1> INFO: Test took 8222ms.
[12:35:13.626] <TB1> INFO: Expecting 41600 events.
[12:35:21.567] <TB1> INFO: 41600 events read in total (7385ms).
[12:35:21.568] <TB1> INFO: Test took 8213ms.
[12:35:21.892] <TB1> INFO: Expecting 41600 events.
[12:35:29.779] <TB1> INFO: 41600 events read in total (7331ms).
[12:35:29.779] <TB1> INFO: Test took 8184ms.
[12:35:30.034] <TB1> INFO: Expecting 2560 events.
[12:35:31.010] <TB1> INFO: 2560 events read in total (420ms).
[12:35:31.010] <TB1> INFO: Test took 1218ms.
[12:35:31.314] <TB1> INFO: Expecting 2560 events.
[12:35:32.288] <TB1> INFO: 2560 events read in total (417ms).
[12:35:32.288] <TB1> INFO: Test took 1277ms.
[12:35:32.593] <TB1> INFO: Expecting 2560 events.
[12:35:33.568] <TB1> INFO: 2560 events read in total (419ms).
[12:35:33.568] <TB1> INFO: Test took 1280ms.
[12:35:33.874] <TB1> INFO: Expecting 2560 events.
[12:35:34.849] <TB1> INFO: 2560 events read in total (419ms).
[12:35:34.849] <TB1> INFO: Test took 1280ms.
[12:35:35.154] <TB1> INFO: Expecting 2560 events.
[12:35:36.144] <TB1> INFO: 2560 events read in total (434ms).
[12:35:36.144] <TB1> INFO: Test took 1285ms.
[12:35:36.450] <TB1> INFO: Expecting 2560 events.
[12:35:37.438] <TB1> INFO: 2560 events read in total (431ms).
[12:35:37.438] <TB1> INFO: Test took 1294ms.
[12:35:37.743] <TB1> INFO: Expecting 2560 events.
[12:35:38.732] <TB1> INFO: 2560 events read in total (433ms).
[12:35:38.732] <TB1> INFO: Test took 1294ms.
[12:35:39.037] <TB1> INFO: Expecting 2560 events.
[12:35:40.025] <TB1> INFO: 2560 events read in total (432ms).
[12:35:40.025] <TB1> INFO: Test took 1292ms.
[12:35:40.330] <TB1> INFO: Expecting 2560 events.
[12:35:41.308] <TB1> INFO: 2560 events read in total (422ms).
[12:35:41.308] <TB1> INFO: Test took 1283ms.
[12:35:41.613] <TB1> INFO: Expecting 2560 events.
[12:35:42.587] <TB1> INFO: 2560 events read in total (418ms).
[12:35:42.587] <TB1> INFO: Test took 1279ms.
[12:35:42.892] <TB1> INFO: Expecting 2560 events.
[12:35:43.866] <TB1> INFO: 2560 events read in total (418ms).
[12:35:43.866] <TB1> INFO: Test took 1278ms.
[12:35:44.170] <TB1> INFO: Expecting 2560 events.
[12:35:45.146] <TB1> INFO: 2560 events read in total (420ms).
[12:35:45.147] <TB1> INFO: Test took 1278ms.
[12:35:45.451] <TB1> INFO: Expecting 2560 events.
[12:35:46.437] <TB1> INFO: 2560 events read in total (430ms).
[12:35:46.438] <TB1> INFO: Test took 1285ms.
[12:35:46.743] <TB1> INFO: Expecting 2560 events.
[12:35:47.732] <TB1> INFO: 2560 events read in total (433ms).
[12:35:47.732] <TB1> INFO: Test took 1294ms.
[12:35:48.037] <TB1> INFO: Expecting 2560 events.
[12:35:49.012] <TB1> INFO: 2560 events read in total (418ms).
[12:35:49.012] <TB1> INFO: Test took 1278ms.
[12:35:49.316] <TB1> INFO: Expecting 2560 events.
[12:35:50.303] <TB1> INFO: 2560 events read in total (431ms).
[12:35:50.303] <TB1> INFO: Test took 1290ms.
[12:35:50.307] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:35:50.609] <TB1> INFO: Expecting 655360 events.
[12:36:41.548] <TB1> INFO: 655360 events read in total (50383ms).
[12:36:41.559] <TB1> INFO: Expecting 655360 events.
[12:37:32.275] <TB1> INFO: 655360 events read in total (50314ms).
[12:37:32.289] <TB1> INFO: Expecting 655360 events.
[12:38:22.782] <TB1> INFO: 655360 events read in total (50091ms).
[12:38:22.802] <TB1> INFO: Expecting 655360 events.
[12:39:13.568] <TB1> INFO: 655360 events read in total (50364ms).
[12:39:13.599] <TB1> INFO: Expecting 655360 events.
[12:40:04.613] <TB1> INFO: 655360 events read in total (50612ms).
[12:40:04.645] <TB1> INFO: Expecting 655360 events.
[12:40:55.552] <TB1> INFO: 655360 events read in total (50505ms).
[12:40:55.585] <TB1> INFO: Expecting 655360 events.
[12:41:46.490] <TB1> INFO: 655360 events read in total (50503ms).
[12:41:46.526] <TB1> INFO: Expecting 655360 events.
[12:42:37.295] <TB1> INFO: 655360 events read in total (50367ms).
[12:42:37.338] <TB1> INFO: Expecting 655360 events.
[12:43:28.296] <TB1> INFO: 655360 events read in total (50556ms).
[12:43:28.340] <TB1> INFO: Expecting 655360 events.
[12:44:19.187] <TB1> INFO: 655360 events read in total (50445ms).
[12:44:19.244] <TB1> INFO: Expecting 655360 events.
[12:45:10.370] <TB1> INFO: 655360 events read in total (50724ms).
[12:45:10.424] <TB1> INFO: Expecting 655360 events.
[12:46:01.384] <TB1> INFO: 655360 events read in total (50558ms).
[12:46:01.446] <TB1> INFO: Expecting 655360 events.
[12:46:52.499] <TB1> INFO: 655360 events read in total (50651ms).
[12:46:52.560] <TB1> INFO: Expecting 655360 events.
[12:47:43.506] <TB1> INFO: 655360 events read in total (50545ms).
[12:47:43.588] <TB1> INFO: Expecting 655360 events.
[12:48:34.500] <TB1> INFO: 655360 events read in total (50510ms).
[12:48:34.570] <TB1> INFO: Expecting 655360 events.
[12:49:25.704] <TB1> INFO: 655360 events read in total (50732ms).
[12:49:25.779] <TB1> INFO: Test took 815472ms.
[12:49:25.876] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:49:26.104] <TB1> INFO: Expecting 655360 events.
[12:50:17.092] <TB1> INFO: 655360 events read in total (50432ms).
[12:50:17.103] <TB1> INFO: Expecting 655360 events.
[12:51:07.939] <TB1> INFO: 655360 events read in total (50434ms).
[12:51:07.954] <TB1> INFO: Expecting 655360 events.
[12:51:58.955] <TB1> INFO: 655360 events read in total (50599ms).
[12:51:58.976] <TB1> INFO: Expecting 655360 events.
[12:52:49.987] <TB1> INFO: 655360 events read in total (50608ms).
[12:52:50.011] <TB1> INFO: Expecting 655360 events.
[12:53:40.913] <TB1> INFO: 655360 events read in total (50500ms).
[12:53:40.942] <TB1> INFO: Expecting 655360 events.
[12:54:31.759] <TB1> INFO: 655360 events read in total (50415ms).
[12:54:31.792] <TB1> INFO: Expecting 655360 events.
[12:55:22.564] <TB1> INFO: 655360 events read in total (50370ms).
[12:55:22.604] <TB1> INFO: Expecting 655360 events.
[12:56:13.607] <TB1> INFO: 655360 events read in total (50601ms).
[12:56:13.652] <TB1> INFO: Expecting 655360 events.
[12:57:04.589] <TB1> INFO: 655360 events read in total (50535ms).
[12:57:04.635] <TB1> INFO: Expecting 655360 events.
[12:57:55.666] <TB1> INFO: 655360 events read in total (50629ms).
[12:57:55.720] <TB1> INFO: Expecting 655360 events.
[12:58:46.601] <TB1> INFO: 655360 events read in total (50479ms).
[12:58:46.656] <TB1> INFO: Expecting 655360 events.
[12:59:37.604] <TB1> INFO: 655360 events read in total (50546ms).
[12:59:37.684] <TB1> INFO: Expecting 655360 events.
[13:00:28.520] <TB1> INFO: 655360 events read in total (50434ms).
[13:00:28.584] <TB1> INFO: Expecting 655360 events.
[13:01:19.677] <TB1> INFO: 655360 events read in total (50691ms).
[13:01:19.749] <TB1> INFO: Expecting 655360 events.
[13:02:10.525] <TB1> INFO: 655360 events read in total (50374ms).
[13:02:10.602] <TB1> INFO: Expecting 655360 events.
[13:03:01.710] <TB1> INFO: 655360 events read in total (50706ms).
[13:03:01.791] <TB1> INFO: Test took 815915ms.
[13:03:01.997] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:03:02.004] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:03:02.011] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:03:02.018] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[13:03:02.025] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[13:03:02.032] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:03:02.039] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:03:02.046] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:03:02.054] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:03:02.061] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:03:02.069] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:03:02.076] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:03:02.084] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:03:02.090] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:03:02.097] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:03:02.104] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:03:02.111] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:03:02.118] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:03:02.157] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C0.dat
[13:03:02.157] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C1.dat
[13:03:02.157] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C2.dat
[13:03:02.157] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C3.dat
[13:03:02.157] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C4.dat
[13:03:02.157] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C5.dat
[13:03:02.157] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C6.dat
[13:03:02.157] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C7.dat
[13:03:02.157] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C8.dat
[13:03:02.157] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C9.dat
[13:03:02.158] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C10.dat
[13:03:02.158] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C11.dat
[13:03:02.158] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C12.dat
[13:03:02.158] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C13.dat
[13:03:02.158] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C14.dat
[13:03:02.158] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//dacParameters35_C15.dat
[13:03:02.426] <TB1> INFO: Expecting 41600 events.
[13:03:09.957] <TB1> INFO: 41600 events read in total (6975ms).
[13:03:09.957] <TB1> INFO: Test took 7796ms.
[13:03:10.400] <TB1> INFO: Expecting 41600 events.
[13:03:17.859] <TB1> INFO: 41600 events read in total (6903ms).
[13:03:17.860] <TB1> INFO: Test took 7722ms.
[13:03:18.306] <TB1> INFO: Expecting 41600 events.
[13:03:25.837] <TB1> INFO: 41600 events read in total (6975ms).
[13:03:25.838] <TB1> INFO: Test took 7797ms.
[13:03:26.024] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:26.114] <TB1> INFO: Expecting 2560 events.
[13:03:27.109] <TB1> INFO: 2560 events read in total (439ms).
[13:03:27.109] <TB1> INFO: Test took 1085ms.
[13:03:27.111] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:27.415] <TB1> INFO: Expecting 2560 events.
[13:03:28.413] <TB1> INFO: 2560 events read in total (442ms).
[13:03:28.413] <TB1> INFO: Test took 1302ms.
[13:03:28.416] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:28.719] <TB1> INFO: Expecting 2560 events.
[13:03:29.709] <TB1> INFO: 2560 events read in total (433ms).
[13:03:29.709] <TB1> INFO: Test took 1293ms.
[13:03:29.711] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:30.015] <TB1> INFO: Expecting 2560 events.
[13:03:31.011] <TB1> INFO: 2560 events read in total (440ms).
[13:03:31.011] <TB1> INFO: Test took 1300ms.
[13:03:31.014] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:31.318] <TB1> INFO: Expecting 2560 events.
[13:03:32.319] <TB1> INFO: 2560 events read in total (439ms).
[13:03:32.319] <TB1> INFO: Test took 1305ms.
[13:03:32.322] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:32.624] <TB1> INFO: Expecting 2560 events.
[13:03:33.614] <TB1> INFO: 2560 events read in total (433ms).
[13:03:33.614] <TB1> INFO: Test took 1293ms.
[13:03:33.617] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:33.920] <TB1> INFO: Expecting 2560 events.
[13:03:34.911] <TB1> INFO: 2560 events read in total (434ms).
[13:03:34.911] <TB1> INFO: Test took 1294ms.
[13:03:34.913] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:35.217] <TB1> INFO: Expecting 2560 events.
[13:03:36.210] <TB1> INFO: 2560 events read in total (437ms).
[13:03:36.210] <TB1> INFO: Test took 1297ms.
[13:03:36.213] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:36.515] <TB1> INFO: Expecting 2560 events.
[13:03:37.510] <TB1> INFO: 2560 events read in total (439ms).
[13:03:37.511] <TB1> INFO: Test took 1298ms.
[13:03:37.513] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:37.816] <TB1> INFO: Expecting 2560 events.
[13:03:38.805] <TB1> INFO: 2560 events read in total (433ms).
[13:03:38.805] <TB1> INFO: Test took 1292ms.
[13:03:38.808] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:39.110] <TB1> INFO: Expecting 2560 events.
[13:03:40.098] <TB1> INFO: 2560 events read in total (432ms).
[13:03:40.098] <TB1> INFO: Test took 1291ms.
[13:03:40.100] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:40.404] <TB1> INFO: Expecting 2560 events.
[13:03:41.395] <TB1> INFO: 2560 events read in total (435ms).
[13:03:41.396] <TB1> INFO: Test took 1296ms.
[13:03:41.398] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:41.701] <TB1> INFO: Expecting 2560 events.
[13:03:42.692] <TB1> INFO: 2560 events read in total (435ms).
[13:03:42.692] <TB1> INFO: Test took 1294ms.
[13:03:42.699] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:42.994] <TB1> INFO: Expecting 2560 events.
[13:03:43.986] <TB1> INFO: 2560 events read in total (435ms).
[13:03:43.986] <TB1> INFO: Test took 1287ms.
[13:03:43.989] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:44.292] <TB1> INFO: Expecting 2560 events.
[13:03:45.281] <TB1> INFO: 2560 events read in total (433ms).
[13:03:45.281] <TB1> INFO: Test took 1292ms.
[13:03:45.284] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:45.586] <TB1> INFO: Expecting 2560 events.
[13:03:46.574] <TB1> INFO: 2560 events read in total (432ms).
[13:03:46.574] <TB1> INFO: Test took 1290ms.
[13:03:46.577] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:46.879] <TB1> INFO: Expecting 2560 events.
[13:03:47.872] <TB1> INFO: 2560 events read in total (437ms).
[13:03:47.872] <TB1> INFO: Test took 1295ms.
[13:03:47.876] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:48.178] <TB1> INFO: Expecting 2560 events.
[13:03:49.173] <TB1> INFO: 2560 events read in total (439ms).
[13:03:49.173] <TB1> INFO: Test took 1297ms.
[13:03:49.176] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:49.480] <TB1> INFO: Expecting 2560 events.
[13:03:50.456] <TB1> INFO: 2560 events read in total (420ms).
[13:03:50.456] <TB1> INFO: Test took 1280ms.
[13:03:50.460] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:50.762] <TB1> INFO: Expecting 2560 events.
[13:03:51.755] <TB1> INFO: 2560 events read in total (437ms).
[13:03:51.755] <TB1> INFO: Test took 1296ms.
[13:03:51.758] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:52.059] <TB1> INFO: Expecting 2560 events.
[13:03:53.051] <TB1> INFO: 2560 events read in total (436ms).
[13:03:53.051] <TB1> INFO: Test took 1293ms.
[13:03:53.053] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:53.357] <TB1> INFO: Expecting 2560 events.
[13:03:54.352] <TB1> INFO: 2560 events read in total (439ms).
[13:03:54.352] <TB1> INFO: Test took 1299ms.
[13:03:54.354] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:54.657] <TB1> INFO: Expecting 2560 events.
[13:03:55.646] <TB1> INFO: 2560 events read in total (433ms).
[13:03:55.647] <TB1> INFO: Test took 1293ms.
[13:03:55.650] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:55.952] <TB1> INFO: Expecting 2560 events.
[13:03:56.940] <TB1> INFO: 2560 events read in total (432ms).
[13:03:56.940] <TB1> INFO: Test took 1291ms.
[13:03:56.942] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:57.246] <TB1> INFO: Expecting 2560 events.
[13:03:58.237] <TB1> INFO: 2560 events read in total (435ms).
[13:03:58.238] <TB1> INFO: Test took 1296ms.
[13:03:58.241] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:58.542] <TB1> INFO: Expecting 2560 events.
[13:03:59.532] <TB1> INFO: 2560 events read in total (433ms).
[13:03:59.533] <TB1> INFO: Test took 1293ms.
[13:03:59.535] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:03:59.839] <TB1> INFO: Expecting 2560 events.
[13:04:00.828] <TB1> INFO: 2560 events read in total (433ms).
[13:04:00.828] <TB1> INFO: Test took 1294ms.
[13:04:00.831] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:04:01.133] <TB1> INFO: Expecting 2560 events.
[13:04:02.125] <TB1> INFO: 2560 events read in total (436ms).
[13:04:02.125] <TB1> INFO: Test took 1294ms.
[13:04:02.128] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:04:02.429] <TB1> INFO: Expecting 2560 events.
[13:04:03.425] <TB1> INFO: 2560 events read in total (439ms).
[13:04:03.425] <TB1> INFO: Test took 1298ms.
[13:04:03.428] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:04:03.729] <TB1> INFO: Expecting 2560 events.
[13:04:04.724] <TB1> INFO: 2560 events read in total (438ms).
[13:04:04.724] <TB1> INFO: Test took 1296ms.
[13:04:04.727] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:04:05.029] <TB1> INFO: Expecting 2560 events.
[13:04:06.022] <TB1> INFO: 2560 events read in total (437ms).
[13:04:06.022] <TB1> INFO: Test took 1295ms.
[13:04:06.024] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:04:06.327] <TB1> INFO: Expecting 2560 events.
[13:04:07.319] <TB1> INFO: 2560 events read in total (435ms).
[13:04:07.319] <TB1> INFO: Test took 1295ms.
[13:04:07.797] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 1742 seconds
[13:04:07.797] <TB1> INFO: PH scale (per ROC): 87 82 103 88 94 96 83 80 84 80 90 87 89 76 92 82
[13:04:07.797] <TB1> INFO: PH offset (per ROC): 154 147 144 144 142 162 133 169 149 143 147 139 156 164 144 149
[13:04:07.802] <TB1> INFO: Decoding statistics:
[13:04:07.802] <TB1> INFO: General information:
[13:04:07.802] <TB1> INFO: 16bit words read: 86926
[13:04:07.802] <TB1> INFO: valid events total: 10240
[13:04:07.802] <TB1> INFO: empty events: 7737
[13:04:07.802] <TB1> INFO: valid events with pixels: 2503
[13:04:07.802] <TB1> INFO: valid pixel hits: 2503
[13:04:07.802] <TB1> INFO: Event errors: 0
[13:04:07.802] <TB1> INFO: start marker: 0
[13:04:07.802] <TB1> INFO: stop marker: 0
[13:04:07.802] <TB1> INFO: overflow: 0
[13:04:07.802] <TB1> INFO: invalid 5bit words: 0
[13:04:07.802] <TB1> INFO: invalid XOR eye diagram: 0
[13:04:07.802] <TB1> INFO: frame (failed synchr.): 0
[13:04:07.802] <TB1> INFO: idle data (no TBM trl): 0
[13:04:07.802] <TB1> INFO: no data (only TBM hdr): 0
[13:04:07.802] <TB1> INFO: TBM errors: 0
[13:04:07.802] <TB1> INFO: flawed TBM headers: 0
[13:04:07.802] <TB1> INFO: flawed TBM trailers: 0
[13:04:07.802] <TB1> INFO: event ID mismatches: 0
[13:04:07.802] <TB1> INFO: ROC errors: 0
[13:04:07.802] <TB1> INFO: missing ROC header(s): 0
[13:04:07.802] <TB1> INFO: misplaced readback start: 0
[13:04:07.802] <TB1> INFO: Pixel decoding errors: 0
[13:04:07.802] <TB1> INFO: pixel data incomplete: 0
[13:04:07.802] <TB1> INFO: pixel address: 0
[13:04:07.802] <TB1> INFO: pulse height fill bit: 0
[13:04:07.802] <TB1> INFO: buffer corruption: 0
[13:04:07.977] <TB1> INFO: ######################################################################
[13:04:07.977] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[13:04:07.977] <TB1> INFO: ######################################################################
[13:04:07.987] <TB1> INFO: scanning low vcal = 10
[13:04:08.251] <TB1> INFO: Expecting 41600 events.
[13:04:13.866] <TB1> INFO: 41600 events read in total (5059ms).
[13:04:13.866] <TB1> INFO: Test took 5879ms.
[13:04:13.868] <TB1> INFO: scanning low vcal = 20
[13:04:14.166] <TB1> INFO: Expecting 41600 events.
[13:04:19.796] <TB1> INFO: 41600 events read in total (5074ms).
[13:04:19.796] <TB1> INFO: Test took 5928ms.
[13:04:19.798] <TB1> INFO: scanning low vcal = 30
[13:04:20.092] <TB1> INFO: Expecting 41600 events.
[13:04:25.790] <TB1> INFO: 41600 events read in total (5142ms).
[13:04:25.790] <TB1> INFO: Test took 5992ms.
[13:04:25.792] <TB1> INFO: scanning low vcal = 40
[13:04:26.078] <TB1> INFO: Expecting 41600 events.
[13:04:34.114] <TB1> INFO: 41600 events read in total (7479ms).
[13:04:34.114] <TB1> INFO: Test took 8322ms.
[13:04:34.117] <TB1> INFO: scanning low vcal = 50
[13:04:34.377] <TB1> INFO: Expecting 41600 events.
[13:04:42.724] <TB1> INFO: 41600 events read in total (7790ms).
[13:04:42.725] <TB1> INFO: Test took 8608ms.
[13:04:42.728] <TB1> INFO: scanning low vcal = 60
[13:04:42.990] <TB1> INFO: Expecting 41600 events.
[13:04:51.400] <TB1> INFO: 41600 events read in total (7854ms).
[13:04:51.400] <TB1> INFO: Test took 8672ms.
[13:04:51.403] <TB1> INFO: scanning low vcal = 70
[13:04:51.683] <TB1> INFO: Expecting 41600 events.
[13:05:00.036] <TB1> INFO: 41600 events read in total (7796ms).
[13:05:00.036] <TB1> INFO: Test took 8633ms.
[13:05:00.039] <TB1> INFO: scanning low vcal = 80
[13:05:00.307] <TB1> INFO: Expecting 41600 events.
[13:05:08.671] <TB1> INFO: 41600 events read in total (7808ms).
[13:05:08.672] <TB1> INFO: Test took 8632ms.
[13:05:08.674] <TB1> INFO: scanning low vcal = 90
[13:05:08.934] <TB1> INFO: Expecting 41600 events.
[13:05:17.285] <TB1> INFO: 41600 events read in total (7795ms).
[13:05:17.286] <TB1> INFO: Test took 8612ms.
[13:05:17.289] <TB1> INFO: scanning low vcal = 100
[13:05:17.549] <TB1> INFO: Expecting 41600 events.
[13:05:25.893] <TB1> INFO: 41600 events read in total (7788ms).
[13:05:25.893] <TB1> INFO: Test took 8605ms.
[13:05:25.896] <TB1> INFO: scanning low vcal = 110
[13:05:26.169] <TB1> INFO: Expecting 41600 events.
[13:05:34.536] <TB1> INFO: 41600 events read in total (7811ms).
[13:05:34.537] <TB1> INFO: Test took 8641ms.
[13:05:34.540] <TB1> INFO: scanning low vcal = 120
[13:05:34.808] <TB1> INFO: Expecting 41600 events.
[13:05:43.157] <TB1> INFO: 41600 events read in total (7793ms).
[13:05:43.158] <TB1> INFO: Test took 8618ms.
[13:05:43.161] <TB1> INFO: scanning low vcal = 130
[13:05:43.427] <TB1> INFO: Expecting 41600 events.
[13:05:51.803] <TB1> INFO: 41600 events read in total (7819ms).
[13:05:51.804] <TB1> INFO: Test took 8643ms.
[13:05:51.807] <TB1> INFO: scanning low vcal = 140
[13:05:52.067] <TB1> INFO: Expecting 41600 events.
[13:06:00.445] <TB1> INFO: 41600 events read in total (7822ms).
[13:06:00.445] <TB1> INFO: Test took 8638ms.
[13:06:00.448] <TB1> INFO: scanning low vcal = 150
[13:06:00.738] <TB1> INFO: Expecting 41600 events.
[13:06:09.114] <TB1> INFO: 41600 events read in total (7819ms).
[13:06:09.115] <TB1> INFO: Test took 8667ms.
[13:06:09.118] <TB1> INFO: scanning low vcal = 160
[13:06:09.394] <TB1> INFO: Expecting 41600 events.
[13:06:17.724] <TB1> INFO: 41600 events read in total (7774ms).
[13:06:17.725] <TB1> INFO: Test took 8607ms.
[13:06:17.728] <TB1> INFO: scanning low vcal = 170
[13:06:17.988] <TB1> INFO: Expecting 41600 events.
[13:06:26.327] <TB1> INFO: 41600 events read in total (7782ms).
[13:06:26.328] <TB1> INFO: Test took 8600ms.
[13:06:26.332] <TB1> INFO: scanning low vcal = 180
[13:06:26.610] <TB1> INFO: Expecting 41600 events.
[13:06:34.949] <TB1> INFO: 41600 events read in total (7783ms).
[13:06:34.950] <TB1> INFO: Test took 8618ms.
[13:06:34.952] <TB1> INFO: scanning low vcal = 190
[13:06:35.217] <TB1> INFO: Expecting 41600 events.
[13:06:43.601] <TB1> INFO: 41600 events read in total (7828ms).
[13:06:43.601] <TB1> INFO: Test took 8649ms.
[13:06:43.604] <TB1> INFO: scanning low vcal = 200
[13:06:43.868] <TB1> INFO: Expecting 41600 events.
[13:06:52.202] <TB1> INFO: 41600 events read in total (7777ms).
[13:06:52.202] <TB1> INFO: Test took 8598ms.
[13:06:52.205] <TB1> INFO: scanning low vcal = 210
[13:06:52.465] <TB1> INFO: Expecting 41600 events.
[13:07:00.815] <TB1> INFO: 41600 events read in total (7794ms).
[13:07:00.816] <TB1> INFO: Test took 8611ms.
[13:07:00.819] <TB1> INFO: scanning low vcal = 220
[13:07:01.078] <TB1> INFO: Expecting 41600 events.
[13:07:09.451] <TB1> INFO: 41600 events read in total (7816ms).
[13:07:09.451] <TB1> INFO: Test took 8633ms.
[13:07:09.454] <TB1> INFO: scanning low vcal = 230
[13:07:09.717] <TB1> INFO: Expecting 41600 events.
[13:07:18.097] <TB1> INFO: 41600 events read in total (7824ms).
[13:07:18.098] <TB1> INFO: Test took 8644ms.
[13:07:18.101] <TB1> INFO: scanning low vcal = 240
[13:07:18.360] <TB1> INFO: Expecting 41600 events.
[13:07:26.767] <TB1> INFO: 41600 events read in total (7850ms).
[13:07:26.767] <TB1> INFO: Test took 8666ms.
[13:07:26.770] <TB1> INFO: scanning low vcal = 250
[13:07:27.066] <TB1> INFO: Expecting 41600 events.
[13:07:35.465] <TB1> INFO: 41600 events read in total (7843ms).
[13:07:35.465] <TB1> INFO: Test took 8695ms.
[13:07:35.469] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[13:07:35.763] <TB1> INFO: Expecting 41600 events.
[13:07:44.078] <TB1> INFO: 41600 events read in total (7759ms).
[13:07:44.079] <TB1> INFO: Test took 8609ms.
[13:07:44.082] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[13:07:44.345] <TB1> INFO: Expecting 41600 events.
[13:07:52.664] <TB1> INFO: 41600 events read in total (7763ms).
[13:07:52.665] <TB1> INFO: Test took 8583ms.
[13:07:52.668] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[13:07:52.949] <TB1> INFO: Expecting 41600 events.
[13:08:01.240] <TB1> INFO: 41600 events read in total (7735ms).
[13:08:01.240] <TB1> INFO: Test took 8572ms.
[13:08:01.243] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[13:08:01.505] <TB1> INFO: Expecting 41600 events.
[13:08:09.800] <TB1> INFO: 41600 events read in total (7739ms).
[13:08:09.800] <TB1> INFO: Test took 8557ms.
[13:08:09.804] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[13:08:10.065] <TB1> INFO: Expecting 41600 events.
[13:08:18.375] <TB1> INFO: 41600 events read in total (7754ms).
[13:08:18.376] <TB1> INFO: Test took 8572ms.
[13:08:18.854] <TB1> INFO: PixTestGainPedestal::measure() done
[13:08:50.961] <TB1> INFO: PixTestGainPedestal::fit() done
[13:08:50.961] <TB1> INFO: non-linearity mean: 0.954 0.951 0.956 0.954 0.953 0.956 0.951 0.944 0.957 0.956 0.956 0.951 0.949 0.952 0.957 0.954
[13:08:50.961] <TB1> INFO: non-linearity RMS: 0.005 0.006 0.004 0.005 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.007 0.006 0.005 0.006 0.006
[13:08:50.961] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[13:08:50.979] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[13:08:50.998] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[13:08:51.016] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[13:08:51.034] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[13:08:51.052] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[13:08:51.070] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[13:08:51.088] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[13:08:51.107] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[13:08:51.126] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[13:08:51.146] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[13:08:51.167] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[13:08:51.187] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[13:08:51.207] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[13:08:51.227] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[13:08:51.247] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[13:08:51.268] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 283 seconds
[13:08:51.268] <TB1> INFO: Decoding statistics:
[13:08:51.268] <TB1> INFO: General information:
[13:08:51.268] <TB1> INFO: 16bit words read: 2662360
[13:08:51.268] <TB1> INFO: valid events total: 166400
[13:08:51.268] <TB1> INFO: empty events: 0
[13:08:51.268] <TB1> INFO: valid events with pixels: 166400
[13:08:51.268] <TB1> INFO: valid pixel hits: 665580
[13:08:51.268] <TB1> INFO: Event errors: 0
[13:08:51.268] <TB1> INFO: start marker: 0
[13:08:51.268] <TB1> INFO: stop marker: 0
[13:08:51.268] <TB1> INFO: overflow: 0
[13:08:51.268] <TB1> INFO: invalid 5bit words: 0
[13:08:51.268] <TB1> INFO: invalid XOR eye diagram: 0
[13:08:51.268] <TB1> INFO: frame (failed synchr.): 0
[13:08:51.268] <TB1> INFO: idle data (no TBM trl): 0
[13:08:51.268] <TB1> INFO: no data (only TBM hdr): 0
[13:08:51.268] <TB1> INFO: TBM errors: 0
[13:08:51.268] <TB1> INFO: flawed TBM headers: 0
[13:08:51.268] <TB1> INFO: flawed TBM trailers: 0
[13:08:51.268] <TB1> INFO: event ID mismatches: 0
[13:08:51.268] <TB1> INFO: ROC errors: 0
[13:08:51.268] <TB1> INFO: missing ROC header(s): 0
[13:08:51.268] <TB1> INFO: misplaced readback start: 0
[13:08:51.268] <TB1> INFO: Pixel decoding errors: 0
[13:08:51.268] <TB1> INFO: pixel data incomplete: 0
[13:08:51.268] <TB1> INFO: pixel address: 0
[13:08:51.268] <TB1> INFO: pulse height fill bit: 0
[13:08:51.268] <TB1> INFO: buffer corruption: 0
[13:08:51.278] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C15.dat
[13:08:51.279] <TB1> INFO: ######################################################################
[13:08:51.279] <TB1> INFO: PixTestReadback::doTest()
[13:08:51.279] <TB1> INFO: ######################################################################
[13:08:51.279] <TB1> INFO: ----------------------------------------------------------------------
[13:08:51.279] <TB1> INFO: PixTestReadback::CalibrateVd()
[13:08:51.279] <TB1> INFO: ----------------------------------------------------------------------
[13:09:00.752] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C0.dat
[13:09:00.752] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C1.dat
[13:09:00.752] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C2.dat
[13:09:00.752] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C3.dat
[13:09:00.752] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C4.dat
[13:09:00.752] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C5.dat
[13:09:00.752] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C6.dat
[13:09:00.752] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C7.dat
[13:09:00.752] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C8.dat
[13:09:00.752] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C9.dat
[13:09:00.753] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C10.dat
[13:09:00.753] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C11.dat
[13:09:00.753] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C12.dat
[13:09:00.753] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C13.dat
[13:09:00.753] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C14.dat
[13:09:00.753] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C15.dat
[13:09:00.784] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[13:09:00.784] <TB1> INFO: ----------------------------------------------------------------------
[13:09:00.784] <TB1> INFO: PixTestReadback::CalibrateVa()
[13:09:00.784] <TB1> INFO: ----------------------------------------------------------------------
[13:09:10.275] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C0.dat
[13:09:10.275] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C1.dat
[13:09:10.275] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C2.dat
[13:09:10.275] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C3.dat
[13:09:10.275] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C4.dat
[13:09:10.275] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C5.dat
[13:09:10.275] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C6.dat
[13:09:10.275] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C7.dat
[13:09:10.275] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C8.dat
[13:09:10.275] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C9.dat
[13:09:10.275] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C10.dat
[13:09:10.275] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C11.dat
[13:09:10.276] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C12.dat
[13:09:10.276] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C13.dat
[13:09:10.276] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C14.dat
[13:09:10.276] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C15.dat
[13:09:10.309] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[13:09:10.309] <TB1> INFO: ----------------------------------------------------------------------
[13:09:10.309] <TB1> INFO: PixTestReadback::readbackVbg()
[13:09:10.309] <TB1> INFO: ----------------------------------------------------------------------
[13:09:17.603] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[13:09:17.603] <TB1> INFO: ----------------------------------------------------------------------
[13:09:17.603] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[13:09:17.603] <TB1> INFO: ----------------------------------------------------------------------
[13:09:17.603] <TB1> INFO: Vbg will be calibrated using Vd calibration
[13:09:17.603] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 151calibrated Vbg = 1.18405 :::*/*/*/*/
[13:09:17.603] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 144.8calibrated Vbg = 1.18421 :::*/*/*/*/
[13:09:17.603] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 145.3calibrated Vbg = 1.18476 :::*/*/*/*/
[13:09:17.603] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 147.1calibrated Vbg = 1.1934 :::*/*/*/*/
[13:09:17.603] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 152.1calibrated Vbg = 1.19818 :::*/*/*/*/
[13:09:17.603] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 152.3calibrated Vbg = 1.21046 :::*/*/*/*/
[13:09:17.603] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 146.7calibrated Vbg = 1.20117 :::*/*/*/*/
[13:09:17.603] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 146.9calibrated Vbg = 1.20227 :::*/*/*/*/
[13:09:17.603] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 155.9calibrated Vbg = 1.20421 :::*/*/*/*/
[13:09:17.603] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 140.9calibrated Vbg = 1.2064 :::*/*/*/*/
[13:09:17.603] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 153.5calibrated Vbg = 1.19956 :::*/*/*/*/
[13:09:17.603] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 155.5calibrated Vbg = 1.20056 :::*/*/*/*/
[13:09:17.603] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 155.6calibrated Vbg = 1.1825 :::*/*/*/*/
[13:09:17.603] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 153.9calibrated Vbg = 1.18924 :::*/*/*/*/
[13:09:17.603] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 153.9calibrated Vbg = 1.18582 :::*/*/*/*/
[13:09:17.603] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 147calibrated Vbg = 1.18876 :::*/*/*/*/
[13:09:17.605] <TB1> INFO: ----------------------------------------------------------------------
[13:09:17.605] <TB1> INFO: PixTestReadback::CalibrateIa()
[13:09:17.605] <TB1> INFO: ----------------------------------------------------------------------
[13:11:52.935] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C0.dat
[13:11:52.938] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C1.dat
[13:11:52.938] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C2.dat
[13:11:52.938] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C3.dat
[13:11:52.938] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C4.dat
[13:11:52.938] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C5.dat
[13:11:52.938] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C6.dat
[13:11:52.938] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C7.dat
[13:11:52.938] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C8.dat
[13:11:52.938] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C9.dat
[13:11:52.938] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C10.dat
[13:11:52.938] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C11.dat
[13:11:52.938] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C12.dat
[13:11:52.938] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C13.dat
[13:11:52.939] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C14.dat
[13:11:52.939] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//000_FulltestPxar_m20//readbackCal_C15.dat
[13:11:52.969] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[13:11:52.970] <TB1> INFO: PixTestReadback::doTest() done
[13:11:52.970] <TB1> INFO: Decoding statistics:
[13:11:52.970] <TB1> INFO: General information:
[13:11:52.970] <TB1> INFO: 16bit words read: 1024
[13:11:52.970] <TB1> INFO: valid events total: 128
[13:11:52.970] <TB1> INFO: empty events: 128
[13:11:52.970] <TB1> INFO: valid events with pixels: 0
[13:11:52.970] <TB1> INFO: valid pixel hits: 0
[13:11:52.970] <TB1> INFO: Event errors: 0
[13:11:52.970] <TB1> INFO: start marker: 0
[13:11:52.970] <TB1> INFO: stop marker: 0
[13:11:52.970] <TB1> INFO: overflow: 0
[13:11:52.970] <TB1> INFO: invalid 5bit words: 0
[13:11:52.970] <TB1> INFO: invalid XOR eye diagram: 0
[13:11:52.970] <TB1> INFO: frame (failed synchr.): 0
[13:11:52.970] <TB1> INFO: idle data (no TBM trl): 0
[13:11:52.970] <TB1> INFO: no data (only TBM hdr): 0
[13:11:52.970] <TB1> INFO: TBM errors: 0
[13:11:52.970] <TB1> INFO: flawed TBM headers: 0
[13:11:52.970] <TB1> INFO: flawed TBM trailers: 0
[13:11:52.970] <TB1> INFO: event ID mismatches: 0
[13:11:52.970] <TB1> INFO: ROC errors: 0
[13:11:52.970] <TB1> INFO: missing ROC header(s): 0
[13:11:52.970] <TB1> INFO: misplaced readback start: 0
[13:11:52.970] <TB1> INFO: Pixel decoding errors: 0
[13:11:52.970] <TB1> INFO: pixel data incomplete: 0
[13:11:52.970] <TB1> INFO: pixel address: 0
[13:11:52.970] <TB1> INFO: pulse height fill bit: 0
[13:11:52.970] <TB1> INFO: buffer corruption: 0
[13:11:52.988] <TB1> INFO: Decoding statistics:
[13:11:52.988] <TB1> INFO: General information:
[13:11:52.988] <TB1> INFO: 16bit words read: 2750310
[13:11:52.988] <TB1> INFO: valid events total: 176768
[13:11:52.988] <TB1> INFO: empty events: 7865
[13:11:52.988] <TB1> INFO: valid events with pixels: 168903
[13:11:52.988] <TB1> INFO: valid pixel hits: 668083
[13:11:52.988] <TB1> INFO: Event errors: 0
[13:11:52.988] <TB1> INFO: start marker: 0
[13:11:52.988] <TB1> INFO: stop marker: 0
[13:11:52.988] <TB1> INFO: overflow: 0
[13:11:52.988] <TB1> INFO: invalid 5bit words: 0
[13:11:52.988] <TB1> INFO: invalid XOR eye diagram: 0
[13:11:52.988] <TB1> INFO: frame (failed synchr.): 0
[13:11:52.988] <TB1> INFO: idle data (no TBM trl): 0
[13:11:52.988] <TB1> INFO: no data (only TBM hdr): 0
[13:11:52.988] <TB1> INFO: TBM errors: 0
[13:11:52.988] <TB1> INFO: flawed TBM headers: 0
[13:11:52.988] <TB1> INFO: flawed TBM trailers: 0
[13:11:52.988] <TB1> INFO: event ID mismatches: 0
[13:11:52.988] <TB1> INFO: ROC errors: 0
[13:11:52.988] <TB1> INFO: missing ROC header(s): 0
[13:11:52.988] <TB1> INFO: misplaced readback start: 0
[13:11:52.988] <TB1> INFO: Pixel decoding errors: 0
[13:11:52.988] <TB1> INFO: pixel data incomplete: 0
[13:11:52.988] <TB1> INFO: pixel address: 0
[13:11:52.988] <TB1> INFO: pulse height fill bit: 0
[13:11:52.988] <TB1> INFO: buffer corruption: 0
[13:11:52.988] <TB1> INFO: enter test to run
[13:11:52.988] <TB1> INFO: test: exit no parameter change
[13:11:53.177] <TB1> QUIET: Connection to board 153 closed.
[13:11:53.256] <TB1> INFO: pXar: this is the end, my friend