[09:58:36.174] <TB1> INFO: *** Welcome to pxar ***
[09:58:36.174] <TB1> INFO: *** Today: 2016/04/24
[09:58:36.257] <TB1> INFO: *** Version: 9751-dirty
[09:58:36.257] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters_C15.dat
[09:58:36.257] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//tbmParameters_C0b.dat
[09:58:36.257] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//defaultMaskFile.dat
[09:58:36.257] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//trimParameters_C15.dat
[09:58:36.325] <TB1> INFO: clk: 4
[09:58:36.325] <TB1> INFO: ctr: 4
[09:58:36.325] <TB1> INFO: sda: 19
[09:58:36.325] <TB1> INFO: tin: 9
[09:58:36.325] <TB1> INFO: level: 15
[09:58:36.325] <TB1> INFO: triggerdelay: 0
[09:58:36.325] <TB1> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[09:58:36.325] <TB1> INFO: Log level: INFO
[09:58:36.329] <TB1> INFO: Found DTB DTB_WXBYFL
[09:58:36.337] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[09:58:36.339] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[09:58:36.340] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[09:58:37.880] <TB1> INFO: DUT info:
[09:58:37.880] <TB1> INFO: The DUT currently contains the following objects:
[09:58:37.880] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[09:58:37.880] <TB1> INFO: TBM Core alpha (0): 7 registers set
[09:58:37.880] <TB1> INFO: TBM Core beta (1): 7 registers set
[09:58:37.880] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:58:37.881] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:37.881] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:37.881] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:37.881] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:37.881] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:37.881] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:37.881] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:37.881] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:37.881] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:37.881] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:37.881] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:37.881] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:37.881] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:37.881] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:37.881] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:37.881] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:58:38.282] <TB1> INFO: enter 'restricted' command line mode
[09:58:38.282] <TB1> INFO: enter test to run
[09:58:38.282] <TB1> INFO: test: pretest no parameter change
[09:58:38.282] <TB1> INFO: running: pretest
[09:58:38.289] <TB1> INFO: ----------------------------------------------------------------------
[09:58:38.289] <TB1> INFO: PixTestPretest::programROC()
[09:58:38.289] <TB1> INFO: ----------------------------------------------------------------------
[09:58:56.304] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[09:58:56.304] <TB1> INFO: IA differences per ROC: 20.1 20.9 19.3 19.3 18.5 18.5 20.9 19.3 18.5 20.9 20.9 17.7 19.3 19.3 19.3 19.3
[09:58:56.399] <TB1> INFO: enter test to run
[09:58:56.399] <TB1> INFO: test: pretest no parameter change
[09:58:56.399] <TB1> INFO: running: pretest
[09:58:56.400] <TB1> INFO: ----------------------------------------------------------------------
[09:58:56.400] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[09:58:56.400] <TB1> INFO: ----------------------------------------------------------------------
[09:59:02.595] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 392.3 mA = 24.5187 mA/ROC
[09:59:02.595] <TB1> INFO: i(loss) [mA/ROC]: 20.1 20.1 19.3 20.1 20.1 20.1 20.1 20.1 20.1 20.1 20.1 19.3 19.3 19.3 19.3 19.3
[09:59:02.629] <TB1> INFO: enter test to run
[09:59:02.629] <TB1> INFO: test: pretest no parameter change
[09:59:02.629] <TB1> INFO: running: pretest
[09:59:02.629] <TB1> INFO: ----------------------------------------------------------------------
[09:59:02.629] <TB1> INFO: PixTestPretest::findTiming()
[09:59:02.629] <TB1> INFO: ----------------------------------------------------------------------
[09:59:02.629] <TB1> INFO: PixTestCmd::init()

[10:00:32.001] <TB1> INFO: TBM phases: 160MHz: 0, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[10:00:32.001] <TB1> INFO: (success/tries = 100/100), width = 3
[10:00:32.002] <TB1> INFO: enter test to run
[10:00:32.002] <TB1> INFO: test: pretest no parameter change
[10:00:32.002] <TB1> INFO: running: pretest
[10:00:32.004] <TB1> INFO: ----------------------------------------------------------------------
[10:00:32.004] <TB1> INFO: PixTestPretest::findWorkingPixel()
[10:00:32.004] <TB1> INFO: ----------------------------------------------------------------------
[10:00:32.097] <TB1> INFO: Expecting 231680 events.


[10:00:42.225] <TB1> INFO: 231680 events read in total (9571ms).
[10:00:42.229] <TB1> INFO: Test took 10220ms.
[10:00:42.451] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:00:42.512] <TB1> INFO: enter test to run
[10:00:42.512] <TB1> INFO: test: pretest no parameter change
[10:00:42.512] <TB1> INFO: running: pretest
[10:00:42.514] <TB1> INFO: ----------------------------------------------------------------------
[10:00:42.514] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[10:00:42.514] <TB1> INFO: ----------------------------------------------------------------------
[10:00:42.605] <TB1> INFO: Expecting 231680 events.
[10:00:52.833] <TB1> INFO: 231680 events read in total (9671ms).
[10:00:52.837] <TB1> INFO: Test took 10320ms.
[10:00:53.073] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[10:00:53.073] <TB1> INFO: CalDel: 157 137 134 163 168 129 144 153 137 150 145 134 149 122 150 144
[10:00:53.073] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 52 51 51 53 51 51 51 51
[10:00:53.112] <TB1> INFO: enter test to run
[10:00:53.112] <TB1> INFO: test: pretest no parameter change
[10:00:53.112] <TB1> INFO: running: pretest
[10:00:53.112] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters_C0.dat
[10:00:53.112] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters_C1.dat
[10:00:53.112] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters_C2.dat
[10:00:53.112] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters_C3.dat
[10:00:53.112] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters_C4.dat
[10:00:53.112] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters_C5.dat
[10:00:53.112] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters_C6.dat
[10:00:53.112] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters_C7.dat
[10:00:53.112] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters_C8.dat
[10:00:53.112] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters_C9.dat
[10:00:53.113] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters_C10.dat
[10:00:53.113] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters_C11.dat
[10:00:53.113] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters_C12.dat
[10:00:53.113] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters_C13.dat
[10:00:53.113] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters_C14.dat
[10:00:53.113] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters_C15.dat
[10:00:53.113] <TB1> INFO: enter test to run
[10:00:53.113] <TB1> INFO: test: fulltest no parameter change
[10:00:53.113] <TB1> INFO: running: fulltest
[10:00:53.113] <TB1> INFO: ######################################################################
[10:00:53.113] <TB1> INFO: PixTestFullTest::doTest()
[10:00:53.113] <TB1> INFO: ######################################################################
[10:00:53.114] <TB1> INFO: ######################################################################
[10:00:53.114] <TB1> INFO: PixTestAlive::doTest()
[10:00:53.114] <TB1> INFO: ######################################################################
[10:00:53.115] <TB1> INFO: ----------------------------------------------------------------------
[10:00:53.115] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:00:53.115] <TB1> INFO: ----------------------------------------------------------------------
[10:00:53.376] <TB1> INFO: Expecting 41600 events.
[10:00:57.175] <TB1> INFO: 41600 events read in total (3243ms).
[10:00:57.176] <TB1> INFO: Test took 4059ms.
[10:00:57.408] <TB1> INFO: PixTestAlive::aliveTest() done
[10:00:57.408] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0
[10:00:57.409] <TB1> INFO: ----------------------------------------------------------------------
[10:00:57.409] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:00:57.409] <TB1> INFO: ----------------------------------------------------------------------
[10:00:57.683] <TB1> INFO: Expecting 41600 events.
[10:01:00.756] <TB1> INFO: 41600 events read in total (2516ms).
[10:01:00.757] <TB1> INFO: Test took 3346ms.
[10:01:00.757] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:01:01.002] <TB1> INFO: PixTestAlive::maskTest() done
[10:01:01.002] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:01:01.003] <TB1> INFO: ----------------------------------------------------------------------
[10:01:01.004] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:01:01.004] <TB1> INFO: ----------------------------------------------------------------------
[10:01:01.264] <TB1> INFO: Expecting 41600 events.
[10:01:05.048] <TB1> INFO: 41600 events read in total (3228ms).
[10:01:05.049] <TB1> INFO: Test took 4044ms.
[10:01:05.268] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[10:01:05.268] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:01:05.268] <TB1> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[10:01:05.268] <TB1> INFO: Decoding statistics:
[10:01:05.268] <TB1> INFO: General information:
[10:01:05.268] <TB1> INFO: 16bit words read: 0
[10:01:05.268] <TB1> INFO: valid events total: 0
[10:01:05.268] <TB1> INFO: empty events: 0
[10:01:05.268] <TB1> INFO: valid events with pixels: 0
[10:01:05.268] <TB1> INFO: valid pixel hits: 0
[10:01:05.268] <TB1> INFO: Event errors: 0
[10:01:05.268] <TB1> INFO: start marker: 0
[10:01:05.268] <TB1> INFO: stop marker: 0
[10:01:05.268] <TB1> INFO: overflow: 0
[10:01:05.268] <TB1> INFO: invalid 5bit words: 0
[10:01:05.268] <TB1> INFO: invalid XOR eye diagram: 0
[10:01:05.268] <TB1> INFO: frame (failed synchr.): 0
[10:01:05.268] <TB1> INFO: idle data (no TBM trl): 0
[10:01:05.268] <TB1> INFO: no data (only TBM hdr): 0
[10:01:05.268] <TB1> INFO: TBM errors: 0
[10:01:05.268] <TB1> INFO: flawed TBM headers: 0
[10:01:05.268] <TB1> INFO: flawed TBM trailers: 0
[10:01:05.268] <TB1> INFO: event ID mismatches: 0
[10:01:05.268] <TB1> INFO: ROC errors: 0
[10:01:05.268] <TB1> INFO: missing ROC header(s): 0
[10:01:05.268] <TB1> INFO: misplaced readback start: 0
[10:01:05.268] <TB1> INFO: Pixel decoding errors: 0
[10:01:05.268] <TB1> INFO: pixel data incomplete: 0
[10:01:05.268] <TB1> INFO: pixel address: 0
[10:01:05.268] <TB1> INFO: pulse height fill bit: 0
[10:01:05.268] <TB1> INFO: buffer corruption: 0
[10:01:05.274] <TB1> INFO: ######################################################################
[10:01:05.274] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:01:05.274] <TB1> INFO: ######################################################################
[10:01:05.277] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[10:01:05.372] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:01:05.372] <TB1> INFO: run 1 of 1
[10:01:05.654] <TB1> INFO: Expecting 3120000 events.
[10:01:46.253] <TB1> INFO: 848655 events read in total (40043ms).
[10:02:25.979] <TB1> INFO: 1687845 events read in total (79769ms).
[10:03:06.562] <TB1> INFO: 2539325 events read in total (120353ms).
[10:03:34.710] <TB1> INFO: 3120000 events read in total (148500ms).
[10:03:34.762] <TB1> INFO: Test took 149390ms.
[10:03:56.881] <TB1> INFO: PixTestBBMap::doTest() done, duration: 171 seconds
[10:03:56.881] <TB1> INFO: number of dead bumps (per ROC): 35 39 9 10 30 18 2 41 5 0 1 2 2 1 43 15
[10:03:56.881] <TB1> INFO: separation cut (per ROC): 80 87 76 77 74 89 85 66 96 93 103 98 89 107 67 97
[10:03:56.881] <TB1> INFO: Decoding statistics:
[10:03:56.881] <TB1> INFO: General information:
[10:03:56.881] <TB1> INFO: 16bit words read: 0
[10:03:56.881] <TB1> INFO: valid events total: 0
[10:03:56.881] <TB1> INFO: empty events: 0
[10:03:56.881] <TB1> INFO: valid events with pixels: 0
[10:03:56.881] <TB1> INFO: valid pixel hits: 0
[10:03:56.881] <TB1> INFO: Event errors: 0
[10:03:56.881] <TB1> INFO: start marker: 0
[10:03:56.881] <TB1> INFO: stop marker: 0
[10:03:56.881] <TB1> INFO: overflow: 0
[10:03:56.881] <TB1> INFO: invalid 5bit words: 0
[10:03:56.881] <TB1> INFO: invalid XOR eye diagram: 0
[10:03:56.881] <TB1> INFO: frame (failed synchr.): 0
[10:03:56.881] <TB1> INFO: idle data (no TBM trl): 0
[10:03:56.881] <TB1> INFO: no data (only TBM hdr): 0
[10:03:56.881] <TB1> INFO: TBM errors: 0
[10:03:56.881] <TB1> INFO: flawed TBM headers: 0
[10:03:56.881] <TB1> INFO: flawed TBM trailers: 0
[10:03:56.881] <TB1> INFO: event ID mismatches: 0
[10:03:56.881] <TB1> INFO: ROC errors: 0
[10:03:56.881] <TB1> INFO: missing ROC header(s): 0
[10:03:56.881] <TB1> INFO: misplaced readback start: 0
[10:03:56.881] <TB1> INFO: Pixel decoding errors: 0
[10:03:56.881] <TB1> INFO: pixel data incomplete: 0
[10:03:56.881] <TB1> INFO: pixel address: 0
[10:03:56.881] <TB1> INFO: pulse height fill bit: 0
[10:03:56.881] <TB1> INFO: buffer corruption: 0
[10:03:56.952] <TB1> INFO: ######################################################################
[10:03:56.952] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:03:56.952] <TB1> INFO: ######################################################################
[10:03:56.952] <TB1> INFO: ----------------------------------------------------------------------
[10:03:56.952] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:03:56.952] <TB1> INFO: ----------------------------------------------------------------------
[10:03:56.952] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:03:56.961] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:03:56.961] <TB1> INFO: run 1 of 1
[10:03:57.219] <TB1> INFO: Expecting 26208000 events.
[10:04:31.011] <TB1> INFO: 912950 events read in total (33236ms).
[10:05:04.807] <TB1> INFO: 1811400 events read in total (67032ms).
[10:05:38.573] <TB1> INFO: 2706550 events read in total (100798ms).
[10:06:10.678] <TB1> INFO: 3599700 events read in total (132903ms).
[10:06:44.876] <TB1> INFO: 4489950 events read in total (167101ms).
[10:07:18.603] <TB1> INFO: 5379800 events read in total (200828ms).
[10:07:51.023] <TB1> INFO: 6272100 events read in total (233248ms).
[10:08:23.392] <TB1> INFO: 7162050 events read in total (265617ms).
[10:08:58.150] <TB1> INFO: 8050250 events read in total (300375ms).
[10:09:31.264] <TB1> INFO: 8938350 events read in total (333489ms).
[10:10:03.145] <TB1> INFO: 9827950 events read in total (365370ms).
[10:10:35.438] <TB1> INFO: 10715650 events read in total (397663ms).
[10:11:08.586] <TB1> INFO: 11601300 events read in total (430811ms).
[10:11:40.696] <TB1> INFO: 12487000 events read in total (462921ms).
[10:12:13.269] <TB1> INFO: 13369050 events read in total (495494ms).
[10:12:46.864] <TB1> INFO: 14247600 events read in total (529089ms).
[10:13:19.669] <TB1> INFO: 15123850 events read in total (561894ms).
[10:13:53.089] <TB1> INFO: 15999200 events read in total (595314ms).
[10:14:25.268] <TB1> INFO: 16872200 events read in total (627493ms).
[10:14:58.804] <TB1> INFO: 17746600 events read in total (661029ms).
[10:15:31.555] <TB1> INFO: 18620250 events read in total (693780ms).
[10:16:04.242] <TB1> INFO: 19491200 events read in total (726467ms).
[10:16:37.356] <TB1> INFO: 20365450 events read in total (759581ms).
[10:17:10.595] <TB1> INFO: 21239400 events read in total (792820ms).
[10:17:44.747] <TB1> INFO: 22108850 events read in total (826972ms).
[10:18:18.386] <TB1> INFO: 22982150 events read in total (860611ms).
[10:18:50.486] <TB1> INFO: 23857650 events read in total (892711ms).
[10:19:24.196] <TB1> INFO: 24732800 events read in total (926421ms).
[10:19:58.315] <TB1> INFO: 25606850 events read in total (960540ms).
[10:20:20.801] <TB1> INFO: 26208000 events read in total (983026ms).
[10:20:20.830] <TB1> INFO: Test took 983870ms.
[10:20:21.050] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:20:22.419] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:20:23.769] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:20:25.138] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:20:26.521] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:20:27.920] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:20:29.254] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:20:30.609] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:20:32.018] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:20:33.399] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:20:34.847] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:20:36.207] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:20:37.529] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:20:38.877] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:20:40.205] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:20:41.594] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:20:42.998] <TB1> INFO: PixTestScurves::scurves() done
[10:20:42.998] <TB1> INFO: Vcal mean: 88.47 90.33 84.30 89.54 87.50 98.09 91.15 82.06 107.70 86.23 108.23 105.21 89.32 100.02 77.71 89.22
[10:20:42.998] <TB1> INFO: Vcal RMS: 5.25 5.84 5.47 5.21 5.99 5.77 5.46 4.71 5.86 5.27 5.57 5.90 5.42 6.29 4.52 5.91
[10:20:42.998] <TB1> INFO: PixTestScurves::fullTest() done, duration: 1006 seconds
[10:20:42.998] <TB1> INFO: Decoding statistics:
[10:20:42.998] <TB1> INFO: General information:
[10:20:42.998] <TB1> INFO: 16bit words read: 0
[10:20:42.998] <TB1> INFO: valid events total: 0
[10:20:42.998] <TB1> INFO: empty events: 0
[10:20:42.998] <TB1> INFO: valid events with pixels: 0
[10:20:42.998] <TB1> INFO: valid pixel hits: 0
[10:20:42.998] <TB1> INFO: Event errors: 0
[10:20:42.998] <TB1> INFO: start marker: 0
[10:20:42.998] <TB1> INFO: stop marker: 0
[10:20:42.998] <TB1> INFO: overflow: 0
[10:20:42.998] <TB1> INFO: invalid 5bit words: 0
[10:20:42.998] <TB1> INFO: invalid XOR eye diagram: 0
[10:20:42.998] <TB1> INFO: frame (failed synchr.): 0
[10:20:42.998] <TB1> INFO: idle data (no TBM trl): 0
[10:20:42.998] <TB1> INFO: no data (only TBM hdr): 0
[10:20:42.998] <TB1> INFO: TBM errors: 0
[10:20:42.998] <TB1> INFO: flawed TBM headers: 0
[10:20:42.998] <TB1> INFO: flawed TBM trailers: 0
[10:20:42.998] <TB1> INFO: event ID mismatches: 0
[10:20:42.998] <TB1> INFO: ROC errors: 0
[10:20:42.998] <TB1> INFO: missing ROC header(s): 0
[10:20:42.998] <TB1> INFO: misplaced readback start: 0
[10:20:42.998] <TB1> INFO: Pixel decoding errors: 0
[10:20:42.998] <TB1> INFO: pixel data incomplete: 0
[10:20:42.998] <TB1> INFO: pixel address: 0
[10:20:42.998] <TB1> INFO: pulse height fill bit: 0
[10:20:42.999] <TB1> INFO: buffer corruption: 0
[10:20:43.100] <TB1> INFO: ######################################################################
[10:20:43.100] <TB1> INFO: PixTestTrim::doTest()
[10:20:43.100] <TB1> INFO: ######################################################################
[10:20:43.102] <TB1> INFO: ----------------------------------------------------------------------
[10:20:43.102] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:20:43.102] <TB1> INFO: ----------------------------------------------------------------------
[10:20:43.194] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:20:43.194] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:20:43.202] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:20:43.202] <TB1> INFO: run 1 of 1
[10:20:43.460] <TB1> INFO: Expecting 6281600 events.
[10:21:23.200] <TB1> INFO: 1079620 events read in total (39182ms).
[10:22:03.697] <TB1> INFO: 2151990 events read in total (79680ms).
[10:22:43.159] <TB1> INFO: 3217410 events read in total (119141ms).
[10:23:21.904] <TB1> INFO: 4287490 events read in total (157886ms).
[10:24:01.407] <TB1> INFO: 5356440 events read in total (197389ms).
[10:24:34.691] <TB1> INFO: 6281600 events read in total (230673ms).
[10:24:34.721] <TB1> INFO: Test took 231519ms.
[10:24:53.211] <TB1> INFO: ROC 0 VthrComp = 92
[10:24:53.211] <TB1> INFO: ROC 1 VthrComp = 96
[10:24:53.211] <TB1> INFO: ROC 2 VthrComp = 88
[10:24:53.211] <TB1> INFO: ROC 3 VthrComp = 93
[10:24:53.211] <TB1> INFO: ROC 4 VthrComp = 87
[10:24:53.211] <TB1> INFO: ROC 5 VthrComp = 101
[10:24:53.211] <TB1> INFO: ROC 6 VthrComp = 96
[10:24:53.211] <TB1> INFO: ROC 7 VthrComp = 86
[10:24:53.211] <TB1> INFO: ROC 8 VthrComp = 102
[10:24:53.211] <TB1> INFO: ROC 9 VthrComp = 90
[10:24:53.212] <TB1> INFO: ROC 10 VthrComp = 104
[10:24:53.212] <TB1> INFO: ROC 11 VthrComp = 100
[10:24:53.212] <TB1> INFO: ROC 12 VthrComp = 90
[10:24:53.212] <TB1> INFO: ROC 13 VthrComp = 99
[10:24:53.212] <TB1> INFO: ROC 14 VthrComp = 83
[10:24:53.212] <TB1> INFO: ROC 15 VthrComp = 88
[10:24:53.212] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:24:53.212] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:24:53.220] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:24:53.220] <TB1> INFO: run 1 of 1
[10:24:53.479] <TB1> INFO: Expecting 6281600 events.
[10:25:28.668] <TB1> INFO: 758340 events read in total (34633ms).
[10:26:03.798] <TB1> INFO: 1513900 events read in total (69763ms).
[10:26:37.706] <TB1> INFO: 2269450 events read in total (103671ms).
[10:27:12.387] <TB1> INFO: 3025230 events read in total (138352ms).
[10:27:47.719] <TB1> INFO: 3775710 events read in total (173684ms).
[10:28:21.387] <TB1> INFO: 4522570 events read in total (207352ms).
[10:28:54.900] <TB1> INFO: 5267610 events read in total (240865ms).
[10:29:30.404] <TB1> INFO: 6013190 events read in total (276369ms).
[10:29:43.318] <TB1> INFO: 6281600 events read in total (289283ms).
[10:29:43.371] <TB1> INFO: Test took 290151ms.
[10:30:05.996] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 57.9724 for pixel 21/12 mean/min/max = 45.3709/32.7128/58.029
[10:30:05.996] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 60.2703 for pixel 0/68 mean/min/max = 45.8163/31.3345/60.2981
[10:30:05.996] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 59.8634 for pixel 21/10 mean/min/max = 46.2528/32.5471/59.9586
[10:30:05.997] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 58.0479 for pixel 12/13 mean/min/max = 45.1769/32.2997/58.0541
[10:30:05.997] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 63.0974 for pixel 9/3 mean/min/max = 47.3177/31.5373/63.0982
[10:30:05.997] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 59.7769 for pixel 27/73 mean/min/max = 46.2203/32.6504/59.7902
[10:30:05.998] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 57.8162 for pixel 23/78 mean/min/max = 44.9417/31.7704/58.113
[10:30:05.998] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 58.6303 for pixel 9/79 mean/min/max = 45.5436/32.3505/58.7367
[10:30:05.998] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 65.5541 for pixel 5/0 mean/min/max = 48.9634/32.3364/65.5905
[10:30:05.998] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 58.4567 for pixel 11/71 mean/min/max = 45.5191/32.5462/58.4921
[10:30:05.998] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 63.2897 for pixel 23/4 mean/min/max = 48.6031/33.8925/63.3137
[10:30:05.999] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 63.4578 for pixel 15/9 mean/min/max = 48.0782/32.4128/63.7435
[10:30:05.999] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 58.398 for pixel 16/78 mean/min/max = 45.9033/33.2927/58.5138
[10:30:05.999] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 60.6938 for pixel 35/5 mean/min/max = 46.0791/31.4218/60.7364
[10:30:05.000] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 57.0577 for pixel 0/20 mean/min/max = 44.7666/32.4593/57.0739
[10:30:06.000] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 61.7004 for pixel 22/3 mean/min/max = 46.998/32.0774/61.9186
[10:30:06.000] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:30:06.088] <TB1> INFO: Expecting 514560 events.
[10:30:18.932] <TB1> INFO: 514560 events read in total (12288ms).
[10:30:18.937] <TB1> INFO: Expecting 514560 events.
[10:30:31.888] <TB1> INFO: 514560 events read in total (12537ms).
[10:30:31.895] <TB1> INFO: Expecting 514560 events.
[10:30:44.932] <TB1> INFO: 514560 events read in total (12635ms).
[10:30:44.941] <TB1> INFO: Expecting 514560 events.
[10:30:57.873] <TB1> INFO: 514560 events read in total (12520ms).
[10:30:57.889] <TB1> INFO: Expecting 514560 events.
[10:31:10.780] <TB1> INFO: 514560 events read in total (12489ms).
[10:31:10.792] <TB1> INFO: Expecting 514560 events.
[10:31:23.762] <TB1> INFO: 514560 events read in total (12559ms).
[10:31:23.776] <TB1> INFO: Expecting 514560 events.
[10:31:36.676] <TB1> INFO: 514560 events read in total (12498ms).
[10:31:36.691] <TB1> INFO: Expecting 514560 events.
[10:31:49.357] <TB1> INFO: 514560 events read in total (12260ms).
[10:31:49.378] <TB1> INFO: Expecting 514560 events.
[10:32:02.204] <TB1> INFO: 514560 events read in total (12419ms).
[10:32:02.224] <TB1> INFO: Expecting 514560 events.
[10:32:14.995] <TB1> INFO: 514560 events read in total (12369ms).
[10:32:15.015] <TB1> INFO: Expecting 514560 events.
[10:32:28.046] <TB1> INFO: 514560 events read in total (12618ms).
[10:32:28.069] <TB1> INFO: Expecting 514560 events.
[10:32:40.954] <TB1> INFO: 514560 events read in total (12482ms).
[10:32:40.980] <TB1> INFO: Expecting 514560 events.
[10:32:53.863] <TB1> INFO: 514560 events read in total (12482ms).
[10:32:53.889] <TB1> INFO: Expecting 514560 events.
[10:33:06.559] <TB1> INFO: 514560 events read in total (12265ms).
[10:33:06.601] <TB1> INFO: Expecting 514560 events.
[10:33:19.236] <TB1> INFO: 514560 events read in total (12234ms).
[10:33:19.266] <TB1> INFO: Expecting 514560 events.
[10:33:32.259] <TB1> INFO: 514560 events read in total (12591ms).
[10:33:32.291] <TB1> INFO: Test took 206291ms.
[10:33:33.206] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:33:33.214] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:33:33.214] <TB1> INFO: run 1 of 1
[10:33:33.475] <TB1> INFO: Expecting 6281600 events.
[10:34:08.182] <TB1> INFO: 742180 events read in total (34150ms).
[10:34:41.699] <TB1> INFO: 1483050 events read in total (67667ms).
[10:35:15.152] <TB1> INFO: 2224610 events read in total (101120ms).
[10:35:50.396] <TB1> INFO: 2966430 events read in total (136364ms).
[10:36:24.356] <TB1> INFO: 3702740 events read in total (170324ms).
[10:36:59.583] <TB1> INFO: 4434440 events read in total (205551ms).
[10:37:34.076] <TB1> INFO: 5164270 events read in total (240045ms).
[10:38:09.614] <TB1> INFO: 5893900 events read in total (275582ms).
[10:38:28.222] <TB1> INFO: 6281600 events read in total (294190ms).
[10:38:28.280] <TB1> INFO: Test took 295066ms.
[10:38:50.641] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.065422 .. 255.000000
[10:38:50.716] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[10:38:50.724] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:38:50.724] <TB1> INFO: run 1 of 1
[10:38:50.982] <TB1> INFO: Expecting 10649600 events.
[10:39:26.199] <TB1> INFO: 712040 events read in total (34661ms).
[10:40:00.391] <TB1> INFO: 1424390 events read in total (68853ms).
[10:40:34.729] <TB1> INFO: 2136580 events read in total (103191ms).
[10:41:09.087] <TB1> INFO: 2848950 events read in total (137549ms).
[10:41:44.854] <TB1> INFO: 3561460 events read in total (173316ms).
[10:42:19.711] <TB1> INFO: 4274080 events read in total (208173ms).
[10:42:54.172] <TB1> INFO: 4986550 events read in total (242634ms).
[10:43:28.690] <TB1> INFO: 5699090 events read in total (277152ms).
[10:44:04.155] <TB1> INFO: 6411350 events read in total (312617ms).
[10:44:38.150] <TB1> INFO: 7123000 events read in total (346612ms).
[10:45:13.601] <TB1> INFO: 7834500 events read in total (382063ms).
[10:45:48.711] <TB1> INFO: 8545340 events read in total (417173ms).
[10:46:23.511] <TB1> INFO: 9256140 events read in total (451973ms).
[10:46:58.032] <TB1> INFO: 9967220 events read in total (486494ms).
[10:47:30.362] <TB1> INFO: 10649600 events read in total (518824ms).
[10:47:30.466] <TB1> INFO: Test took 519742ms.
[10:47:58.115] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 15.622226 .. 54.009257
[10:47:58.190] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 5 .. 64 (-1/-1) hits flags = 528 (plus default)
[10:47:58.198] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:47:58.198] <TB1> INFO: run 1 of 1
[10:47:58.454] <TB1> INFO: Expecting 2496000 events.
[10:48:35.997] <TB1> INFO: 882060 events read in total (36987ms).
[10:49:13.813] <TB1> INFO: 1764350 events read in total (74803ms).
[10:49:45.893] <TB1> INFO: 2496000 events read in total (106884ms).
[10:49:45.910] <TB1> INFO: Test took 107713ms.
[10:50:00.490] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 4.500000 .. 54.009257
[10:50:00.565] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 4 .. 64 (-1/-1) hits flags = 528 (plus default)
[10:50:00.573] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:50:00.573] <TB1> INFO: run 1 of 1
[10:50:00.832] <TB1> INFO: Expecting 2537600 events.
[10:50:37.370] <TB1> INFO: 889680 events read in total (35982ms).
[10:51:14.392] <TB1> INFO: 1778990 events read in total (73005ms).
[10:51:45.299] <TB1> INFO: 2537600 events read in total (103912ms).
[10:51:45.315] <TB1> INFO: Test took 104743ms.
[10:51:59.481] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 4.500000 .. 54.009257
[10:51:59.556] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 4 .. 64 (-1/-1) hits flags = 528 (plus default)
[10:51:59.565] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:51:59.565] <TB1> INFO: run 1 of 1
[10:51:59.824] <TB1> INFO: Expecting 2537600 events.
[10:52:37.933] <TB1> INFO: 889950 events read in total (37553ms).
[10:53:15.325] <TB1> INFO: 1779690 events read in total (74946ms).
[10:53:46.088] <TB1> INFO: 2537600 events read in total (105709ms).
[10:53:46.106] <TB1> INFO: Test took 106541ms.
[10:54:00.027] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[10:54:00.027] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[10:54:00.035] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:54:00.035] <TB1> INFO: run 1 of 1
[10:54:00.297] <TB1> INFO: Expecting 1705600 events.
[10:54:38.865] <TB1> INFO: 879030 events read in total (38012ms).
[10:55:14.093] <TB1> INFO: 1705600 events read in total (73240ms).
[10:55:14.104] <TB1> INFO: Test took 74069ms.
[10:55:26.892] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:55:26.892] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:55:26.892] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:55:26.892] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:55:26.892] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:55:26.893] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:55:26.893] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:55:26.893] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:55:26.893] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:55:26.893] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:55:26.893] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:55:26.894] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:55:26.894] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:55:26.894] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:55:26.894] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:55:26.894] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:55:26.894] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//trimParameters35_C0.dat
[10:55:26.906] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//trimParameters35_C1.dat
[10:55:26.918] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//trimParameters35_C2.dat
[10:55:26.930] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//trimParameters35_C3.dat
[10:55:26.941] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//trimParameters35_C4.dat
[10:55:26.949] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//trimParameters35_C5.dat
[10:55:26.961] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//trimParameters35_C6.dat
[10:55:26.972] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//trimParameters35_C7.dat
[10:55:26.978] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//trimParameters35_C8.dat
[10:55:26.983] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//trimParameters35_C9.dat
[10:55:26.989] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//trimParameters35_C10.dat
[10:55:26.995] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//trimParameters35_C11.dat
[10:55:27.001] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//trimParameters35_C12.dat
[10:55:27.007] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//trimParameters35_C13.dat
[10:55:27.013] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//trimParameters35_C14.dat
[10:55:27.019] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//trimParameters35_C15.dat
[10:55:27.025] <TB1> INFO: PixTestTrim::trimTest() done
[10:55:27.025] <TB1> INFO: vtrim: 90 102 111 102 114 98 93 89 119 92 113 106 101 103 84 117
[10:55:27.025] <TB1> INFO: vthrcomp: 92 96 88 93 87 101 96 86 102 90 104 100 90 99 83 88
[10:55:27.025] <TB1> INFO: vcal mean: 34.96 34.98 35.04 34.96 35.02 34.96 34.98 34.99 34.95 34.98 34.97 35.02 35.19 34.96 34.99 35.03
[10:55:27.025] <TB1> INFO: vcal RMS: 0.83 0.87 0.85 0.87 0.92 0.87 0.83 0.79 1.02 0.82 0.95 0.95 1.04 0.93 0.97 0.92
[10:55:27.025] <TB1> INFO: bits mean: 9.47 9.04 9.18 9.43 9.25 9.20 9.25 8.84 8.84 9.46 8.50 8.88 9.36 9.42 9.32 9.39
[10:55:27.025] <TB1> INFO: bits RMS: 2.63 2.96 2.70 2.71 2.74 2.65 2.88 2.94 2.69 2.66 2.63 2.69 2.56 2.75 2.78 2.59
[10:55:27.031] <TB1> INFO: ----------------------------------------------------------------------
[10:55:27.031] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[10:55:27.031] <TB1> INFO: ----------------------------------------------------------------------
[10:55:27.034] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[10:55:27.042] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:55:27.042] <TB1> INFO: run 1 of 1
[10:55:27.301] <TB1> INFO: Expecting 4160000 events.
[10:56:10.400] <TB1> INFO: 934590 events read in total (42543ms).
[10:56:52.524] <TB1> INFO: 1862175 events read in total (84668ms).
[10:57:34.300] <TB1> INFO: 2782620 events read in total (126444ms).
[10:58:15.962] <TB1> INFO: 3700555 events read in total (168105ms).
[10:58:36.355] <TB1> INFO: 4160000 events read in total (188499ms).
[10:58:36.394] <TB1> INFO: Test took 189352ms.
[10:59:03.567] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 182 (-1/-1) hits flags = 528 (plus default)
[10:59:03.575] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:59:03.578] <TB1> INFO: run 1 of 1
[10:59:03.837] <TB1> INFO: Expecting 3806400 events.
[10:59:45.713] <TB1> INFO: 937505 events read in total (41320ms).
[11:00:27.328] <TB1> INFO: 1867715 events read in total (82935ms).
[11:01:08.274] <TB1> INFO: 2788520 events read in total (123881ms).
[11:01:49.327] <TB1> INFO: 3708405 events read in total (164934ms).
[11:01:54.213] <TB1> INFO: 3806400 events read in total (169820ms).
[11:01:54.243] <TB1> INFO: Test took 170665ms.
[11:02:21.206] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 168 (-1/-1) hits flags = 528 (plus default)
[11:02:21.214] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:02:21.214] <TB1> INFO: run 1 of 1
[11:02:21.481] <TB1> INFO: Expecting 3515200 events.
[11:03:04.726] <TB1> INFO: 969630 events read in total (42689ms).
[11:03:47.326] <TB1> INFO: 1929100 events read in total (85289ms).
[11:04:30.153] <TB1> INFO: 2879180 events read in total (128117ms).
[11:04:58.114] <TB1> INFO: 3515200 events read in total (156077ms).
[11:04:58.148] <TB1> INFO: Test took 156933ms.
[11:05:22.917] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 167 (-1/-1) hits flags = 528 (plus default)
[11:05:22.925] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:05:22.925] <TB1> INFO: run 1 of 1
[11:05:23.183] <TB1> INFO: Expecting 3494400 events.
[11:06:06.796] <TB1> INFO: 971470 events read in total (43056ms).
[11:06:49.397] <TB1> INFO: 1932685 events read in total (85657ms).
[11:07:32.910] <TB1> INFO: 2884685 events read in total (129170ms).
[11:08:00.724] <TB1> INFO: 3494400 events read in total (156984ms).
[11:08:00.759] <TB1> INFO: Test took 157834ms.
[11:08:25.721] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 167 (-1/-1) hits flags = 528 (plus default)
[11:08:25.729] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:08:25.729] <TB1> INFO: run 1 of 1
[11:08:25.994] <TB1> INFO: Expecting 3494400 events.
[11:09:08.276] <TB1> INFO: 970745 events read in total (41726ms).
[11:09:51.454] <TB1> INFO: 1931095 events read in total (84904ms).
[11:10:34.115] <TB1> INFO: 2882465 events read in total (127565ms).
[11:11:01.297] <TB1> INFO: 3494400 events read in total (154747ms).
[11:11:01.328] <TB1> INFO: Test took 155599ms.
[11:11:28.907] <TB1> INFO: PixTestTrim::trimBitTest() done
[11:11:28.908] <TB1> INFO: PixTestTrim::doTest() done, duration: 3045 seconds
[11:11:28.908] <TB1> INFO: Decoding statistics:
[11:11:28.908] <TB1> INFO: General information:
[11:11:28.908] <TB1> INFO: 16bit words read: 0
[11:11:28.908] <TB1> INFO: valid events total: 0
[11:11:28.908] <TB1> INFO: empty events: 0
[11:11:28.908] <TB1> INFO: valid events with pixels: 0
[11:11:28.908] <TB1> INFO: valid pixel hits: 0
[11:11:28.908] <TB1> INFO: Event errors: 0
[11:11:28.908] <TB1> INFO: start marker: 0
[11:11:28.908] <TB1> INFO: stop marker: 0
[11:11:28.908] <TB1> INFO: overflow: 0
[11:11:28.908] <TB1> INFO: invalid 5bit words: 0
[11:11:28.908] <TB1> INFO: invalid XOR eye diagram: 0
[11:11:28.908] <TB1> INFO: frame (failed synchr.): 0
[11:11:28.908] <TB1> INFO: idle data (no TBM trl): 0
[11:11:28.909] <TB1> INFO: no data (only TBM hdr): 0
[11:11:28.909] <TB1> INFO: TBM errors: 0
[11:11:28.909] <TB1> INFO: flawed TBM headers: 0
[11:11:28.909] <TB1> INFO: flawed TBM trailers: 0
[11:11:28.909] <TB1> INFO: event ID mismatches: 0
[11:11:28.909] <TB1> INFO: ROC errors: 0
[11:11:28.909] <TB1> INFO: missing ROC header(s): 0
[11:11:28.909] <TB1> INFO: misplaced readback start: 0
[11:11:28.909] <TB1> INFO: Pixel decoding errors: 0
[11:11:28.909] <TB1> INFO: pixel data incomplete: 0
[11:11:28.909] <TB1> INFO: pixel address: 0
[11:11:28.909] <TB1> INFO: pulse height fill bit: 0
[11:11:28.909] <TB1> INFO: buffer corruption: 0
[11:11:29.615] <TB1> INFO: ######################################################################
[11:11:29.615] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:11:29.615] <TB1> INFO: ######################################################################
[11:11:29.900] <TB1> INFO: Expecting 41600 events.
[11:11:33.684] <TB1> INFO: 41600 events read in total (3227ms).
[11:11:33.684] <TB1> INFO: Test took 4067ms.
[11:11:34.150] <TB1> INFO: Expecting 41600 events.
[11:11:37.892] <TB1> INFO: 41600 events read in total (3186ms).
[11:11:37.892] <TB1> INFO: Test took 4003ms.
[11:11:38.184] <TB1> INFO: Expecting 41600 events.
[11:11:41.924] <TB1> INFO: 41600 events read in total (3184ms).
[11:11:41.925] <TB1> INFO: Test took 4012ms.
[11:11:42.207] <TB1> INFO: Expecting 41600 events.
[11:11:45.952] <TB1> INFO: 41600 events read in total (3189ms).
[11:11:45.953] <TB1> INFO: Test took 4009ms.
[11:11:46.232] <TB1> INFO: Expecting 41600 events.
[11:11:49.987] <TB1> INFO: 41600 events read in total (3199ms).
[11:11:49.988] <TB1> INFO: Test took 4015ms.
[11:11:50.266] <TB1> INFO: Expecting 41600 events.
[11:11:54.016] <TB1> INFO: 41600 events read in total (3193ms).
[11:11:54.017] <TB1> INFO: Test took 4010ms.
[11:11:54.296] <TB1> INFO: Expecting 41600 events.
[11:11:58.329] <TB1> INFO: 41600 events read in total (3477ms).
[11:11:58.330] <TB1> INFO: Test took 4294ms.
[11:11:58.633] <TB1> INFO: Expecting 41600 events.
[11:12:02.579] <TB1> INFO: 41600 events read in total (3389ms).
[11:12:02.580] <TB1> INFO: Test took 4231ms.
[11:12:02.891] <TB1> INFO: Expecting 41600 events.
[11:12:06.751] <TB1> INFO: 41600 events read in total (3303ms).
[11:12:06.752] <TB1> INFO: Test took 4152ms.
[11:12:07.029] <TB1> INFO: Expecting 41600 events.
[11:12:10.989] <TB1> INFO: 41600 events read in total (3404ms).
[11:12:10.989] <TB1> INFO: Test took 4218ms.
[11:12:11.268] <TB1> INFO: Expecting 41600 events.
[11:12:15.003] <TB1> INFO: 41600 events read in total (3179ms).
[11:12:15.003] <TB1> INFO: Test took 3994ms.
[11:12:15.280] <TB1> INFO: Expecting 41600 events.
[11:12:19.033] <TB1> INFO: 41600 events read in total (3196ms).
[11:12:19.034] <TB1> INFO: Test took 4011ms.
[11:12:19.311] <TB1> INFO: Expecting 41600 events.
[11:12:23.067] <TB1> INFO: 41600 events read in total (3199ms).
[11:12:23.068] <TB1> INFO: Test took 4014ms.
[11:12:23.348] <TB1> INFO: Expecting 41600 events.
[11:12:27.094] <TB1> INFO: 41600 events read in total (3189ms).
[11:12:27.094] <TB1> INFO: Test took 4004ms.
[11:12:27.373] <TB1> INFO: Expecting 41600 events.
[11:12:31.116] <TB1> INFO: 41600 events read in total (3187ms).
[11:12:31.117] <TB1> INFO: Test took 4003ms.
[11:12:31.401] <TB1> INFO: Expecting 41600 events.
[11:12:35.154] <TB1> INFO: 41600 events read in total (3197ms).
[11:12:35.155] <TB1> INFO: Test took 4019ms.
[11:12:35.429] <TB1> INFO: Expecting 41600 events.
[11:12:39.373] <TB1> INFO: 41600 events read in total (3388ms).
[11:12:39.373] <TB1> INFO: Test took 4198ms.
[11:12:39.655] <TB1> INFO: Expecting 41600 events.
[11:12:43.599] <TB1> INFO: 41600 events read in total (3387ms).
[11:12:43.599] <TB1> INFO: Test took 4206ms.
[11:12:43.891] <TB1> INFO: Expecting 41600 events.
[11:12:48.008] <TB1> INFO: 41600 events read in total (3561ms).
[11:12:48.008] <TB1> INFO: Test took 4389ms.
[11:12:48.288] <TB1> INFO: Expecting 41600 events.
[11:12:52.306] <TB1> INFO: 41600 events read in total (3462ms).
[11:12:52.306] <TB1> INFO: Test took 4278ms.
[11:12:52.594] <TB1> INFO: Expecting 41600 events.
[11:12:56.475] <TB1> INFO: 41600 events read in total (3325ms).
[11:12:56.476] <TB1> INFO: Test took 4150ms.
[11:12:56.769] <TB1> INFO: Expecting 41600 events.
[11:13:00.616] <TB1> INFO: 41600 events read in total (3291ms).
[11:13:00.616] <TB1> INFO: Test took 4120ms.
[11:13:00.896] <TB1> INFO: Expecting 41600 events.
[11:13:04.784] <TB1> INFO: 41600 events read in total (3331ms).
[11:13:04.784] <TB1> INFO: Test took 4148ms.
[11:13:05.061] <TB1> INFO: Expecting 41600 events.
[11:13:08.978] <TB1> INFO: 41600 events read in total (3360ms).
[11:13:08.979] <TB1> INFO: Test took 4175ms.
[11:13:09.272] <TB1> INFO: Expecting 41600 events.
[11:13:13.188] <TB1> INFO: 41600 events read in total (3360ms).
[11:13:13.190] <TB1> INFO: Test took 4191ms.
[11:13:13.522] <TB1> INFO: Expecting 41600 events.
[11:13:17.449] <TB1> INFO: 41600 events read in total (3371ms).
[11:13:17.450] <TB1> INFO: Test took 4237ms.
[11:13:17.760] <TB1> INFO: Expecting 41600 events.
[11:13:21.564] <TB1> INFO: 41600 events read in total (3247ms).
[11:13:21.564] <TB1> INFO: Test took 4091ms.
[11:13:21.842] <TB1> INFO: Expecting 41600 events.
[11:13:25.604] <TB1> INFO: 41600 events read in total (3205ms).
[11:13:25.604] <TB1> INFO: Test took 4018ms.
[11:13:25.887] <TB1> INFO: Expecting 41600 events.
[11:13:29.680] <TB1> INFO: 41600 events read in total (3236ms).
[11:13:29.681] <TB1> INFO: Test took 4053ms.
[11:13:29.956] <TB1> INFO: Expecting 41600 events.
[11:13:33.726] <TB1> INFO: 41600 events read in total (3214ms).
[11:13:33.727] <TB1> INFO: Test took 4027ms.
[11:13:34.004] <TB1> INFO: Expecting 41600 events.
[11:13:37.765] <TB1> INFO: 41600 events read in total (3205ms).
[11:13:37.766] <TB1> INFO: Test took 4020ms.
[11:13:38.044] <TB1> INFO: Expecting 41600 events.
[11:13:41.797] <TB1> INFO: 41600 events read in total (3197ms).
[11:13:41.798] <TB1> INFO: Test took 4013ms.
[11:13:42.076] <TB1> INFO: Expecting 41600 events.
[11:13:45.840] <TB1> INFO: 41600 events read in total (3208ms).
[11:13:45.841] <TB1> INFO: Test took 4024ms.
[11:13:46.117] <TB1> INFO: Expecting 41600 events.
[11:13:49.891] <TB1> INFO: 41600 events read in total (3217ms).
[11:13:49.892] <TB1> INFO: Test took 4031ms.
[11:13:50.167] <TB1> INFO: Expecting 41600 events.
[11:13:53.946] <TB1> INFO: 41600 events read in total (3222ms).
[11:13:53.947] <TB1> INFO: Test took 4036ms.
[11:13:54.225] <TB1> INFO: Expecting 41600 events.
[11:13:58.093] <TB1> INFO: 41600 events read in total (3312ms).
[11:13:58.095] <TB1> INFO: Test took 4128ms.
[11:13:58.378] <TB1> INFO: Expecting 41600 events.
[11:14:02.253] <TB1> INFO: 41600 events read in total (3319ms).
[11:14:02.254] <TB1> INFO: Test took 4140ms.
[11:14:02.538] <TB1> INFO: Expecting 41600 events.
[11:14:06.297] <TB1> INFO: 41600 events read in total (3203ms).
[11:14:06.298] <TB1> INFO: Test took 4020ms.
[11:14:06.576] <TB1> INFO: Expecting 41600 events.
[11:14:10.332] <TB1> INFO: 41600 events read in total (3199ms).
[11:14:10.333] <TB1> INFO: Test took 4015ms.
[11:14:10.610] <TB1> INFO: Expecting 41600 events.
[11:14:14.356] <TB1> INFO: 41600 events read in total (3190ms).
[11:14:14.357] <TB1> INFO: Test took 4004ms.
[11:14:14.639] <TB1> INFO: Expecting 41600 events.
[11:14:18.391] <TB1> INFO: 41600 events read in total (3196ms).
[11:14:18.392] <TB1> INFO: Test took 4015ms.
[11:14:18.669] <TB1> INFO: Expecting 41600 events.
[11:14:22.417] <TB1> INFO: 41600 events read in total (3191ms).
[11:14:22.418] <TB1> INFO: Test took 4006ms.
[11:14:22.697] <TB1> INFO: Expecting 41600 events.
[11:14:26.455] <TB1> INFO: 41600 events read in total (3202ms).
[11:14:26.456] <TB1> INFO: Test took 4017ms.
[11:14:26.743] <TB1> INFO: Expecting 41600 events.
[11:14:30.506] <TB1> INFO: 41600 events read in total (3207ms).
[11:14:30.507] <TB1> INFO: Test took 4031ms.
[11:14:30.793] <TB1> INFO: Expecting 41600 events.
[11:14:34.555] <TB1> INFO: 41600 events read in total (3206ms).
[11:14:34.555] <TB1> INFO: Test took 4028ms.
[11:14:34.834] <TB1> INFO: Expecting 41600 events.
[11:14:38.572] <TB1> INFO: 41600 events read in total (3181ms).
[11:14:38.573] <TB1> INFO: Test took 3998ms.
[11:14:38.852] <TB1> INFO: Expecting 41600 events.
[11:14:42.649] <TB1> INFO: 41600 events read in total (3241ms).
[11:14:42.649] <TB1> INFO: Test took 4056ms.
[11:14:42.932] <TB1> INFO: Expecting 41600 events.
[11:14:46.858] <TB1> INFO: 41600 events read in total (3370ms).
[11:14:46.859] <TB1> INFO: Test took 4186ms.
[11:14:47.168] <TB1> INFO: Expecting 41600 events.
[11:14:50.918] <TB1> INFO: 41600 events read in total (3193ms).
[11:14:50.918] <TB1> INFO: Test took 4039ms.
[11:14:51.238] <TB1> INFO: Expecting 41600 events.
[11:14:55.091] <TB1> INFO: 41600 events read in total (3296ms).
[11:14:55.091] <TB1> INFO: Test took 4153ms.
[11:14:55.372] <TB1> INFO: Expecting 41600 events.
[11:14:59.114] <TB1> INFO: 41600 events read in total (3186ms).
[11:14:59.115] <TB1> INFO: Test took 4004ms.
[11:14:59.391] <TB1> INFO: Expecting 41600 events.
[11:15:03.142] <TB1> INFO: 41600 events read in total (3195ms).
[11:15:03.143] <TB1> INFO: Test took 4008ms.
[11:15:03.419] <TB1> INFO: Expecting 41600 events.
[11:15:07.211] <TB1> INFO: 41600 events read in total (3235ms).
[11:15:07.212] <TB1> INFO: Test took 4050ms.
[11:15:07.489] <TB1> INFO: Expecting 41600 events.
[11:15:11.324] <TB1> INFO: 41600 events read in total (3279ms).
[11:15:11.324] <TB1> INFO: Test took 4092ms.
[11:15:11.578] <TB1> INFO: Expecting 2560 events.
[11:15:12.437] <TB1> INFO: 2560 events read in total (302ms).
[11:15:12.437] <TB1> INFO: Test took 1100ms.
[11:15:12.742] <TB1> INFO: Expecting 2560 events.
[11:15:13.599] <TB1> INFO: 2560 events read in total (301ms).
[11:15:13.599] <TB1> INFO: Test took 1162ms.
[11:15:13.905] <TB1> INFO: Expecting 2560 events.
[11:15:14.758] <TB1> INFO: 2560 events read in total (297ms).
[11:15:14.759] <TB1> INFO: Test took 1160ms.
[11:15:15.063] <TB1> INFO: Expecting 2560 events.
[11:15:15.916] <TB1> INFO: 2560 events read in total (296ms).
[11:15:15.917] <TB1> INFO: Test took 1158ms.
[11:15:16.221] <TB1> INFO: Expecting 2560 events.
[11:15:17.079] <TB1> INFO: 2560 events read in total (301ms).
[11:15:17.079] <TB1> INFO: Test took 1162ms.
[11:15:17.386] <TB1> INFO: Expecting 2560 events.
[11:15:18.247] <TB1> INFO: 2560 events read in total (305ms).
[11:15:18.247] <TB1> INFO: Test took 1167ms.
[11:15:18.553] <TB1> INFO: Expecting 2560 events.
[11:15:19.409] <TB1> INFO: 2560 events read in total (299ms).
[11:15:19.409] <TB1> INFO: Test took 1161ms.
[11:15:19.715] <TB1> INFO: Expecting 2560 events.
[11:15:20.576] <TB1> INFO: 2560 events read in total (305ms).
[11:15:20.576] <TB1> INFO: Test took 1166ms.
[11:15:20.883] <TB1> INFO: Expecting 2560 events.
[11:15:21.738] <TB1> INFO: 2560 events read in total (299ms).
[11:15:21.738] <TB1> INFO: Test took 1161ms.
[11:15:22.044] <TB1> INFO: Expecting 2560 events.
[11:15:22.902] <TB1> INFO: 2560 events read in total (302ms).
[11:15:22.902] <TB1> INFO: Test took 1163ms.
[11:15:23.208] <TB1> INFO: Expecting 2560 events.
[11:15:24.061] <TB1> INFO: 2560 events read in total (297ms).
[11:15:24.061] <TB1> INFO: Test took 1158ms.
[11:15:24.366] <TB1> INFO: Expecting 2560 events.
[11:15:25.218] <TB1> INFO: 2560 events read in total (296ms).
[11:15:25.218] <TB1> INFO: Test took 1157ms.
[11:15:25.524] <TB1> INFO: Expecting 2560 events.
[11:15:26.379] <TB1> INFO: 2560 events read in total (299ms).
[11:15:26.379] <TB1> INFO: Test took 1161ms.
[11:15:26.685] <TB1> INFO: Expecting 2560 events.
[11:15:27.539] <TB1> INFO: 2560 events read in total (298ms).
[11:15:27.539] <TB1> INFO: Test took 1160ms.
[11:15:27.845] <TB1> INFO: Expecting 2560 events.
[11:15:28.700] <TB1> INFO: 2560 events read in total (299ms).
[11:15:28.701] <TB1> INFO: Test took 1162ms.
[11:15:29.006] <TB1> INFO: Expecting 2560 events.
[11:15:29.861] <TB1> INFO: 2560 events read in total (299ms).
[11:15:29.861] <TB1> INFO: Test took 1160ms.
[11:15:29.865] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:15:30.169] <TB1> INFO: Expecting 655360 events.
[11:15:47.191] <TB1> INFO: 655360 events read in total (16466ms).
[11:15:47.200] <TB1> INFO: Expecting 655360 events.
[11:16:04.055] <TB1> INFO: 655360 events read in total (16453ms).
[11:16:04.072] <TB1> INFO: Expecting 655360 events.
[11:16:20.581] <TB1> INFO: 655360 events read in total (16107ms).
[11:16:20.596] <TB1> INFO: Expecting 655360 events.
[11:16:37.080] <TB1> INFO: 655360 events read in total (16082ms).
[11:16:37.108] <TB1> INFO: Expecting 655360 events.
[11:16:53.707] <TB1> INFO: 655360 events read in total (16198ms).
[11:16:53.739] <TB1> INFO: Expecting 655360 events.
[11:17:10.367] <TB1> INFO: 655360 events read in total (16226ms).
[11:17:10.393] <TB1> INFO: Expecting 655360 events.
[11:17:27.388] <TB1> INFO: 655360 events read in total (16594ms).
[11:17:27.430] <TB1> INFO: Expecting 655360 events.
[11:17:44.121] <TB1> INFO: 655360 events read in total (16289ms).
[11:17:44.153] <TB1> INFO: Expecting 655360 events.
[11:18:01.099] <TB1> INFO: 655360 events read in total (16545ms).
[11:18:01.134] <TB1> INFO: Expecting 655360 events.
[11:18:18.046] <TB1> INFO: 655360 events read in total (16510ms).
[11:18:18.084] <TB1> INFO: Expecting 655360 events.
[11:18:34.708] <TB1> INFO: 655360 events read in total (16222ms).
[11:18:34.753] <TB1> INFO: Expecting 655360 events.
[11:18:51.439] <TB1> INFO: 655360 events read in total (16284ms).
[11:18:51.484] <TB1> INFO: Expecting 655360 events.
[11:19:08.394] <TB1> INFO: 655360 events read in total (16508ms).
[11:19:08.464] <TB1> INFO: Expecting 655360 events.
[11:19:25.473] <TB1> INFO: 655360 events read in total (16607ms).
[11:19:25.524] <TB1> INFO: Expecting 655360 events.
[11:19:42.634] <TB1> INFO: 655360 events read in total (16708ms).
[11:19:42.701] <TB1> INFO: Expecting 655360 events.
[11:19:59.036] <TB1> INFO: 655360 events read in total (15933ms).
[11:19:59.092] <TB1> INFO: Test took 269227ms.
[11:19:59.167] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:19:59.398] <TB1> INFO: Expecting 655360 events.
[11:20:16.106] <TB1> INFO: 655360 events read in total (16152ms).
[11:20:16.114] <TB1> INFO: Expecting 655360 events.
[11:20:32.464] <TB1> INFO: 655360 events read in total (15948ms).
[11:20:32.475] <TB1> INFO: Expecting 655360 events.
[11:20:48.575] <TB1> INFO: 655360 events read in total (15698ms).
[11:20:48.590] <TB1> INFO: Expecting 655360 events.
[11:21:04.880] <TB1> INFO: 655360 events read in total (15888ms).
[11:21:04.898] <TB1> INFO: Expecting 655360 events.
[11:21:21.072] <TB1> INFO: 655360 events read in total (15773ms).
[11:21:21.092] <TB1> INFO: Expecting 655360 events.
[11:21:37.326] <TB1> INFO: 655360 events read in total (15832ms).
[11:21:37.349] <TB1> INFO: Expecting 655360 events.
[11:21:53.626] <TB1> INFO: 655360 events read in total (15875ms).
[11:21:53.653] <TB1> INFO: Expecting 655360 events.
[11:22:09.871] <TB1> INFO: 655360 events read in total (15816ms).
[11:22:09.902] <TB1> INFO: Expecting 655360 events.
[11:22:26.066] <TB1> INFO: 655360 events read in total (15762ms).
[11:22:26.100] <TB1> INFO: Expecting 655360 events.
[11:22:42.408] <TB1> INFO: 655360 events read in total (15906ms).
[11:22:42.447] <TB1> INFO: Expecting 655360 events.
[11:22:58.667] <TB1> INFO: 655360 events read in total (15818ms).
[11:22:58.707] <TB1> INFO: Expecting 655360 events.
[11:23:14.952] <TB1> INFO: 655360 events read in total (15843ms).
[11:23:14.996] <TB1> INFO: Expecting 655360 events.
[11:23:31.307] <TB1> INFO: 655360 events read in total (15909ms).
[11:23:31.354] <TB1> INFO: Expecting 655360 events.
[11:23:47.672] <TB1> INFO: 655360 events read in total (15916ms).
[11:23:47.722] <TB1> INFO: Expecting 655360 events.
[11:24:04.033] <TB1> INFO: 655360 events read in total (15909ms).
[11:24:04.086] <TB1> INFO: Expecting 655360 events.
[11:24:20.366] <TB1> INFO: 655360 events read in total (15878ms).
[11:24:20.423] <TB1> INFO: Test took 261256ms.
[11:24:20.601] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:24:20.608] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:24:20.615] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:24:20.621] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[11:24:20.628] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[11:24:20.635] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[11:24:20.641] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[11:24:20.648] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:24:20.654] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:24:20.661] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:24:20.668] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:24:20.674] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:24:20.681] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[11:24:20.687] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:24:20.694] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:24:20.700] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:24:20.707] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:24:20.713] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:24:20.720] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:24:20.726] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:24:20.733] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:24:20.740] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[11:24:20.746] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[11:24:20.782] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C0.dat
[11:24:20.783] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C1.dat
[11:24:20.783] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C2.dat
[11:24:20.783] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C3.dat
[11:24:20.783] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C4.dat
[11:24:20.783] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C5.dat
[11:24:20.783] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C6.dat
[11:24:20.783] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C7.dat
[11:24:20.783] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C8.dat
[11:24:20.783] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C9.dat
[11:24:20.783] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C10.dat
[11:24:20.784] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C11.dat
[11:24:20.784] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C12.dat
[11:24:20.784] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C13.dat
[11:24:20.784] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C14.dat
[11:24:20.784] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//dacParameters35_C15.dat
[11:24:21.042] <TB1> INFO: Expecting 41600 events.
[11:24:24.472] <TB1> INFO: 41600 events read in total (2873ms).
[11:24:24.473] <TB1> INFO: Test took 3686ms.
[11:24:24.905] <TB1> INFO: Expecting 41600 events.
[11:24:28.333] <TB1> INFO: 41600 events read in total (2872ms).
[11:24:28.333] <TB1> INFO: Test took 3682ms.
[11:24:28.765] <TB1> INFO: Expecting 41600 events.
[11:24:32.193] <TB1> INFO: 41600 events read in total (2872ms).
[11:24:32.194] <TB1> INFO: Test took 3683ms.
[11:24:32.376] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:32.464] <TB1> INFO: Expecting 2560 events.
[11:24:33.316] <TB1> INFO: 2560 events read in total (296ms).
[11:24:33.316] <TB1> INFO: Test took 940ms.
[11:24:33.318] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:33.621] <TB1> INFO: Expecting 2560 events.
[11:24:34.473] <TB1> INFO: 2560 events read in total (295ms).
[11:24:34.473] <TB1> INFO: Test took 1155ms.
[11:24:34.475] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:34.778] <TB1> INFO: Expecting 2560 events.
[11:24:35.631] <TB1> INFO: 2560 events read in total (296ms).
[11:24:35.631] <TB1> INFO: Test took 1156ms.
[11:24:35.633] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:35.936] <TB1> INFO: Expecting 2560 events.
[11:24:36.788] <TB1> INFO: 2560 events read in total (296ms).
[11:24:36.788] <TB1> INFO: Test took 1155ms.
[11:24:36.790] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:37.093] <TB1> INFO: Expecting 2560 events.
[11:24:37.946] <TB1> INFO: 2560 events read in total (297ms).
[11:24:37.946] <TB1> INFO: Test took 1156ms.
[11:24:37.947] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:38.251] <TB1> INFO: Expecting 2560 events.
[11:24:39.104] <TB1> INFO: 2560 events read in total (297ms).
[11:24:39.104] <TB1> INFO: Test took 1157ms.
[11:24:39.106] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:39.409] <TB1> INFO: Expecting 2560 events.
[11:24:40.261] <TB1> INFO: 2560 events read in total (296ms).
[11:24:40.262] <TB1> INFO: Test took 1156ms.
[11:24:40.263] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:40.567] <TB1> INFO: Expecting 2560 events.
[11:24:41.419] <TB1> INFO: 2560 events read in total (296ms).
[11:24:41.420] <TB1> INFO: Test took 1157ms.
[11:24:41.421] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:41.725] <TB1> INFO: Expecting 2560 events.
[11:24:42.577] <TB1> INFO: 2560 events read in total (296ms).
[11:24:42.578] <TB1> INFO: Test took 1157ms.
[11:24:42.579] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:42.883] <TB1> INFO: Expecting 2560 events.
[11:24:43.735] <TB1> INFO: 2560 events read in total (296ms).
[11:24:43.736] <TB1> INFO: Test took 1157ms.
[11:24:43.737] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:44.041] <TB1> INFO: Expecting 2560 events.
[11:24:44.892] <TB1> INFO: 2560 events read in total (295ms).
[11:24:44.893] <TB1> INFO: Test took 1156ms.
[11:24:44.898] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:45.198] <TB1> INFO: Expecting 2560 events.
[11:24:46.050] <TB1> INFO: 2560 events read in total (296ms).
[11:24:46.051] <TB1> INFO: Test took 1153ms.
[11:24:46.053] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:46.357] <TB1> INFO: Expecting 2560 events.
[11:24:47.212] <TB1> INFO: 2560 events read in total (299ms).
[11:24:47.212] <TB1> INFO: Test took 1160ms.
[11:24:47.214] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:47.518] <TB1> INFO: Expecting 2560 events.
[11:24:48.370] <TB1> INFO: 2560 events read in total (296ms).
[11:24:48.371] <TB1> INFO: Test took 1157ms.
[11:24:48.373] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:48.677] <TB1> INFO: Expecting 2560 events.
[11:24:49.532] <TB1> INFO: 2560 events read in total (299ms).
[11:24:49.532] <TB1> INFO: Test took 1159ms.
[11:24:49.534] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:49.838] <TB1> INFO: Expecting 2560 events.
[11:24:50.692] <TB1> INFO: 2560 events read in total (298ms).
[11:24:50.692] <TB1> INFO: Test took 1158ms.
[11:24:50.694] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:50.998] <TB1> INFO: Expecting 2560 events.
[11:24:51.851] <TB1> INFO: 2560 events read in total (297ms).
[11:24:51.851] <TB1> INFO: Test took 1157ms.
[11:24:51.853] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:52.157] <TB1> INFO: Expecting 2560 events.
[11:24:53.010] <TB1> INFO: 2560 events read in total (296ms).
[11:24:53.010] <TB1> INFO: Test took 1157ms.
[11:24:53.012] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:53.316] <TB1> INFO: Expecting 2560 events.
[11:24:54.169] <TB1> INFO: 2560 events read in total (297ms).
[11:24:54.169] <TB1> INFO: Test took 1157ms.
[11:24:54.171] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:54.475] <TB1> INFO: Expecting 2560 events.
[11:24:55.328] <TB1> INFO: 2560 events read in total (297ms).
[11:24:55.328] <TB1> INFO: Test took 1157ms.
[11:24:55.330] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:55.634] <TB1> INFO: Expecting 2560 events.
[11:24:56.487] <TB1> INFO: 2560 events read in total (297ms).
[11:24:56.487] <TB1> INFO: Test took 1157ms.
[11:24:56.489] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:56.793] <TB1> INFO: Expecting 2560 events.
[11:24:57.646] <TB1> INFO: 2560 events read in total (297ms).
[11:24:57.646] <TB1> INFO: Test took 1157ms.
[11:24:57.648] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:57.952] <TB1> INFO: Expecting 2560 events.
[11:24:58.805] <TB1> INFO: 2560 events read in total (297ms).
[11:24:58.805] <TB1> INFO: Test took 1157ms.
[11:24:58.807] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:24:59.111] <TB1> INFO: Expecting 2560 events.
[11:24:59.964] <TB1> INFO: 2560 events read in total (297ms).
[11:24:59.964] <TB1> INFO: Test took 1157ms.
[11:24:59.966] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:00.270] <TB1> INFO: Expecting 2560 events.
[11:25:01.123] <TB1> INFO: 2560 events read in total (297ms).
[11:25:01.123] <TB1> INFO: Test took 1157ms.
[11:25:01.125] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:01.429] <TB1> INFO: Expecting 2560 events.
[11:25:02.282] <TB1> INFO: 2560 events read in total (297ms).
[11:25:02.282] <TB1> INFO: Test took 1157ms.
[11:25:02.284] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:02.588] <TB1> INFO: Expecting 2560 events.
[11:25:03.441] <TB1> INFO: 2560 events read in total (297ms).
[11:25:03.441] <TB1> INFO: Test took 1157ms.
[11:25:03.443] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:03.747] <TB1> INFO: Expecting 2560 events.
[11:25:04.600] <TB1> INFO: 2560 events read in total (297ms).
[11:25:04.600] <TB1> INFO: Test took 1157ms.
[11:25:04.602] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:04.906] <TB1> INFO: Expecting 2560 events.
[11:25:05.761] <TB1> INFO: 2560 events read in total (299ms).
[11:25:05.761] <TB1> INFO: Test took 1159ms.
[11:25:05.763] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:06.067] <TB1> INFO: Expecting 2560 events.
[11:25:06.920] <TB1> INFO: 2560 events read in total (297ms).
[11:25:06.920] <TB1> INFO: Test took 1157ms.
[11:25:06.923] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:07.226] <TB1> INFO: Expecting 2560 events.
[11:25:08.079] <TB1> INFO: 2560 events read in total (297ms).
[11:25:08.079] <TB1> INFO: Test took 1157ms.
[11:25:08.081] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:25:08.385] <TB1> INFO: Expecting 2560 events.
[11:25:09.237] <TB1> INFO: 2560 events read in total (296ms).
[11:25:09.238] <TB1> INFO: Test took 1157ms.
[11:25:09.694] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 820 seconds
[11:25:09.694] <TB1> INFO: PH scale (per ROC): 86 90 80 82 78 79 84 80 74 80 70 77 79 77 97 80
[11:25:09.694] <TB1> INFO: PH offset (per ROC): 162 152 171 160 175 162 157 149 174 161 182 162 167 178 166 173
[11:25:09.698] <TB1> INFO: Decoding statistics:
[11:25:09.698] <TB1> INFO: General information:
[11:25:09.698] <TB1> INFO: 16bit words read: 91936
[11:25:09.698] <TB1> INFO: valid events total: 10240
[11:25:09.698] <TB1> INFO: empty events: 7736
[11:25:09.698] <TB1> INFO: valid events with pixels: 2504
[11:25:09.698] <TB1> INFO: valid pixel hits: 2504
[11:25:09.698] <TB1> INFO: Event errors: 0
[11:25:09.698] <TB1> INFO: start marker: 0
[11:25:09.698] <TB1> INFO: stop marker: 0
[11:25:09.698] <TB1> INFO: overflow: 0
[11:25:09.698] <TB1> INFO: invalid 5bit words: 0
[11:25:09.698] <TB1> INFO: invalid XOR eye diagram: 0
[11:25:09.698] <TB1> INFO: frame (failed synchr.): 0
[11:25:09.698] <TB1> INFO: idle data (no TBM trl): 0
[11:25:09.698] <TB1> INFO: no data (only TBM hdr): 0
[11:25:09.698] <TB1> INFO: TBM errors: 0
[11:25:09.698] <TB1> INFO: flawed TBM headers: 0
[11:25:09.698] <TB1> INFO: flawed TBM trailers: 0
[11:25:09.698] <TB1> INFO: event ID mismatches: 0
[11:25:09.698] <TB1> INFO: ROC errors: 0
[11:25:09.698] <TB1> INFO: missing ROC header(s): 0
[11:25:09.698] <TB1> INFO: misplaced readback start: 0
[11:25:09.698] <TB1> INFO: Pixel decoding errors: 0
[11:25:09.698] <TB1> INFO: pixel data incomplete: 0
[11:25:09.698] <TB1> INFO: pixel address: 0
[11:25:09.698] <TB1> INFO: pulse height fill bit: 0
[11:25:09.698] <TB1> INFO: buffer corruption: 0
[11:25:09.865] <TB1> INFO: ######################################################################
[11:25:09.865] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:25:09.865] <TB1> INFO: ######################################################################
[11:25:09.875] <TB1> INFO: scanning low vcal = 10
[11:25:10.128] <TB1> INFO: Expecting 41600 events.
[11:25:13.648] <TB1> INFO: 41600 events read in total (2964ms).
[11:25:13.648] <TB1> INFO: Test took 3773ms.
[11:25:13.651] <TB1> INFO: scanning low vcal = 20
[11:25:13.954] <TB1> INFO: Expecting 41600 events.
[11:25:17.486] <TB1> INFO: 41600 events read in total (2976ms).
[11:25:17.486] <TB1> INFO: Test took 3835ms.
[11:25:17.489] <TB1> INFO: scanning low vcal = 30
[11:25:17.792] <TB1> INFO: Expecting 41600 events.
[11:25:21.326] <TB1> INFO: 41600 events read in total (2978ms).
[11:25:21.326] <TB1> INFO: Test took 3837ms.
[11:25:21.328] <TB1> INFO: scanning low vcal = 40
[11:25:21.626] <TB1> INFO: Expecting 41600 events.
[11:25:25.712] <TB1> INFO: 41600 events read in total (3530ms).
[11:25:25.712] <TB1> INFO: Test took 4384ms.
[11:25:25.716] <TB1> INFO: scanning low vcal = 50
[11:25:25.969] <TB1> INFO: Expecting 41600 events.
[11:25:30.119] <TB1> INFO: 41600 events read in total (3593ms).
[11:25:30.120] <TB1> INFO: Test took 4404ms.
[11:25:30.123] <TB1> INFO: scanning low vcal = 60
[11:25:30.377] <TB1> INFO: Expecting 41600 events.
[11:25:34.526] <TB1> INFO: 41600 events read in total (3593ms).
[11:25:34.527] <TB1> INFO: Test took 4404ms.
[11:25:34.530] <TB1> INFO: scanning low vcal = 70
[11:25:34.783] <TB1> INFO: Expecting 41600 events.
[11:25:38.933] <TB1> INFO: 41600 events read in total (3593ms).
[11:25:38.934] <TB1> INFO: Test took 4404ms.
[11:25:38.937] <TB1> INFO: scanning low vcal = 80
[11:25:39.189] <TB1> INFO: Expecting 41600 events.
[11:25:43.340] <TB1> INFO: 41600 events read in total (3594ms).
[11:25:43.340] <TB1> INFO: Test took 4403ms.
[11:25:43.343] <TB1> INFO: scanning low vcal = 90
[11:25:43.597] <TB1> INFO: Expecting 41600 events.
[11:25:47.747] <TB1> INFO: 41600 events read in total (3594ms).
[11:25:47.748] <TB1> INFO: Test took 4405ms.
[11:25:47.751] <TB1> INFO: scanning low vcal = 100
[11:25:48.002] <TB1> INFO: Expecting 41600 events.
[11:25:52.152] <TB1> INFO: 41600 events read in total (3593ms).
[11:25:52.152] <TB1> INFO: Test took 4401ms.
[11:25:52.155] <TB1> INFO: scanning low vcal = 110
[11:25:52.408] <TB1> INFO: Expecting 41600 events.
[11:25:56.557] <TB1> INFO: 41600 events read in total (3593ms).
[11:25:56.557] <TB1> INFO: Test took 4402ms.
[11:25:56.560] <TB1> INFO: scanning low vcal = 120
[11:25:56.813] <TB1> INFO: Expecting 41600 events.
[11:26:00.963] <TB1> INFO: 41600 events read in total (3593ms).
[11:26:00.963] <TB1> INFO: Test took 4403ms.
[11:26:00.966] <TB1> INFO: scanning low vcal = 130
[11:26:01.217] <TB1> INFO: Expecting 41600 events.
[11:26:05.368] <TB1> INFO: 41600 events read in total (3595ms).
[11:26:05.368] <TB1> INFO: Test took 4402ms.
[11:26:05.371] <TB1> INFO: scanning low vcal = 140
[11:26:05.625] <TB1> INFO: Expecting 41600 events.
[11:26:09.776] <TB1> INFO: 41600 events read in total (3595ms).
[11:26:09.776] <TB1> INFO: Test took 4405ms.
[11:26:09.779] <TB1> INFO: scanning low vcal = 150
[11:26:10.030] <TB1> INFO: Expecting 41600 events.
[11:26:14.182] <TB1> INFO: 41600 events read in total (3595ms).
[11:26:14.182] <TB1> INFO: Test took 4403ms.
[11:26:14.185] <TB1> INFO: scanning low vcal = 160
[11:26:14.438] <TB1> INFO: Expecting 41600 events.
[11:26:18.588] <TB1> INFO: 41600 events read in total (3594ms).
[11:26:18.588] <TB1> INFO: Test took 4403ms.
[11:26:18.591] <TB1> INFO: scanning low vcal = 170
[11:26:18.842] <TB1> INFO: Expecting 41600 events.
[11:26:22.992] <TB1> INFO: 41600 events read in total (3594ms).
[11:26:22.992] <TB1> INFO: Test took 4401ms.
[11:26:22.996] <TB1> INFO: scanning low vcal = 180
[11:26:23.247] <TB1> INFO: Expecting 41600 events.
[11:26:27.397] <TB1> INFO: 41600 events read in total (3594ms).
[11:26:27.398] <TB1> INFO: Test took 4402ms.
[11:26:27.401] <TB1> INFO: scanning low vcal = 190
[11:26:27.652] <TB1> INFO: Expecting 41600 events.
[11:26:31.803] <TB1> INFO: 41600 events read in total (3595ms).
[11:26:31.804] <TB1> INFO: Test took 4403ms.
[11:26:31.807] <TB1> INFO: scanning low vcal = 200
[11:26:32.060] <TB1> INFO: Expecting 41600 events.
[11:26:36.209] <TB1> INFO: 41600 events read in total (3593ms).
[11:26:36.210] <TB1> INFO: Test took 4403ms.
[11:26:36.212] <TB1> INFO: scanning low vcal = 210
[11:26:36.467] <TB1> INFO: Expecting 41600 events.
[11:26:40.616] <TB1> INFO: 41600 events read in total (3592ms).
[11:26:40.617] <TB1> INFO: Test took 4404ms.
[11:26:40.620] <TB1> INFO: scanning low vcal = 220
[11:26:40.871] <TB1> INFO: Expecting 41600 events.
[11:26:45.021] <TB1> INFO: 41600 events read in total (3594ms).
[11:26:45.022] <TB1> INFO: Test took 4402ms.
[11:26:45.025] <TB1> INFO: scanning low vcal = 230
[11:26:45.277] <TB1> INFO: Expecting 41600 events.
[11:26:49.426] <TB1> INFO: 41600 events read in total (3593ms).
[11:26:49.427] <TB1> INFO: Test took 4402ms.
[11:26:49.433] <TB1> INFO: scanning low vcal = 240
[11:26:49.684] <TB1> INFO: Expecting 41600 events.
[11:26:53.834] <TB1> INFO: 41600 events read in total (3593ms).
[11:26:53.834] <TB1> INFO: Test took 4401ms.
[11:26:53.837] <TB1> INFO: scanning low vcal = 250
[11:26:54.089] <TB1> INFO: Expecting 41600 events.
[11:26:58.238] <TB1> INFO: 41600 events read in total (3593ms).
[11:26:58.238] <TB1> INFO: Test took 4401ms.
[11:26:58.242] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[11:26:58.495] <TB1> INFO: Expecting 41600 events.
[11:27:02.645] <TB1> INFO: 41600 events read in total (3594ms).
[11:27:02.645] <TB1> INFO: Test took 4402ms.
[11:27:02.648] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[11:27:02.901] <TB1> INFO: Expecting 41600 events.
[11:27:07.051] <TB1> INFO: 41600 events read in total (3593ms).
[11:27:07.052] <TB1> INFO: Test took 4404ms.
[11:27:07.055] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[11:27:07.306] <TB1> INFO: Expecting 41600 events.
[11:27:11.455] <TB1> INFO: 41600 events read in total (3592ms).
[11:27:11.456] <TB1> INFO: Test took 4401ms.
[11:27:11.459] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[11:27:11.710] <TB1> INFO: Expecting 41600 events.
[11:27:15.860] <TB1> INFO: 41600 events read in total (3594ms).
[11:27:15.860] <TB1> INFO: Test took 4401ms.
[11:27:15.863] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:27:16.115] <TB1> INFO: Expecting 41600 events.
[11:27:20.267] <TB1> INFO: 41600 events read in total (3596ms).
[11:27:20.267] <TB1> INFO: Test took 4404ms.
[11:27:20.687] <TB1> INFO: PixTestGainPedestal::measure() done
[11:27:50.574] <TB1> INFO: PixTestGainPedestal::fit() done
[11:27:50.574] <TB1> INFO: non-linearity mean: 0.957 0.958 0.956 0.958 0.957 0.956 0.960 0.956 0.959 0.951 0.954 0.958 0.961 0.955 0.960 0.956
[11:27:50.574] <TB1> INFO: non-linearity RMS: 0.006 0.006 0.005 0.006 0.005 0.005 0.006 0.006 0.007 0.006 0.006 0.007 0.005 0.006 0.005 0.005
[11:27:50.574] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[11:27:50.591] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[11:27:50.608] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[11:27:50.625] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[11:27:50.642] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[11:27:50.659] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[11:27:50.676] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[11:27:50.692] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[11:27:50.709] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[11:27:50.726] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[11:27:50.743] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[11:27:50.760] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[11:27:50.777] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[11:27:50.793] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[11:27:50.810] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[11:27:50.827] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[11:27:50.844] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 160 seconds
[11:27:50.844] <TB1> INFO: Decoding statistics:
[11:27:50.844] <TB1> INFO: General information:
[11:27:50.844] <TB1> INFO: 16bit words read: 2662400
[11:27:50.844] <TB1> INFO: valid events total: 166400
[11:27:50.844] <TB1> INFO: empty events: 0
[11:27:50.844] <TB1> INFO: valid events with pixels: 166400
[11:27:50.844] <TB1> INFO: valid pixel hits: 665565
[11:27:50.844] <TB1> INFO: Event errors: 0
[11:27:50.844] <TB1> INFO: start marker: 0
[11:27:50.844] <TB1> INFO: stop marker: 0
[11:27:50.844] <TB1> INFO: overflow: 0
[11:27:50.844] <TB1> INFO: invalid 5bit words: 0
[11:27:50.844] <TB1> INFO: invalid XOR eye diagram: 0
[11:27:50.844] <TB1> INFO: frame (failed synchr.): 0
[11:27:50.844] <TB1> INFO: idle data (no TBM trl): 0
[11:27:50.844] <TB1> INFO: no data (only TBM hdr): 0
[11:27:50.844] <TB1> INFO: TBM errors: 0
[11:27:50.844] <TB1> INFO: flawed TBM headers: 0
[11:27:50.844] <TB1> INFO: flawed TBM trailers: 0
[11:27:50.844] <TB1> INFO: event ID mismatches: 0
[11:27:50.844] <TB1> INFO: ROC errors: 0
[11:27:50.844] <TB1> INFO: missing ROC header(s): 0
[11:27:50.844] <TB1> INFO: misplaced readback start: 0
[11:27:50.844] <TB1> INFO: Pixel decoding errors: 0
[11:27:50.844] <TB1> INFO: pixel data incomplete: 0
[11:27:50.844] <TB1> INFO: pixel address: 0
[11:27:50.844] <TB1> INFO: pulse height fill bit: 0
[11:27:50.844] <TB1> INFO: buffer corruption: 0
[11:27:50.854] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C15.dat
[11:27:50.855] <TB1> INFO: ######################################################################
[11:27:50.855] <TB1> INFO: PixTestReadback::doTest()
[11:27:50.855] <TB1> INFO: ######################################################################
[11:27:50.855] <TB1> INFO: ----------------------------------------------------------------------
[11:27:50.855] <TB1> INFO: PixTestReadback::CalibrateVd()
[11:27:50.855] <TB1> INFO: ----------------------------------------------------------------------
[11:28:00.310] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C0.dat
[11:28:00.310] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C1.dat
[11:28:00.311] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C2.dat
[11:28:00.311] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C3.dat
[11:28:00.311] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C4.dat
[11:28:00.311] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C5.dat
[11:28:00.311] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C6.dat
[11:28:00.311] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C7.dat
[11:28:00.311] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C8.dat
[11:28:00.311] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C9.dat
[11:28:00.311] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C10.dat
[11:28:00.311] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C11.dat
[11:28:00.311] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C12.dat
[11:28:00.311] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C13.dat
[11:28:00.311] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C14.dat
[11:28:00.311] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C15.dat
[11:28:00.339] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:28:00.339] <TB1> INFO: ----------------------------------------------------------------------
[11:28:00.339] <TB1> INFO: PixTestReadback::CalibrateVa()
[11:28:00.339] <TB1> INFO: ----------------------------------------------------------------------
[11:28:09.795] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C0.dat
[11:28:09.795] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C1.dat
[11:28:09.796] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C2.dat
[11:28:09.796] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C3.dat
[11:28:09.796] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C4.dat
[11:28:09.796] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C5.dat
[11:28:09.796] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C6.dat
[11:28:09.796] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C7.dat
[11:28:09.796] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C8.dat
[11:28:09.796] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C9.dat
[11:28:09.796] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C10.dat
[11:28:09.796] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C11.dat
[11:28:09.796] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C12.dat
[11:28:09.796] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C13.dat
[11:28:09.796] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C14.dat
[11:28:09.797] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C15.dat
[11:28:09.824] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:28:09.824] <TB1> INFO: ----------------------------------------------------------------------
[11:28:09.824] <TB1> INFO: PixTestReadback::readbackVbg()
[11:28:09.824] <TB1> INFO: ----------------------------------------------------------------------
[11:28:17.092] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:28:17.092] <TB1> INFO: ----------------------------------------------------------------------
[11:28:17.092] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[11:28:17.092] <TB1> INFO: ----------------------------------------------------------------------
[11:28:17.092] <TB1> INFO: Vbg will be calibrated using Vd calibration
[11:28:17.092] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 142.7calibrated Vbg = 1.17109 :::*/*/*/*/
[11:28:17.092] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 148.2calibrated Vbg = 1.17349 :::*/*/*/*/
[11:28:17.092] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 153.5calibrated Vbg = 1.17821 :::*/*/*/*/
[11:28:17.092] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 150.5calibrated Vbg = 1.18158 :::*/*/*/*/
[11:28:17.092] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 156.3calibrated Vbg = 1.18481 :::*/*/*/*/
[11:28:17.092] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 154.5calibrated Vbg = 1.18821 :::*/*/*/*/
[11:28:17.092] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 148.4calibrated Vbg = 1.18854 :::*/*/*/*/
[11:28:17.092] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 151.2calibrated Vbg = 1.18784 :::*/*/*/*/
[11:28:17.092] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 155.6calibrated Vbg = 1.1789 :::*/*/*/*/
[11:28:17.092] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 148.1calibrated Vbg = 1.18871 :::*/*/*/*/
[11:28:17.093] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 157.5calibrated Vbg = 1.19451 :::*/*/*/*/
[11:28:17.093] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 157.4calibrated Vbg = 1.18357 :::*/*/*/*/
[11:28:17.093] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 151.3calibrated Vbg = 1.18382 :::*/*/*/*/
[11:28:17.093] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 160.5calibrated Vbg = 1.17749 :::*/*/*/*/
[11:28:17.093] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 157.3calibrated Vbg = 1.187 :::*/*/*/*/
[11:28:17.093] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 164.7calibrated Vbg = 1.18785 :::*/*/*/*/
[11:28:17.094] <TB1> INFO: ----------------------------------------------------------------------
[11:28:17.094] <TB1> INFO: PixTestReadback::CalibrateIa()
[11:28:17.094] <TB1> INFO: ----------------------------------------------------------------------
[11:30:52.132] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C0.dat
[11:30:52.132] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C1.dat
[11:30:52.132] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C2.dat
[11:30:52.132] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C3.dat
[11:30:52.132] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C4.dat
[11:30:52.132] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C5.dat
[11:30:52.132] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C6.dat
[11:30:52.132] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C7.dat
[11:30:52.132] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C8.dat
[11:30:52.133] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C9.dat
[11:30:52.133] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C10.dat
[11:30:52.133] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C11.dat
[11:30:52.133] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C12.dat
[11:30:52.133] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C13.dat
[11:30:52.133] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C14.dat
[11:30:52.133] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2217_FullQualification_2016-04-24_11h48m_1461491304//000_FulltestPxar_m20//readbackCal_C15.dat
[11:30:52.161] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:30:52.161] <TB1> INFO: PixTestReadback::doTest() done
[11:30:52.161] <TB1> INFO: Decoding statistics:
[11:30:52.161] <TB1> INFO: General information:
[11:30:52.161] <TB1> INFO: 16bit words read: 1024
[11:30:52.161] <TB1> INFO: valid events total: 128
[11:30:52.161] <TB1> INFO: empty events: 128
[11:30:52.161] <TB1> INFO: valid events with pixels: 0
[11:30:52.161] <TB1> INFO: valid pixel hits: 0
[11:30:52.161] <TB1> INFO: Event errors: 0
[11:30:52.161] <TB1> INFO: start marker: 0
[11:30:52.161] <TB1> INFO: stop marker: 0
[11:30:52.161] <TB1> INFO: overflow: 0
[11:30:52.161] <TB1> INFO: invalid 5bit words: 0
[11:30:52.161] <TB1> INFO: invalid XOR eye diagram: 0
[11:30:52.161] <TB1> INFO: frame (failed synchr.): 0
[11:30:52.161] <TB1> INFO: idle data (no TBM trl): 0
[11:30:52.161] <TB1> INFO: no data (only TBM hdr): 0
[11:30:52.161] <TB1> INFO: TBM errors: 0
[11:30:52.161] <TB1> INFO: flawed TBM headers: 0
[11:30:52.161] <TB1> INFO: flawed TBM trailers: 0
[11:30:52.162] <TB1> INFO: event ID mismatches: 0
[11:30:52.162] <TB1> INFO: ROC errors: 0
[11:30:52.162] <TB1> INFO: missing ROC header(s): 0
[11:30:52.162] <TB1> INFO: misplaced readback start: 0
[11:30:52.162] <TB1> INFO: Pixel decoding errors: 0
[11:30:52.162] <TB1> INFO: pixel data incomplete: 0
[11:30:52.162] <TB1> INFO: pixel address: 0
[11:30:52.162] <TB1> INFO: pulse height fill bit: 0
[11:30:52.162] <TB1> INFO: buffer corruption: 0
[11:30:52.185] <TB1> INFO: Decoding statistics:
[11:30:52.185] <TB1> INFO: General information:
[11:30:52.185] <TB1> INFO: 16bit words read: 2755360
[11:30:52.185] <TB1> INFO: valid events total: 176768
[11:30:52.185] <TB1> INFO: empty events: 7864
[11:30:52.185] <TB1> INFO: valid events with pixels: 168904
[11:30:52.185] <TB1> INFO: valid pixel hits: 668069
[11:30:52.185] <TB1> INFO: Event errors: 0
[11:30:52.185] <TB1> INFO: start marker: 0
[11:30:52.185] <TB1> INFO: stop marker: 0
[11:30:52.185] <TB1> INFO: overflow: 0
[11:30:52.185] <TB1> INFO: invalid 5bit words: 0
[11:30:52.185] <TB1> INFO: invalid XOR eye diagram: 0
[11:30:52.185] <TB1> INFO: frame (failed synchr.): 0
[11:30:52.185] <TB1> INFO: idle data (no TBM trl): 0
[11:30:52.185] <TB1> INFO: no data (only TBM hdr): 0
[11:30:52.185] <TB1> INFO: TBM errors: 0
[11:30:52.185] <TB1> INFO: flawed TBM headers: 0
[11:30:52.185] <TB1> INFO: flawed TBM trailers: 0
[11:30:52.185] <TB1> INFO: event ID mismatches: 0
[11:30:52.185] <TB1> INFO: ROC errors: 0
[11:30:52.185] <TB1> INFO: missing ROC header(s): 0
[11:30:52.185] <TB1> INFO: misplaced readback start: 0
[11:30:52.185] <TB1> INFO: Pixel decoding errors: 0
[11:30:52.185] <TB1> INFO: pixel data incomplete: 0
[11:30:52.185] <TB1> INFO: pixel address: 0
[11:30:52.185] <TB1> INFO: pulse height fill bit: 0
[11:30:52.185] <TB1> INFO: buffer corruption: 0
[11:30:52.185] <TB1> INFO: enter test to run
[11:30:52.185] <TB1> INFO: test: exit no parameter change
[11:30:52.377] <TB1> QUIET: Connection to board 153 closed.
[11:30:52.460] <TB1> INFO: pXar: this is the end, my friend