[11:02:31.092] <TB1> INFO: *** Welcome to pxar ***
[11:02:31.092] <TB1> INFO: *** Today: 2016/01/20
[11:02:31.191] <TB1> INFO: *** Version: 3197-dirty
[11:02:31.191] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters_C15.dat
[11:02:31.191] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//tbmParameters_C0b.dat
[11:02:31.191] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//defaultMaskFile.dat
[11:02:31.191] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//trimParameters_C15.dat
[11:02:31.260] <TB1> INFO: clk: 4
[11:02:31.260] <TB1> INFO: ctr: 4
[11:02:31.260] <TB1> INFO: sda: 19
[11:02:31.260] <TB1> INFO: tin: 9
[11:02:31.260] <TB1> INFO: level: 15
[11:02:31.260] <TB1> INFO: triggerdelay: 0
[11:02:31.260] <TB1> QUIET: Instanciating API for pxar prod-12
[11:02:31.260] <TB1> INFO: Log level: INFO
[11:02:31.267] <TB1> INFO: Found DTB DTB_WXBYFL
[11:02:31.277] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[11:02:31.281] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[11:02:31.283] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[11:02:32.808] <TB1> INFO: DUT info:
[11:02:32.808] <TB1> INFO: The DUT currently contains the following objects:
[11:02:32.808] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[11:02:32.808] <TB1> INFO: TBM Core alpha (0): 7 registers set
[11:02:32.808] <TB1> INFO: TBM Core beta (1): 7 registers set
[11:02:32.808] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:02:32.808] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:32.808] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:32.808] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:32.809] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:32.809] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:32.809] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:32.809] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:32.809] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:32.809] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:32.809] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:32.809] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:32.809] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:32.809] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:32.809] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:32.809] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:32.809] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:02:33.210] <TB1> INFO: enter 'restricted' command line mode
[11:02:33.210] <TB1> INFO: enter test to run
[11:02:33.210] <TB1> INFO: test: pretest no parameter change
[11:02:33.210] <TB1> INFO: running: pretest
[11:02:33.222] <TB1> INFO: ----------------------------------------------------------------------
[11:02:33.222] <TB1> INFO: PixTestPretest::programROC()
[11:02:33.222] <TB1> INFO: ----------------------------------------------------------------------
[11:02:51.243] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[11:02:51.243] <TB1> INFO: IA differences per ROC: 25.0 22.6 22.6 23.4 24.2 25.8 28.2 21.8 21.0 25.8 25.8 23.4 24.2 23.4 20.1 18.5
[11:02:51.362] <TB1> INFO: enter test to run
[11:02:51.362] <TB1> INFO: test: pretest no parameter change
[11:02:51.362] <TB1> INFO: running: pretest
[11:02:51.363] <TB1> INFO: ----------------------------------------------------------------------
[11:02:51.363] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[11:02:51.363] <TB1> INFO: ----------------------------------------------------------------------
[11:03:04.025] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 365.8 mA = 22.8625 mA/ROC
[11:03:04.025] <TB1> INFO: i(loss) [mA/ROC]: 16.9 16.9 16.9 17.7 16.9 15.3 ->8.9<- 16.9 16.9 16.9 15.3 16.9 16.9 16.9 16.9 16.9
[11:03:04.070] <TB1> INFO: enter test to run
[11:03:04.070] <TB1> INFO: test: pretest no parameter change
[11:03:04.070] <TB1> INFO: running: pretest
[11:03:04.070] <TB1> INFO: ----------------------------------------------------------------------
[11:03:04.070] <TB1> INFO: PixTestPretest::findTiming()
[11:03:04.070] <TB1> INFO: ----------------------------------------------------------------------
[11:03:04.070] <TB1> INFO: PixTestCmd::init()
[11:03:04.719] <TB1> WARNING: Not unmasking DUT, not setting Calibrate bits!
[11:04:57.565] <TB1> INFO: TBM phases: 160MHz: 0, 400MHz: 7, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[11:04:57.565] <TB1> INFO: (success/tries = 100/100), width = 4
[11:04:57.565] <TB1> INFO: enter test to run
[11:04:57.565] <TB1> INFO: test: pretest no parameter change
[11:04:57.565] <TB1> INFO: running: pretest
[11:04:57.567] <TB1> INFO: ----------------------------------------------------------------------
[11:04:57.567] <TB1> INFO: PixTestPretest::findWorkingPixel()
[11:04:57.567] <TB1> INFO: ----------------------------------------------------------------------
[11:04:57.709] <TB1> INFO: Expecting 231680 events.
[11:05:06.046] <TB1> INFO: 231680 events read in total (7620ms).
[11:05:06.050] <TB1> INFO: Test took 8478ms.
[11:05:06.367] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[11:05:06.448] <TB1> INFO: enter test to run
[11:05:06.449] <TB1> INFO: test: pretest no parameter change
[11:05:06.449] <TB1> INFO: running: pretest
[11:05:06.450] <TB1> INFO: ----------------------------------------------------------------------
[11:05:06.450] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[11:05:06.450] <TB1> INFO: ----------------------------------------------------------------------
[11:05:06.589] <TB1> INFO: Expecting 231680 events.
[11:05:15.133] <TB1> INFO: 231680 events read in total (7828ms).
[11:05:15.136] <TB1> INFO: Test took 8681ms.
[11:05:15.478] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[11:05:15.478] <TB1> INFO: CalDel: 179 160 155 199 170 174 _ 96 167 141 155 146 150 178 158 148 158
[11:05:15.478] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[11:05:15.510] <TB1> INFO: enter test to run
[11:05:15.510] <TB1> INFO: test: pretest no parameter change
[11:05:15.510] <TB1> INFO: running: pretest
[11:05:15.510] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters_C0.dat
[11:05:15.511] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters_C1.dat
[11:05:15.511] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters_C2.dat
[11:05:15.511] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters_C3.dat
[11:05:15.511] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters_C4.dat
[11:05:15.512] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters_C5.dat
[11:05:15.512] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters_C6.dat
[11:05:15.512] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters_C7.dat
[11:05:15.512] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters_C8.dat
[11:05:15.512] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters_C9.dat
[11:05:15.512] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters_C10.dat
[11:05:15.512] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters_C11.dat
[11:05:15.512] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters_C12.dat
[11:05:15.512] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters_C13.dat
[11:05:15.512] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters_C14.dat
[11:05:15.512] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters_C15.dat
[11:05:15.513] <TB1> INFO: enter test to run
[11:05:15.513] <TB1> INFO: test: fulltest no parameter change
[11:05:15.513] <TB1> INFO: running: fulltest
[11:05:15.513] <TB1> INFO: ######################################################################
[11:05:15.513] <TB1> INFO: PixTestFullTest::doTest()
[11:05:15.513] <TB1> INFO: ######################################################################
[11:05:15.514] <TB1> INFO: ######################################################################
[11:05:15.514] <TB1> INFO: PixTestAlive::doTest()
[11:05:15.514] <TB1> INFO: ######################################################################
[11:05:15.516] <TB1> INFO: ----------------------------------------------------------------------
[11:05:15.516] <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)
[11:05:15.516] <TB1> INFO: ----------------------------------------------------------------------
[11:05:15.821] <TB1> INFO: Expecting 41600 events.
[11:05:20.047] <TB1> INFO: 41600 events read in total (3509ms).
[11:05:20.048] <TB1> INFO: Test took 4530ms.
[11:05:20.054] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:20.355] <TB1> INFO: PixTestAlive::aliveTest() done
[11:05:20.355] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 4160 0 0 0 0 1 0 0 0 1
[11:05:20.357] <TB1> INFO: ----------------------------------------------------------------------
[11:05:20.357] <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)
[11:05:20.357] <TB1> INFO: ----------------------------------------------------------------------
[11:05:20.694] <TB1> INFO: Expecting 41600 events.
[11:05:23.850] <TB1> INFO: 41600 events read in total (2439ms).
[11:05:23.850] <TB1> INFO: Test took 3491ms.
[11:05:23.850] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:23.850] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[11:05:24.178] <TB1> INFO: PixTestAlive::maskTest() done
[11:05:24.178] <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
[11:05:24.180] <TB1> INFO: ----------------------------------------------------------------------
[11:05:24.180] <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)
[11:05:24.180] <TB1> INFO: ----------------------------------------------------------------------
[11:05:24.515] <TB1> INFO: Expecting 41600 events.
[11:05:28.769] <TB1> INFO: 41600 events read in total (3537ms).
[11:05:28.769] <TB1> INFO: Test took 4587ms.
[11:05:28.775] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:29.084] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[11:05:29.084] <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
[11:05:29.084] <TB1> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[11:05:29.084] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:05:29.084] <TB1> INFO: Decoding statistics:
[11:05:29.084] <TB1> INFO: General information:
[11:05:29.084] <TB1> INFO: 16bit words read: 0
[11:05:29.084] <TB1> INFO: valid events total: 0
[11:05:29.085] <TB1> INFO: empty events: 0
[11:05:29.085] <TB1> INFO: valid events with pixels: 0
[11:05:29.085] <TB1> INFO: valid pixel hits: 0
[11:05:29.085] <TB1> INFO: Event errors: 0
[11:05:29.085] <TB1> INFO: start marker: 0
[11:05:29.085] <TB1> INFO: stop marker: 0
[11:05:29.085] <TB1> INFO: overflow: 0
[11:05:29.085] <TB1> INFO: invalid 5bit words: 0
[11:05:29.085] <TB1> INFO: invalid XOR eye diagram: 0
[11:05:29.085] <TB1> INFO: TBM errors: 0
[11:05:29.085] <TB1> INFO: flawed TBM headers: 0
[11:05:29.085] <TB1> INFO: flawed TBM trailers: 0
[11:05:29.085] <TB1> INFO: event ID mismatches: 0
[11:05:29.085] <TB1> INFO: ROC errors: 0
[11:05:29.085] <TB1> INFO: missing ROC header(s): 0
[11:05:29.085] <TB1> INFO: misplaced readback start: 0
[11:05:29.085] <TB1> INFO: Pixel decoding errors: 0
[11:05:29.085] <TB1> INFO: pixel data incomplete: 0
[11:05:29.085] <TB1> INFO: pixel address: 0
[11:05:29.085] <TB1> INFO: pulse height fill bit: 0
[11:05:29.085] <TB1> INFO: buffer corruption: 0
[11:05:29.095] <TB1> INFO: ######################################################################
[11:05:29.095] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[11:05:29.095] <TB1> INFO: ######################################################################
[11:05:29.099] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[11:05:29.109] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:05:29.110] <TB1> INFO: run 1 of 1
[11:05:29.417] <TB1> INFO: Expecting 3120000 events.
[11:06:09.044] <TB1> INFO: 896800 events read in total (38910ms).
[11:06:47.886] <TB1> INFO: 1785245 events read in total (77752ms).
[11:07:26.635] <TB1> INFO: 2685450 events read in total (116501ms).
[11:07:45.372] <TB1> INFO: 3120000 events read in total (135238ms).
[11:07:45.412] <TB1> INFO: Test took 136302ms.
[11:07:45.494] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:08:04.419] <TB1> INFO: PixTestBBMap::doTest() done, duration: 155 seconds
[11:08:04.419] <TB1> INFO: number of dead bumps (per ROC): 9 12 5 4 20 21 500 45 27 21 33 33 7 8 39 27
[11:08:04.419] <TB1> INFO: separation cut (per ROC): 68 60 62 57 54 46 256 48 60 57 56 64 66 61 66 62
[11:08:04.420] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:08:04.420] <TB1> INFO: Decoding statistics:
[11:08:04.420] <TB1> INFO: General information:
[11:08:04.420] <TB1> INFO: 16bit words read: 0
[11:08:04.420] <TB1> INFO: valid events total: 0
[11:08:04.420] <TB1> INFO: empty events: 0
[11:08:04.420] <TB1> INFO: valid events with pixels: 0
[11:08:04.420] <TB1> INFO: valid pixel hits: 0
[11:08:04.420] <TB1> INFO: Event errors: 0
[11:08:04.420] <TB1> INFO: start marker: 0
[11:08:04.420] <TB1> INFO: stop marker: 0
[11:08:04.420] <TB1> INFO: overflow: 0
[11:08:04.420] <TB1> INFO: invalid 5bit words: 0
[11:08:04.420] <TB1> INFO: invalid XOR eye diagram: 0
[11:08:04.420] <TB1> INFO: TBM errors: 0
[11:08:04.420] <TB1> INFO: flawed TBM headers: 0
[11:08:04.420] <TB1> INFO: flawed TBM trailers: 0
[11:08:04.420] <TB1> INFO: event ID mismatches: 0
[11:08:04.420] <TB1> INFO: ROC errors: 0
[11:08:04.420] <TB1> INFO: missing ROC header(s): 0
[11:08:04.420] <TB1> INFO: misplaced readback start: 0
[11:08:04.420] <TB1> INFO: Pixel decoding errors: 0
[11:08:04.420] <TB1> INFO: pixel data incomplete: 0
[11:08:04.420] <TB1> INFO: pixel address: 0
[11:08:04.420] <TB1> INFO: pulse height fill bit: 0
[11:08:04.420] <TB1> INFO: buffer corruption: 0
[11:08:04.591] <TB1> INFO: ######################################################################
[11:08:04.591] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[11:08:04.591] <TB1> INFO: ######################################################################
[11:08:04.592] <TB1> INFO: ----------------------------------------------------------------------
[11:08:04.592] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[11:08:04.592] <TB1> INFO: ----------------------------------------------------------------------
[11:08:04.592] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[11:08:04.599] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[11:08:04.599] <TB1> INFO: run 1 of 1
[11:08:04.903] <TB1> INFO: Expecting 26208000 events.
[11:08:31.883] <TB1> INFO: 771750 events read in total (26262ms).
[11:08:58.152] <TB1> INFO: 1537200 events read in total (52531ms).
[11:09:24.823] <TB1> INFO: 2300450 events read in total (79202ms).
[11:09:50.992] <TB1> INFO: 3065300 events read in total (105371ms).
[11:10:17.241] <TB1> INFO: 3828150 events read in total (131620ms).
[11:10:44.196] <TB1> INFO: 4591100 events read in total (158575ms).
[11:11:11.008] <TB1> INFO: 5351650 events read in total (185387ms).
[11:11:37.595] <TB1> INFO: 6114200 events read in total (211974ms).
[11:12:04.221] <TB1> INFO: 6874800 events read in total (238600ms).
[11:12:31.022] <TB1> INFO: 7637850 events read in total (265401ms).
[11:12:57.026] <TB1> INFO: 8397600 events read in total (291405ms).
[11:13:23.332] <TB1> INFO: 9160900 events read in total (317711ms).
[11:13:49.847] <TB1> INFO: 9920700 events read in total (344226ms).
[11:14:16.222] <TB1> INFO: 10683450 events read in total (370601ms).
[11:14:42.660] <TB1> INFO: 11443500 events read in total (397039ms).
[11:15:08.781] <TB1> INFO: 12206600 events read in total (423160ms).
[11:15:35.186] <TB1> INFO: 12967050 events read in total (449565ms).
[11:16:01.380] <TB1> INFO: 13722600 events read in total (475759ms).
[11:16:27.837] <TB1> INFO: 14475650 events read in total (502216ms).
[11:16:54.345] <TB1> INFO: 15230550 events read in total (528724ms).
[11:17:20.877] <TB1> INFO: 15981150 events read in total (555256ms).
[11:17:46.901] <TB1> INFO: 16732900 events read in total (581280ms).
[11:18:13.233] <TB1> INFO: 17482800 events read in total (607612ms).
[11:18:39.604] <TB1> INFO: 18235900 events read in total (633983ms).
[11:19:06.390] <TB1> INFO: 18984500 events read in total (660769ms).
[11:19:32.531] <TB1> INFO: 19734300 events read in total (686910ms).
[11:19:58.994] <TB1> INFO: 20483300 events read in total (713373ms).
[11:20:24.801] <TB1> INFO: 21233600 events read in total (739180ms).
[11:20:50.911] <TB1> INFO: 21982700 events read in total (765290ms).
[11:21:17.744] <TB1> INFO: 22730550 events read in total (792123ms).
[11:21:44.819] <TB1> INFO: 23478300 events read in total (819198ms).
[11:22:11.365] <TB1> INFO: 24227900 events read in total (845744ms).
[11:22:38.054] <TB1> INFO: 24977150 events read in total (872433ms).
[11:23:04.275] <TB1> INFO: 25727450 events read in total (898654ms).
[11:23:21.100] <TB1> INFO: 26208000 events read in total (915479ms).
[11:23:21.143] <TB1> INFO: Test took 916544ms.
[11:23:21.249] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:21.462] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:23:22.967] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:23:24.458] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:23:25.941] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:23:27.464] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:23:29.066] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:23:30.593] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:23:30.636] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:23:32.143] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:23:33.638] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:23:35.157] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:23:36.773] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:23:38.433] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:23:39.924] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:23:41.432] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:23:42.944] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[11:23:44.460] <TB1> INFO: PixTestScurves::scurves() done
[11:23:44.460] <TB1> INFO: Vcal mean: 65.14 64.49 51.07 54.23 56.63 48.39 0.57 48.66 69.69 59.44 56.07 65.64 61.89 55.23 70.49 70.03
[11:23:44.460] <TB1> INFO: Vcal RMS: 4.71 5.18 4.22 5.42 5.30 4.02 2.78 4.33 4.75 4.85 4.85 4.84 4.67 4.82 5.07 4.73
[11:23:44.460] <TB1> INFO: PixTestScurves::fullTest() done, duration: 939 seconds
[11:23:44.460] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:23:44.460] <TB1> INFO: Decoding statistics:
[11:23:44.460] <TB1> INFO: General information:
[11:23:44.460] <TB1> INFO: 16bit words read: 0
[11:23:44.460] <TB1> INFO: valid events total: 0
[11:23:44.460] <TB1> INFO: empty events: 0
[11:23:44.460] <TB1> INFO: valid events with pixels: 0
[11:23:44.460] <TB1> INFO: valid pixel hits: 0
[11:23:44.460] <TB1> INFO: Event errors: 0
[11:23:44.460] <TB1> INFO: start marker: 0
[11:23:44.460] <TB1> INFO: stop marker: 0
[11:23:44.460] <TB1> INFO: overflow: 0
[11:23:44.460] <TB1> INFO: invalid 5bit words: 0
[11:23:44.460] <TB1> INFO: invalid XOR eye diagram: 0
[11:23:44.460] <TB1> INFO: TBM errors: 0
[11:23:44.460] <TB1> INFO: flawed TBM headers: 0
[11:23:44.460] <TB1> INFO: flawed TBM trailers: 0
[11:23:44.460] <TB1> INFO: event ID mismatches: 0
[11:23:44.460] <TB1> INFO: ROC errors: 0
[11:23:44.460] <TB1> INFO: missing ROC header(s): 0
[11:23:44.460] <TB1> INFO: misplaced readback start: 0
[11:23:44.460] <TB1> INFO: Pixel decoding errors: 0
[11:23:44.460] <TB1> INFO: pixel data incomplete: 0
[11:23:44.461] <TB1> INFO: pixel address: 0
[11:23:44.461] <TB1> INFO: pulse height fill bit: 0
[11:23:44.461] <TB1> INFO: buffer corruption: 0
[11:23:44.532] <TB1> INFO: ######################################################################
[11:23:44.532] <TB1> INFO: PixTestTrim::doTest()
[11:23:44.532] <TB1> INFO: ######################################################################
[11:23:44.533] <TB1> INFO: ----------------------------------------------------------------------
[11:23:44.533] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[11:23:44.533] <TB1> INFO: ----------------------------------------------------------------------
[11:23:44.615] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[11:23:44.615] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[11:23:44.623] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:23:44.623] <TB1> INFO: run 1 of 1
[11:23:44.930] <TB1> INFO: Expecting 6281600 events.
[11:24:21.445] <TB1> INFO: 1093880 events read in total (35799ms).
[11:24:57.358] <TB1> INFO: 2181350 events read in total (71712ms).
[11:25:32.768] <TB1> INFO: 3262960 events read in total (107122ms).
[11:26:08.749] <TB1> INFO: 4352300 events read in total (143103ms).
[11:26:44.660] <TB1> INFO: 5443160 events read in total (179014ms).
[11:27:12.595] <TB1> INFO: 6281600 events read in total (206949ms).
[11:27:12.629] <TB1> INFO: Test took 208006ms.
[11:27:12.677] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:27:30.706] <TB1> INFO: ROC 0 VthrComp = 71
[11:27:30.706] <TB1> INFO: ROC 1 VthrComp = 69
[11:27:30.706] <TB1> INFO: ROC 2 VthrComp = 57
[11:27:30.706] <TB1> INFO: ROC 3 VthrComp = 56
[11:27:30.706] <TB1> INFO: ROC 4 VthrComp = 63
[11:27:30.706] <TB1> INFO: ROC 5 VthrComp = 53
[11:27:30.707] <TB1> INFO: ROC 6 VthrComp = 1
[11:27:30.707] <TB1> INFO: ROC 7 VthrComp = 54
[11:27:30.707] <TB1> INFO: ROC 8 VthrComp = 75
[11:27:30.707] <TB1> INFO: ROC 9 VthrComp = 68
[11:27:30.707] <TB1> INFO: ROC 10 VthrComp = 66
[11:27:30.707] <TB1> INFO: ROC 11 VthrComp = 71
[11:27:30.707] <TB1> INFO: ROC 12 VthrComp = 70
[11:27:30.707] <TB1> INFO: ROC 13 VthrComp = 62
[11:27:30.708] <TB1> INFO: ROC 14 VthrComp = 74
[11:27:30.708] <TB1> INFO: ROC 15 VthrComp = 75
[11:27:30.708] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[11:27:30.708] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[11:27:30.716] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:27:30.716] <TB1> INFO: run 1 of 1
[11:27:31.057] <TB1> INFO: Expecting 6281600 events.
[11:28:02.965] <TB1> INFO: 755100 events read in total (31192ms).
[11:28:33.436] <TB1> INFO: 1508060 events read in total (61663ms).
[11:29:03.768] <TB1> INFO: 2261110 events read in total (91996ms).
[11:29:34.201] <TB1> INFO: 3013920 events read in total (122428ms).
[11:30:04.713] <TB1> INFO: 3762600 events read in total (152940ms).
[11:30:35.111] <TB1> INFO: 4508180 events read in total (183338ms).
[11:31:05.641] <TB1> INFO: 5252100 events read in total (213868ms).
[11:31:36.052] <TB1> INFO: 5995630 events read in total (244279ms).
[11:31:48.098] <TB1> INFO: 6281600 events read in total (256326ms).
[11:31:48.152] <TB1> INFO: Test took 257436ms.
[11:31:48.283] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:32:10.758] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 57.2443 for pixel 2/5 mean/min/max = 44.531/31.6547/57.4073
[11:32:10.758] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 56.8877 for pixel 34/70 mean/min/max = 44.5665/32.0745/57.0584
[11:32:10.758] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 57.2443 for pixel 28/79 mean/min/max = 45.9133/34.4833/57.3432
[11:32:10.759] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 64.1115 for pixel 2/18 mean/min/max = 49.1862/34.2181/64.1542
[11:32:10.759] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 57.6402 for pixel 15/56 mean/min/max = 45.0203/32.3573/57.6833
[11:32:10.759] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 57.9829 for pixel 12/2 mean/min/max = 46.1477/34.3005/57.9949
[11:32:10.760] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 97.3644 for pixel 1/79 mean/min/max = 73.7538/34.7871/112.721
[11:32:10.760] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 58.1754 for pixel 0/18 mean/min/max = 45.271/32.2986/58.2435
[11:32:10.760] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 57.6482 for pixel 15/79 mean/min/max = 46.389/34.9265/57.8515
[11:32:10.761] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 55.9143 for pixel 0/27 mean/min/max = 43.7858/31.5672/56.0044
[11:32:10.761] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 55.6962 for pixel 11/79 mean/min/max = 43.7343/31.7682/55.7003
[11:32:10.761] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 56.4777 for pixel 2/60 mean/min/max = 44.5615/32.6081/56.5148
[11:32:10.762] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 55.5628 for pixel 25/11 mean/min/max = 43.406/31.2328/55.5791
[11:32:10.762] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 56.671 for pixel 7/59 mean/min/max = 44.6092/32.2849/56.9335
[11:32:10.762] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 59.1057 for pixel 50/77 mean/min/max = 46.7004/34.1368/59.2641
[11:32:10.762] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 57.433 for pixel 16/2 mean/min/max = 46.0764/34.6009/57.5519
[11:32:10.763] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:32:10.896] <TB1> INFO: Expecting 514560 events.
[11:32:21.647] <TB1> INFO: 514560 events read in total (10035ms).
[11:32:21.651] <TB1> INFO: Expecting 514560 events.
[11:32:32.312] <TB1> INFO: 514560 events read in total (10071ms).
[11:32:32.319] <TB1> INFO: Expecting 514560 events.
[11:32:42.780] <TB1> INFO: 514560 events read in total (9883ms).
[11:32:42.786] <TB1> INFO: Expecting 514560 events.
[11:32:53.173] <TB1> INFO: 514560 events read in total (9794ms).
[11:32:53.180] <TB1> INFO: Expecting 514560 events.
[11:33:03.640] <TB1> INFO: 514560 events read in total (9869ms).
[11:33:03.649] <TB1> INFO: Expecting 514560 events.
[11:33:14.357] <TB1> INFO: 514560 events read in total (10123ms).
[11:33:14.366] <TB1> INFO: Expecting 514560 events.
[11:33:24.706] <TB1> INFO: 514560 events read in total (9751ms).
[11:33:24.717] <TB1> INFO: Expecting 514560 events.
[11:33:35.261] <TB1> INFO: 514560 events read in total (9947ms).
[11:33:35.274] <TB1> INFO: Expecting 514560 events.
[11:33:46.328] <TB1> INFO: 514560 events read in total (10469ms).
[11:33:46.340] <TB1> INFO: Expecting 514560 events.
[11:33:56.958] <TB1> INFO: 514560 events read in total (10036ms).
[11:33:56.975] <TB1> INFO: Expecting 514560 events.
[11:34:07.591] <TB1> INFO: 514560 events read in total (10039ms).
[11:34:07.607] <TB1> INFO: Expecting 514560 events.
[11:34:18.411] <TB1> INFO: 514560 events read in total (10230ms).
[11:34:18.429] <TB1> INFO: Expecting 514560 events.
[11:34:29.155] <TB1> INFO: 514560 events read in total (10149ms).
[11:34:29.174] <TB1> INFO: Expecting 514560 events.
[11:34:39.780] <TB1> INFO: 514560 events read in total (10032ms).
[11:34:39.800] <TB1> INFO: Expecting 514560 events.
[11:34:50.807] <TB1> INFO: 514560 events read in total (10433ms).
[11:34:50.829] <TB1> INFO: Expecting 514560 events.
[11:35:01.536] <TB1> INFO: 514560 events read in total (10141ms).
[11:35:01.564] <TB1> INFO: Test took 170801ms.
[11:35:02.349] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[11:35:02.357] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:35:02.357] <TB1> INFO: run 1 of 1
[11:35:02.665] <TB1> INFO: Expecting 6281600 events.
[11:35:34.091] <TB1> INFO: 740760 events read in total (30709ms).
[11:36:05.119] <TB1> INFO: 1479430 events read in total (61737ms).
[11:36:35.586] <TB1> INFO: 2217620 events read in total (92205ms).
[11:37:05.921] <TB1> INFO: 2956310 events read in total (122539ms).
[11:37:36.726] <TB1> INFO: 3689950 events read in total (153344ms).
[11:38:06.892] <TB1> INFO: 4420400 events read in total (183510ms).
[11:38:37.147] <TB1> INFO: 5148820 events read in total (213765ms).
[11:39:07.198] <TB1> INFO: 5877150 events read in total (243816ms).
[11:39:24.111] <TB1> INFO: 6281600 events read in total (260729ms).
[11:39:24.171] <TB1> INFO: Test took 261814ms.
[11:39:24.325] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:39:47.626] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.001363 .. 255.000000
[11:39:47.701] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[11:39:47.709] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:39:47.709] <TB1> INFO: run 1 of 1
[11:39:48.014] <TB1> INFO: Expecting 10649600 events.
[11:40:18.868] <TB1> INFO: 712820 events read in total (30137ms).
[11:40:48.783] <TB1> INFO: 1425810 events read in total (60052ms).
[11:41:18.090] <TB1> INFO: 2138560 events read in total (89360ms).
[11:41:48.478] <TB1> INFO: 2851580 events read in total (119747ms).
[11:42:18.559] <TB1> INFO: 3564520 events read in total (149828ms).
[11:42:48.779] <TB1> INFO: 4277470 events read in total (180048ms).
[11:43:19.049] <TB1> INFO: 4990640 events read in total (210318ms).
[11:43:49.125] <TB1> INFO: 5703210 events read in total (240394ms).
[11:44:19.284] <TB1> INFO: 6414960 events read in total (270553ms).
[11:44:49.090] <TB1> INFO: 7126290 events read in total (300359ms).
[11:45:19.037] <TB1> INFO: 7837400 events read in total (330306ms).
[11:45:48.931] <TB1> INFO: 8547630 events read in total (360200ms).
[11:46:19.218] <TB1> INFO: 9258130 events read in total (390487ms).
[11:46:49.372] <TB1> INFO: 9968510 events read in total (420641ms).
[11:47:18.460] <TB1> INFO: 10649600 events read in total (449729ms).
[11:47:18.544] <TB1> INFO: Test took 450835ms.
[11:47:18.804] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:47:49.327] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 12.978092 .. 97.712932
[11:47:49.427] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 2 .. 107 (-1/-1) hits flags = 528 (plus default)
[11:47:49.436] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:47:49.436] <TB1> INFO: run 1 of 1
[11:47:49.782] <TB1> INFO: Expecting 4409600 events.
[11:48:22.067] <TB1> INFO: 785910 events read in total (31569ms).
[11:48:53.594] <TB1> INFO: 1571690 events read in total (63096ms).
[11:49:25.749] <TB1> INFO: 2357210 events read in total (95254ms).
[11:49:57.197] <TB1> INFO: 3142480 events read in total (126699ms).
[11:50:28.638] <TB1> INFO: 3926900 events read in total (158140ms).
[11:50:48.281] <TB1> INFO: 4409600 events read in total (177783ms).
[11:50:48.325] <TB1> INFO: Test took 178889ms.
[11:50:48.412] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:51:07.922] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 15.278979 .. 97.712932
[11:51:07.997] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 5 .. 107 (-1/-1) hits flags = 528 (plus default)
[11:51:08.005] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:51:08.005] <TB1> INFO: run 1 of 1
[11:51:08.309] <TB1> INFO: Expecting 4284800 events.
[11:51:39.924] <TB1> INFO: 777500 events read in total (30898ms).
[11:52:11.241] <TB1> INFO: 1555110 events read in total (62215ms).
[11:52:42.580] <TB1> INFO: 2332710 events read in total (93554ms).
[11:53:13.083] <TB1> INFO: 3109970 events read in total (124057ms).
[11:53:44.371] <TB1> INFO: 3886940 events read in total (155345ms).
[11:54:00.540] <TB1> INFO: 4284800 events read in total (171514ms).
[11:54:00.589] <TB1> INFO: Test took 172584ms.
[11:54:00.686] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:54:20.149] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 1.090803 .. 97.712932
[11:54:20.225] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 1 .. 107 (-1/-1) hits flags = 528 (plus default)
[11:54:20.232] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:54:20.232] <TB1> INFO: run 1 of 1
[11:54:20.537] <TB1> INFO: Expecting 4451200 events.
[11:54:52.611] <TB1> INFO: 789940 events read in total (31357ms).
[11:55:24.410] <TB1> INFO: 1579820 events read in total (63156ms).
[11:55:55.557] <TB1> INFO: 2369600 events read in total (94305ms).
[11:56:27.004] <TB1> INFO: 3159350 events read in total (125750ms).
[11:56:58.649] <TB1> INFO: 3949030 events read in total (157395ms).
[11:57:19.141] <TB1> INFO: 4451200 events read in total (177887ms).
[11:57:19.185] <TB1> INFO: Test took 178953ms.
[11:57:19.275] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:57:40.481] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:57:40.481] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:57:40.489] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[11:57:40.489] <TB1> INFO: run 1 of 1
[11:57:40.796] <TB1> INFO: Expecting 1705600 events.
[11:58:14.899] <TB1> INFO: 880480 events read in total (33387ms).
[11:58:46.411] <TB1> INFO: 1705600 events read in total (64899ms).
[11:58:46.425] <TB1> INFO: Test took 65936ms.
[11:58:46.454] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[11:58:59.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C0.dat
[11:58:59.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C1.dat
[11:58:59.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C2.dat
[11:58:59.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C3.dat
[11:58:59.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C4.dat
[11:58:59.972] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C5.dat
[11:58:59.973] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C6.dat
[11:58:59.973] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C7.dat
[11:58:59.973] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C8.dat
[11:58:59.973] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C9.dat
[11:58:59.973] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C10.dat
[11:58:59.973] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C11.dat
[11:58:59.973] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C12.dat
[11:58:59.974] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C13.dat
[11:58:59.974] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C14.dat
[11:58:59.974] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C15.dat
[11:58:59.974] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//trimParameters35_C0.dat
[11:58:59.984] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//trimParameters35_C1.dat
[11:58:59.994] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//trimParameters35_C2.dat
[11:59:00.004] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//trimParameters35_C3.dat
[11:59:00.014] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//trimParameters35_C4.dat
[11:59:00.028] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//trimParameters35_C5.dat
[11:59:00.042] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//trimParameters35_C6.dat
[11:59:00.054] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//trimParameters35_C7.dat
[11:59:00.069] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//trimParameters35_C8.dat
[11:59:00.083] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//trimParameters35_C9.dat
[11:59:00.096] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//trimParameters35_C10.dat
[11:59:00.109] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//trimParameters35_C11.dat
[11:59:00.119] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//trimParameters35_C12.dat
[11:59:00.129] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//trimParameters35_C13.dat
[11:59:00.138] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//trimParameters35_C14.dat
[11:59:00.148] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//trimParameters35_C15.dat
[11:59:00.158] <TB1> INFO: PixTestTrim::trimTest() done
[11:59:00.158] <TB1> INFO: vtrim: 65 72 58 51 54 43 0 55 72 54 60 58 63 76 85 70
[11:59:00.158] <TB1> INFO: vthrcomp: 71 69 57 56 63 53 1 54 75 68 66 71 70 62 74 75
[11:59:00.158] <TB1> INFO: vcal mean: 34.94 34.99 34.95 34.81 34.91 35.06 0.00 34.98 35.02 34.98 35.00 34.97 34.95 35.03 34.95 34.98
[11:59:00.158] <TB1> INFO: vcal RMS: 1.02 0.89 0.91 2.10 1.09 1.14 0.00 1.20 0.78 0.79 0.84 0.98 0.87 3.52 0.83 0.96
[11:59:00.158] <TB1> INFO: bits mean: 10.54 10.14 9.67 9.60 10.57 9.72 4.38 10.25 8.52 10.35 10.64 10.17 10.72 10.34 9.36 8.76
[11:59:00.158] <TB1> INFO: bits RMS: 2.30 2.45 2.29 2.14 2.20 2.34 2.22 2.36 2.59 2.44 2.35 2.33 2.36 2.41 2.40 2.61
[11:59:00.167] <TB1> INFO: ----------------------------------------------------------------------
[11:59:00.167] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[11:59:00.167] <TB1> INFO: ----------------------------------------------------------------------
[11:59:00.172] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[11:59:00.181] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:59:00.181] <TB1> INFO: run 1 of 1
[11:59:00.528] <TB1> INFO: Expecting 4160000 events.
[11:59:38.823] <TB1> INFO: 832080 events read in total (37578ms).
[12:00:16.405] <TB1> INFO: 1660185 events read in total (75160ms).
[12:00:53.773] <TB1> INFO: 2484790 events read in total (112528ms).
[12:01:30.748] <TB1> INFO: 3304780 events read in total (149503ms).
[12:02:07.421] <TB1> INFO: 4125580 events read in total (186176ms).
[12:02:09.455] <TB1> INFO: 4160000 events read in total (188210ms).
[12:02:09.495] <TB1> INFO: Test took 189314ms.
[12:02:09.650] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:02:39.656] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[12:02:39.663] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[12:02:39.663] <TB1> INFO: run 1 of 1
[12:02:39.971] <TB1> INFO: Expecting 5324800 events.
[12:03:17.151] <TB1> INFO: 770845 events read in total (36463ms).
[12:03:53.095] <TB1> INFO: 1539205 events read in total (72407ms).
[12:04:28.739] <TB1> INFO: 2306650 events read in total (108051ms).
[12:05:04.373] <TB1> INFO: 3072170 events read in total (143685ms).
[12:05:40.151] <TB1> INFO: 3835310 events read in total (179463ms).
[12:06:15.337] <TB1> INFO: 4597770 events read in total (214649ms).
[12:06:49.585] <TB1> INFO: 5324800 events read in total (248897ms).
[12:06:49.658] <TB1> INFO: Test took 249995ms.
[12:06:49.893] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:07:22.740] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[12:07:22.750] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[12:07:22.750] <TB1> INFO: run 1 of 1
[12:07:23.099] <TB1> INFO: Expecting 5324800 events.
[12:07:59.299] <TB1> INFO: 769255 events read in total (35483ms).
[12:08:34.770] <TB1> INFO: 1536235 events read in total (70954ms).
[12:09:10.301] <TB1> INFO: 2302285 events read in total (106485ms).
[12:09:45.654] <TB1> INFO: 3066275 events read in total (141838ms).
[12:10:21.228] <TB1> INFO: 3827900 events read in total (177412ms).
[12:10:57.102] <TB1> INFO: 4588810 events read in total (213286ms).
[12:11:31.934] <TB1> INFO: 5324800 events read in total (248118ms).
[12:11:32.019] <TB1> INFO: Test took 249270ms.
[12:11:32.239] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:12:03.568] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[12:12:03.576] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[12:12:03.576] <TB1> INFO: run 1 of 1
[12:12:03.879] <TB1> INFO: Expecting 5324800 events.
[12:12:39.982] <TB1> INFO: 767410 events read in total (35386ms).
[12:13:16.006] <TB1> INFO: 1532135 events read in total (71410ms).
[12:13:52.433] <TB1> INFO: 2296220 events read in total (107837ms).
[12:14:27.787] <TB1> INFO: 3058190 events read in total (143191ms).
[12:15:03.141] <TB1> INFO: 3817850 events read in total (178545ms).
[12:15:38.801] <TB1> INFO: 4576790 events read in total (214205ms).
[12:16:12.736] <TB1> INFO: 5324800 events read in total (248140ms).
[12:16:12.813] <TB1> INFO: Test took 249237ms.
[12:16:13.011] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:16:43.388] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[12:16:43.395] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[12:16:43.395] <TB1> INFO: run 1 of 1
[12:16:43.700] <TB1> INFO: Expecting 5324800 events.
[12:17:20.250] <TB1> INFO: 765320 events read in total (35833ms).
[12:17:56.052] <TB1> INFO: 1528035 events read in total (71635ms).
[12:18:31.910] <TB1> INFO: 2289930 events read in total (107493ms).
[12:19:07.597] <TB1> INFO: 3049765 events read in total (143180ms).
[12:19:43.183] <TB1> INFO: 3807435 events read in total (178766ms).
[12:20:19.139] <TB1> INFO: 4564170 events read in total (214722ms).
[12:20:55.295] <TB1> INFO: 5322730 events read in total (250878ms).
[12:20:55.867] <TB1> INFO: 5324800 events read in total (251450ms).
[12:20:55.942] <TB1> INFO: Test took 252547ms.
[12:20:56.145] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:25.176] <TB1> INFO: PixTestTrim::trimBitTest() done
[12:21:25.178] <TB1> INFO: PixTestTrim::doTest() done, duration: 3460 seconds
[12:21:25.178] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:25.178] <TB1> INFO: Decoding statistics:
[12:21:25.178] <TB1> INFO: General information:
[12:21:25.178] <TB1> INFO: 16bit words read: 0
[12:21:25.178] <TB1> INFO: valid events total: 0
[12:21:25.178] <TB1> INFO: empty events: 0
[12:21:25.178] <TB1> INFO: valid events with pixels: 0
[12:21:25.178] <TB1> INFO: valid pixel hits: 0
[12:21:25.178] <TB1> INFO: Event errors: 0
[12:21:25.178] <TB1> INFO: start marker: 0
[12:21:25.178] <TB1> INFO: stop marker: 0
[12:21:25.178] <TB1> INFO: overflow: 0
[12:21:25.178] <TB1> INFO: invalid 5bit words: 0
[12:21:25.178] <TB1> INFO: invalid XOR eye diagram: 0
[12:21:25.178] <TB1> INFO: TBM errors: 0
[12:21:25.178] <TB1> INFO: flawed TBM headers: 0
[12:21:25.178] <TB1> INFO: flawed TBM trailers: 0
[12:21:25.178] <TB1> INFO: event ID mismatches: 0
[12:21:25.178] <TB1> INFO: ROC errors: 0
[12:21:25.178] <TB1> INFO: missing ROC header(s): 0
[12:21:25.178] <TB1> INFO: misplaced readback start: 0
[12:21:25.178] <TB1> INFO: Pixel decoding errors: 0
[12:21:25.178] <TB1> INFO: pixel data incomplete: 0
[12:21:25.178] <TB1> INFO: pixel address: 0
[12:21:25.178] <TB1> INFO: pulse height fill bit: 0
[12:21:25.178] <TB1> INFO: buffer corruption: 0
[12:21:25.853] <TB1> INFO: ######################################################################
[12:21:25.853] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[12:21:25.853] <TB1> INFO: ######################################################################
[12:21:26.168] <TB1> INFO: Expecting 41600 events.
[12:21:30.429] <TB1> INFO: 41600 events read in total (3544ms).
[12:21:30.430] <TB1> INFO: Test took 4575ms.
[12:21:30.435] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:31.007] <TB1> INFO: Expecting 41600 events.
[12:21:35.231] <TB1> INFO: 41600 events read in total (3508ms).
[12:21:35.231] <TB1> INFO: Test took 4533ms.
[12:21:35.237] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:35.592] <TB1> INFO: Expecting 41600 events.
[12:21:39.844] <TB1> INFO: 41600 events read in total (3535ms).
[12:21:39.845] <TB1> INFO: Test took 4577ms.
[12:21:39.850] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:21:40.206] <TB1> INFO: Expecting 2560 events.
[12:21:41.277] <TB1> INFO: 2560 events read in total (355ms).
[12:21:41.278] <TB1> INFO: Test took 1416ms.
[12:21:41.693] <TB1> INFO: Expecting 2560 events.
[12:21:42.757] <TB1> INFO: 2560 events read in total (348ms).
[12:21:42.758] <TB1> INFO: Test took 1480ms.
[12:21:43.173] <TB1> INFO: Expecting 2560 events.
[12:21:44.237] <TB1> INFO: 2560 events read in total (348ms).
[12:21:44.238] <TB1> INFO: Test took 1480ms.
[12:21:44.653] <TB1> INFO: Expecting 2560 events.
[12:21:45.723] <TB1> INFO: 2560 events read in total (353ms).
[12:21:45.723] <TB1> INFO: Test took 1484ms.
[12:21:46.138] <TB1> INFO: Expecting 2560 events.
[12:21:47.205] <TB1> INFO: 2560 events read in total (350ms).
[12:21:47.205] <TB1> INFO: Test took 1481ms.
[12:21:47.621] <TB1> INFO: Expecting 2560 events.
[12:21:48.689] <TB1> INFO: 2560 events read in total (352ms).
[12:21:48.689] <TB1> INFO: Test took 1483ms.
[12:21:49.104] <TB1> INFO: Expecting 2560 events.
[12:21:50.169] <TB1> INFO: 2560 events read in total (349ms).
[12:21:50.170] <TB1> INFO: Test took 1481ms.
[12:21:50.586] <TB1> INFO: Expecting 2560 events.
[12:21:51.654] <TB1> INFO: 2560 events read in total (352ms).
[12:21:51.654] <TB1> INFO: Test took 1484ms.
[12:21:52.069] <TB1> INFO: Expecting 2560 events.
[12:21:53.140] <TB1> INFO: 2560 events read in total (354ms).
[12:21:53.140] <TB1> INFO: Test took 1485ms.
[12:21:53.555] <TB1> INFO: Expecting 2560 events.
[12:21:54.627] <TB1> INFO: 2560 events read in total (355ms).
[12:21:54.627] <TB1> INFO: Test took 1486ms.
[12:21:55.042] <TB1> INFO: Expecting 2560 events.
[12:21:56.106] <TB1> INFO: 2560 events read in total (348ms).
[12:21:56.107] <TB1> INFO: Test took 1479ms.
[12:21:56.522] <TB1> INFO: Expecting 2560 events.
[12:21:57.592] <TB1> INFO: 2560 events read in total (354ms).
[12:21:57.592] <TB1> INFO: Test took 1485ms.
[12:21:58.008] <TB1> INFO: Expecting 2560 events.
[12:21:59.071] <TB1> INFO: 2560 events read in total (347ms).
[12:21:59.072] <TB1> INFO: Test took 1479ms.
[12:21:59.487] <TB1> INFO: Expecting 2560 events.
[12:22:00.551] <TB1> INFO: 2560 events read in total (348ms).
[12:22:00.551] <TB1> INFO: Test took 1479ms.
[12:22:00.966] <TB1> INFO: Expecting 2560 events.
[12:22:02.031] <TB1> INFO: 2560 events read in total (349ms).
[12:22:02.031] <TB1> INFO: Test took 1479ms.
[12:22:02.446] <TB1> INFO: Expecting 2560 events.
[12:22:03.512] <TB1> INFO: 2560 events read in total (350ms).
[12:22:03.512] <TB1> INFO: Test took 1480ms.
[12:22:03.517] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:22:03.929] <TB1> INFO: Expecting 655360 events.
[12:22:18.513] <TB1> INFO: 655360 events read in total (13862ms).
[12:22:18.522] <TB1> INFO: Expecting 655360 events.
[12:22:32.685] <TB1> INFO: 655360 events read in total (13634ms).
[12:22:32.697] <TB1> INFO: Expecting 655360 events.
[12:22:47.072] <TB1> INFO: 655360 events read in total (13846ms).
[12:22:47.090] <TB1> INFO: Expecting 655360 events.
[12:23:01.108] <TB1> INFO: 655360 events read in total (13490ms).
[12:23:01.127] <TB1> INFO: Expecting 655360 events.
[12:23:15.620] <TB1> INFO: 655360 events read in total (13965ms).
[12:23:15.644] <TB1> INFO: Expecting 655360 events.
[12:23:29.998] <TB1> INFO: 655360 events read in total (13826ms).
[12:23:30.028] <TB1> INFO: Expecting 655360 events.
[12:23:43.258] <TB1> INFO: 655360 events read in total (12702ms).
[12:23:43.279] <TB1> INFO: Expecting 655360 events.
[12:23:57.632] <TB1> INFO: 655360 events read in total (13765ms).
[12:23:57.662] <TB1> INFO: Expecting 655360 events.
[12:24:11.901] <TB1> INFO: 655360 events read in total (13711ms).
[12:24:11.935] <TB1> INFO: Expecting 655360 events.
[12:24:26.275] <TB1> INFO: 655360 events read in total (13811ms).
[12:24:26.318] <TB1> INFO: Expecting 655360 events.
[12:24:40.526] <TB1> INFO: 655360 events read in total (13680ms).
[12:24:40.571] <TB1> INFO: Expecting 655360 events.
[12:24:54.847] <TB1> INFO: 655360 events read in total (13748ms).
[12:24:54.890] <TB1> INFO: Expecting 655360 events.
[12:25:09.146] <TB1> INFO: 655360 events read in total (13728ms).
[12:25:09.202] <TB1> INFO: Expecting 655360 events.
[12:25:23.589] <TB1> INFO: 655360 events read in total (13859ms).
[12:25:23.640] <TB1> INFO: Expecting 655360 events.
[12:25:37.855] <TB1> INFO: 655360 events read in total (13687ms).
[12:25:37.911] <TB1> INFO: Expecting 655360 events.
[12:25:52.105] <TB1> INFO: 655360 events read in total (13665ms).
[12:25:52.168] <TB1> INFO: Test took 228652ms.
[12:25:52.252] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:25:52.558] <TB1> INFO: Expecting 655360 events.
[12:26:06.869] <TB1> INFO: 655360 events read in total (13594ms).
[12:26:06.878] <TB1> INFO: Expecting 655360 events.
[12:26:21.301] <TB1> INFO: 655360 events read in total (13895ms).
[12:26:21.314] <TB1> INFO: Expecting 655360 events.
[12:26:35.424] <TB1> INFO: 655360 events read in total (13582ms).
[12:26:35.440] <TB1> INFO: Expecting 655360 events.
[12:26:49.724] <TB1> INFO: 655360 events read in total (13756ms).
[12:26:49.743] <TB1> INFO: Expecting 655360 events.
[12:27:03.732] <TB1> INFO: 655360 events read in total (13460ms).
[12:27:03.758] <TB1> INFO: Expecting 655360 events.
[12:27:17.966] <TB1> INFO: 655360 events read in total (13680ms).
[12:27:17.993] <TB1> INFO: Expecting 655360 events.
[12:27:31.985] <TB1> INFO: 655360 events read in total (13465ms).
[12:27:32.016] <TB1> INFO: Expecting 655360 events.
[12:27:46.402] <TB1> INFO: 655360 events read in total (13857ms).
[12:27:46.436] <TB1> INFO: Expecting 655360 events.
[12:28:00.622] <TB1> INFO: 655360 events read in total (13658ms).
[12:28:00.664] <TB1> INFO: Expecting 655360 events.
[12:28:15.183] <TB1> INFO: 655360 events read in total (13991ms).
[12:28:15.227] <TB1> INFO: Expecting 655360 events.
[12:28:29.468] <TB1> INFO: 655360 events read in total (13713ms).
[12:28:29.512] <TB1> INFO: Expecting 655360 events.
[12:28:44.158] <TB1> INFO: 655360 events read in total (14118ms).
[12:28:44.204] <TB1> INFO: Expecting 655360 events.
[12:28:58.352] <TB1> INFO: 655360 events read in total (13620ms).
[12:28:58.418] <TB1> INFO: Expecting 655360 events.
[12:29:12.692] <TB1> INFO: 655360 events read in total (13746ms).
[12:29:12.762] <TB1> INFO: Expecting 655360 events.
[12:29:26.993] <TB1> INFO: 655360 events read in total (13703ms).
[12:29:27.057] <TB1> INFO: Expecting 655360 events.
[12:29:41.482] <TB1> INFO: 655360 events read in total (13897ms).
[12:29:41.545] <TB1> INFO: Test took 229293ms.
[12:29:41.746] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[12:29:41.753] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[12:29:41.759] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[12:29:41.766] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[12:29:41.773] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[12:29:41.779] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[12:29:41.786] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[12:29:41.792] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[12:29:41.799] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[12:29:41.806] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[12:29:41.812] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[12:29:41.819] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[12:29:41.826] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[12:29:41.833] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[12:29:41.839] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[12:29:41.846] <TB1> INFO: safety margin for low PH: adding 8, margin is now 28
[12:29:41.853] <TB1> INFO: safety margin for low PH: adding 9, margin is now 29
[12:29:41.860] <TB1> INFO: safety margin for low PH: adding 10, margin is now 30
[12:29:41.869] <TB1> INFO: safety margin for low PH: adding 11, margin is now 31
[12:29:41.878] <TB1> INFO: safety margin for low PH: adding 12, margin is now 32
[12:29:41.885] <TB1> INFO: safety margin for low PH: adding 13, margin is now 33
[12:29:41.891] <TB1> INFO: safety margin for low PH: adding 14, margin is now 34
[12:29:41.900] <TB1> INFO: safety margin for low PH: adding 15, margin is now 35
[12:29:41.907] <TB1> INFO: safety margin for low PH: adding 16, margin is now 36
[12:29:41.914] <TB1> INFO: safety margin for low PH: adding 17, margin is now 37
[12:29:41.921] <TB1> INFO: safety margin for low PH: adding 18, margin is now 38
[12:29:41.927] <TB1> INFO: safety margin for low PH: adding 19, margin is now 39
[12:29:41.934] <TB1> INFO: safety margin for low PH: adding 20, margin is now 40
[12:29:41.941] <TB1> INFO: safety margin for low PH: adding 21, margin is now 41
[12:29:41.948] <TB1> INFO: safety margin for low PH: adding 22, margin is now 42
[12:29:41.960] <TB1> INFO: safety margin for low PH: adding 23, margin is now 43
[12:29:41.967] <TB1> INFO: safety margin for low PH: adding 24, margin is now 44
[12:29:41.974] <TB1> INFO: safety margin for low PH: adding 25, margin is now 45
[12:29:41.981] <TB1> INFO: safety margin for low PH: adding 26, margin is now 46
[12:29:41.988] <TB1> INFO: safety margin for low PH: adding 27, margin is now 47
[12:29:41.995] <TB1> INFO: safety margin for low PH: adding 28, margin is now 48
[12:29:42.001] <TB1> INFO: safety margin for low PH: adding 29, margin is now 49
[12:29:42.009] <TB1> INFO: safety margin for low PH: adding 30, margin is now 50
[12:29:42.016] <TB1> INFO: safety margin for low PH: adding 31, margin is now 51
[12:29:42.022] <TB1> INFO: safety margin for low PH: adding 32, margin is now 52
[12:29:42.029] <TB1> INFO: safety margin for low PH: adding 33, margin is now 53
[12:29:42.036] <TB1> INFO: safety margin for low PH: adding 34, margin is now 54
[12:29:42.043] <TB1> INFO: safety margin for low PH: adding 35, margin is now 55
[12:29:42.049] <TB1> INFO: safety margin for low PH: adding 36, margin is now 56
[12:29:42.056] <TB1> INFO: safety margin for low PH: adding 37, margin is now 57
[12:29:42.063] <TB1> INFO: safety margin for low PH: adding 38, margin is now 58
[12:29:42.070] <TB1> INFO: safety margin for low PH: adding 39, margin is now 59
[12:29:42.076] <TB1> INFO: safety margin for low PH: adding 40, margin is now 60
[12:29:42.083] <TB1> INFO: safety margin for low PH: adding 41, margin is now 61
[12:29:42.090] <TB1> INFO: safety margin for low PH: adding 42, margin is now 62
[12:29:42.097] <TB1> INFO: safety margin for low PH: adding 43, margin is now 63
[12:29:42.104] <TB1> INFO: safety margin for low PH: adding 44, margin is now 64
[12:29:42.111] <TB1> INFO: safety margin for low PH: adding 45, margin is now 65
[12:29:42.118] <TB1> INFO: safety margin for low PH: adding 46, margin is now 66
[12:29:42.125] <TB1> INFO: safety margin for low PH: adding 47, margin is now 67
[12:29:42.133] <TB1> INFO: safety margin for low PH: adding 48, margin is now 68
[12:29:42.140] <TB1> INFO: safety margin for low PH: adding 49, margin is now 69
[12:29:42.149] <TB1> INFO: safety margin for low PH: adding 50, margin is now 70
[12:29:42.157] <TB1> INFO: safety margin for low PH: adding 51, margin is now 71
[12:29:42.173] <TB1> INFO: safety margin for low PH: adding 52, margin is now 72
[12:29:42.182] <TB1> INFO: safety margin for low PH: adding 53, margin is now 73
[12:29:42.191] <TB1> INFO: safety margin for low PH: adding 54, margin is now 74
[12:29:42.200] <TB1> INFO: safety margin for low PH: adding 55, margin is now 75
[12:29:42.208] <TB1> INFO: safety margin for low PH: adding 56, margin is now 76
[12:29:42.216] <TB1> INFO: safety margin for low PH: adding 57, margin is now 77
[12:29:42.224] <TB1> INFO: safety margin for low PH: adding 58, margin is now 78
[12:29:42.233] <TB1> INFO: safety margin for low PH: adding 59, margin is now 79
[12:29:42.243] <TB1> INFO: safety margin for low PH: adding 60, margin is now 80
[12:29:42.251] <TB1> INFO: safety margin for low PH: adding 61, margin is now 81
[12:29:42.260] <TB1> INFO: safety margin for low PH: adding 62, margin is now 82
[12:29:42.269] <TB1> INFO: safety margin for low PH: adding 63, margin is now 83
[12:29:42.278] <TB1> INFO: safety margin for low PH: adding 64, margin is now 84
[12:29:42.286] <TB1> INFO: safety margin for low PH: adding 65, margin is now 85
[12:29:42.295] <TB1> INFO: safety margin for low PH: adding 66, margin is now 86
[12:29:42.304] <TB1> INFO: safety margin for low PH: adding 67, margin is now 87
[12:29:42.312] <TB1> INFO: safety margin for low PH: adding 68, margin is now 88
[12:29:42.320] <TB1> INFO: safety margin for low PH: adding 69, margin is now 89
[12:29:42.327] <TB1> INFO: safety margin for low PH: adding 70, margin is now 90
[12:29:42.334] <TB1> INFO: safety margin for low PH: adding 71, margin is now 91
[12:29:42.341] <TB1> INFO: safety margin for low PH: adding 72, margin is now 92
[12:29:42.348] <TB1> INFO: safety margin for low PH: adding 73, margin is now 93
[12:29:42.354] <TB1> INFO: safety margin for low PH: adding 74, margin is now 94
[12:29:42.361] <TB1> INFO: safety margin for low PH: adding 75, margin is now 95
[12:29:42.368] <TB1> INFO: safety margin for low PH: adding 76, margin is now 96
[12:29:42.374] <TB1> INFO: safety margin for low PH: adding 77, margin is now 97
[12:29:42.381] <TB1> INFO: safety margin for low PH: adding 78, margin is now 98
[12:29:42.388] <TB1> INFO: safety margin for low PH: adding 79, margin is now 99
[12:29:42.394] <TB1> INFO: safety margin for low PH: adding 80, margin is now 100
[12:29:42.401] <TB1> INFO: safety margin for low PH: adding 81, margin is now 101
[12:29:42.408] <TB1> INFO: safety margin for low PH: adding 82, margin is now 102
[12:29:42.415] <TB1> INFO: safety margin for low PH: adding 83, margin is now 103
[12:29:42.421] <TB1> INFO: safety margin for low PH: adding 84, margin is now 104
[12:29:42.428] <TB1> INFO: safety margin for low PH: adding 85, margin is now 105
[12:29:42.435] <TB1> INFO: safety margin for low PH: adding 86, margin is now 106
[12:29:42.441] <TB1> INFO: safety margin for low PH: adding 87, margin is now 107
[12:29:42.448] <TB1> INFO: safety margin for low PH: adding 88, margin is now 108
[12:29:42.455] <TB1> INFO: safety margin for low PH: adding 89, margin is now 109
[12:29:42.461] <TB1> INFO: safety margin for low PH: adding 90, margin is now 110
[12:29:42.468] <TB1> INFO: safety margin for low PH: adding 91, margin is now 111
[12:29:42.475] <TB1> INFO: safety margin for low PH: adding 92, margin is now 112
[12:29:42.482] <TB1> INFO: safety margin for low PH: adding 93, margin is now 113
[12:29:42.488] <TB1> INFO: safety margin for low PH: adding 94, margin is now 114
[12:29:42.495] <TB1> INFO: safety margin for low PH: adding 95, margin is now 115
[12:29:42.502] <TB1> INFO: safety margin for low PH: adding 96, margin is now 116
[12:29:42.509] <TB1> INFO: safety margin for low PH: adding 97, margin is now 117
[12:29:42.515] <TB1> INFO: safety margin for low PH: adding 98, margin is now 118
[12:29:42.522] <TB1> INFO: safety margin for low PH: adding 99, margin is now 119
[12:29:42.529] <TB1> INFO: safety margin for low PH: adding 100, margin is now 120
[12:29:42.535] <TB1> INFO: safety margin for low PH: adding 101, margin is now 121
[12:29:42.542] <TB1> INFO: safety margin for low PH: adding 102, margin is now 122
[12:29:42.549] <TB1> INFO: safety margin for low PH: adding 103, margin is now 123
[12:29:42.556] <TB1> INFO: safety margin for low PH: adding 104, margin is now 124
[12:29:42.562] <TB1> INFO: safety margin for low PH: adding 105, margin is now 125
[12:29:42.571] <TB1> INFO: safety margin for low PH: adding 106, margin is now 126
[12:29:42.578] <TB1> INFO: safety margin for low PH: adding 107, margin is now 127
[12:29:42.584] <TB1> INFO: safety margin for low PH: adding 108, margin is now 128
[12:29:42.591] <TB1> INFO: safety margin for low PH: adding 109, margin is now 129
[12:29:42.598] <TB1> INFO: safety margin for low PH: adding 110, margin is now 130
[12:29:42.604] <TB1> INFO: safety margin for low PH: adding 111, margin is now 131
[12:29:42.611] <TB1> INFO: safety margin for low PH: adding 112, margin is now 132
[12:29:42.617] <TB1> INFO: safety margin for low PH: adding 113, margin is now 133
[12:29:42.624] <TB1> INFO: safety margin for low PH: adding 114, margin is now 134
[12:29:42.631] <TB1> INFO: safety margin for low PH: adding 115, margin is now 135
[12:29:42.637] <TB1> INFO: safety margin for low PH: adding 116, margin is now 136
[12:29:42.644] <TB1> INFO: safety margin for low PH: adding 117, margin is now 137
[12:29:42.650] <TB1> INFO: safety margin for low PH: adding 118, margin is now 138
[12:29:42.657] <TB1> INFO: safety margin for low PH: adding 119, margin is now 139
[12:29:42.664] <TB1> INFO: safety margin for low PH: adding 120, margin is now 140
[12:29:42.670] <TB1> INFO: safety margin for low PH: adding 121, margin is now 141
[12:29:42.677] <TB1> INFO: safety margin for low PH: adding 122, margin is now 142
[12:29:42.683] <TB1> INFO: safety margin for low PH: adding 123, margin is now 143
[12:29:42.690] <TB1> INFO: safety margin for low PH: adding 124, margin is now 144
[12:29:42.697] <TB1> INFO: safety margin for low PH: adding 125, margin is now 145
[12:29:42.703] <TB1> INFO: safety margin for low PH: adding 126, margin is now 146
[12:29:42.710] <TB1> INFO: safety margin for low PH: adding 127, margin is now 147
[12:29:42.716] <TB1> INFO: safety margin for low PH: adding 128, margin is now 148
[12:29:42.723] <TB1> INFO: safety margin for low PH: adding 129, margin is now 149
[12:29:42.730] <TB1> INFO: safety margin for low PH: adding 130, margin is now 150
[12:29:42.736] <TB1> INFO: safety margin for low PH: adding 131, margin is now 151
[12:29:42.743] <TB1> INFO: safety margin for low PH: adding 132, margin is now 152
[12:29:42.749] <TB1> INFO: safety margin for low PH: adding 133, margin is now 153
[12:29:42.756] <TB1> INFO: safety margin for low PH: adding 134, margin is now 154
[12:29:42.763] <TB1> INFO: safety margin for low PH: adding 135, margin is now 155
[12:29:42.769] <TB1> INFO: safety margin for low PH: adding 136, margin is now 156
[12:29:42.776] <TB1> INFO: safety margin for low PH: adding 137, margin is now 157
[12:29:42.783] <TB1> INFO: safety margin for low PH: adding 138, margin is now 158
[12:29:42.789] <TB1> INFO: safety margin for low PH: adding 139, margin is now 159
[12:29:42.796] <TB1> INFO: safety margin for low PH: adding 140, margin is now 160
[12:29:42.803] <TB1> INFO: safety margin for low PH: adding 141, margin is now 161
[12:29:42.810] <TB1> INFO: safety margin for low PH: adding 142, margin is now 162
[12:29:42.817] <TB1> INFO: safety margin for low PH: adding 143, margin is now 163
[12:29:42.823] <TB1> INFO: safety margin for low PH: adding 144, margin is now 164
[12:29:42.830] <TB1> INFO: safety margin for low PH: adding 145, margin is now 165
[12:29:42.837] <TB1> INFO: safety margin for low PH: adding 146, margin is now 166
[12:29:42.844] <TB1> INFO: safety margin for low PH: adding 147, margin is now 167
[12:29:42.850] <TB1> INFO: safety margin for low PH: adding 148, margin is now 168
[12:29:42.857] <TB1> INFO: safety margin for low PH: adding 149, margin is now 169
[12:29:42.864] <TB1> INFO: safety margin for low PH: adding 150, margin is now 170
[12:29:42.870] <TB1> INFO: safety margin for low PH: adding 151, margin is now 171
[12:29:42.877] <TB1> INFO: safety margin for low PH: adding 152, margin is now 172
[12:29:42.884] <TB1> INFO: safety margin for low PH: adding 153, margin is now 173
[12:29:42.890] <TB1> INFO: safety margin for low PH: adding 154, margin is now 174
[12:29:42.897] <TB1> INFO: safety margin for low PH: adding 155, margin is now 175
[12:29:42.904] <TB1> INFO: safety margin for low PH: adding 156, margin is now 176
[12:29:42.911] <TB1> INFO: safety margin for low PH: adding 157, margin is now 177
[12:29:42.917] <TB1> INFO: safety margin for low PH: adding 158, margin is now 178
[12:29:42.924] <TB1> INFO: safety margin for low PH: adding 159, margin is now 179
[12:29:42.931] <TB1> INFO: safety margin for low PH: adding 160, margin is now 180
[12:29:42.937] <TB1> INFO: safety margin for low PH: adding 161, margin is now 181
[12:29:42.944] <TB1> INFO: safety margin for low PH: adding 162, margin is now 182
[12:29:42.951] <TB1> INFO: safety margin for low PH: adding 163, margin is now 183
[12:29:42.957] <TB1> INFO: safety margin for low PH: adding 164, margin is now 184
[12:29:42.964] <TB1> INFO: safety margin for low PH: adding 165, margin is now 185
[12:29:42.971] <TB1> INFO: safety margin for low PH: adding 166, margin is now 186
[12:29:42.978] <TB1> INFO: safety margin for low PH: adding 167, margin is now 187
[12:29:42.984] <TB1> INFO: safety margin for low PH: adding 168, margin is now 188
[12:29:42.991] <TB1> INFO: safety margin for low PH: adding 169, margin is now 189
[12:29:42.998] <TB1> INFO: safety margin for low PH: adding 170, margin is now 190
[12:29:43.004] <TB1> INFO: safety margin for low PH: adding 171, margin is now 191
[12:29:43.011] <TB1> INFO: safety margin for low PH: adding 172, margin is now 192
[12:29:43.018] <TB1> INFO: safety margin for low PH: adding 173, margin is now 193
[12:29:43.025] <TB1> INFO: safety margin for low PH: adding 174, margin is now 194
[12:29:43.031] <TB1> INFO: safety margin for low PH: adding 175, margin is now 195
[12:29:43.038] <TB1> INFO: safety margin for low PH: adding 176, margin is now 196
[12:29:43.045] <TB1> INFO: safety margin for low PH: adding 177, margin is now 197
[12:29:43.051] <TB1> INFO: safety margin for low PH: adding 178, margin is now 198
[12:29:43.058] <TB1> INFO: safety margin for low PH: adding 179, margin is now 199
[12:29:43.065] <TB1> INFO: safety margin for low PH: adding 180, margin is now 200
[12:29:43.071] <TB1> INFO: safety margin for low PH: adding 181, margin is now 201
[12:29:43.080] <TB1> INFO: safety margin for low PH: adding 182, margin is now 202
[12:29:43.089] <TB1> INFO: safety margin for low PH: adding 183, margin is now 203
[12:29:43.098] <TB1> INFO: safety margin for low PH: adding 184, margin is now 204
[12:29:43.107] <TB1> INFO: safety margin for low PH: adding 185, margin is now 205
[12:29:43.116] <TB1> INFO: safety margin for low PH: adding 186, margin is now 206
[12:29:43.126] <TB1> INFO: safety margin for low PH: adding 187, margin is now 207
[12:29:43.135] <TB1> INFO: safety margin for low PH: adding 188, margin is now 208
[12:29:43.144] <TB1> INFO: safety margin for low PH: adding 189, margin is now 209
[12:29:43.153] <TB1> INFO: safety margin for low PH: adding 190, margin is now 210
[12:29:43.162] <TB1> INFO: safety margin for low PH: adding 191, margin is now 211
[12:29:43.170] <TB1> INFO: safety margin for low PH: adding 192, margin is now 212
[12:29:43.179] <TB1> INFO: safety margin for low PH: adding 193, margin is now 213
[12:29:43.187] <TB1> INFO: safety margin for low PH: adding 194, margin is now 214
[12:29:43.196] <TB1> INFO: safety margin for low PH: adding 195, margin is now 215
[12:29:43.205] <TB1> INFO: safety margin for low PH: adding 196, margin is now 216
[12:29:43.214] <TB1> INFO: safety margin for low PH: adding 197, margin is now 217
[12:29:43.223] <TB1> INFO: safety margin for low PH: adding 198, margin is now 218
[12:29:43.230] <TB1> INFO: safety margin for low PH: adding 199, margin is now 219
[12:29:43.239] <TB1> INFO: safety margin for low PH: adding 200, margin is now 220
[12:29:43.249] <TB1> INFO: safety margin for low PH: adding 201, margin is now 221
[12:29:43.259] <TB1> INFO: safety margin for low PH: adding 202, margin is now 222
[12:29:43.268] <TB1> INFO: safety margin for low PH: adding 203, margin is now 223
[12:29:43.275] <TB1> INFO: safety margin for low PH: adding 204, margin is now 224
[12:29:43.283] <TB1> INFO: safety margin for low PH: adding 205, margin is now 225
[12:29:43.293] <TB1> INFO: safety margin for low PH: adding 206, margin is now 226
[12:29:43.301] <TB1> INFO: safety margin for low PH: adding 207, margin is now 227
[12:29:43.309] <TB1> INFO: safety margin for low PH: adding 208, margin is now 228
[12:29:43.318] <TB1> INFO: safety margin for low PH: adding 209, margin is now 229
[12:29:43.325] <TB1> INFO: safety margin for low PH: adding 210, margin is now 230
[12:29:43.332] <TB1> INFO: safety margin for low PH: adding 211, margin is now 231
[12:29:43.339] <TB1> INFO: safety margin for low PH: adding 212, margin is now 232
[12:29:43.346] <TB1> INFO: safety margin for low PH: adding 213, margin is now 233
[12:29:43.352] <TB1> INFO: safety margin for low PH: adding 214, margin is now 234
[12:29:43.360] <TB1> INFO: safety margin for low PH: adding 215, margin is now 235
[12:29:43.368] <TB1> INFO: safety margin for low PH: adding 216, margin is now 236
[12:29:43.375] <TB1> INFO: safety margin for low PH: adding 217, margin is now 237
[12:29:43.382] <TB1> INFO: safety margin for low PH: adding 218, margin is now 238
[12:29:43.389] <TB1> INFO: safety margin for low PH: adding 219, margin is now 239
[12:29:43.396] <TB1> INFO: safety margin for low PH: adding 220, margin is now 240
[12:29:43.403] <TB1> INFO: safety margin for low PH: adding 221, margin is now 241
[12:29:43.410] <TB1> INFO: safety margin for low PH: adding 222, margin is now 242
[12:29:43.416] <TB1> INFO: safety margin for low PH: adding 223, margin is now 243
[12:29:43.423] <TB1> INFO: safety margin for low PH: adding 224, margin is now 244
[12:29:43.430] <TB1> INFO: safety margin for low PH: adding 225, margin is now 245
[12:29:43.437] <TB1> INFO: safety margin for low PH: adding 226, margin is now 246
[12:29:43.444] <TB1> INFO: safety margin for low PH: adding 227, margin is now 247
[12:29:43.451] <TB1> INFO: safety margin for low PH: adding 228, margin is now 248
[12:29:43.458] <TB1> INFO: safety margin for low PH: adding 229, margin is now 249
[12:29:43.465] <TB1> INFO: safety margin for low PH: adding 230, margin is now 250
[12:29:43.472] <TB1> INFO: safety margin for low PH: adding 231, margin is now 251
[12:29:43.479] <TB1> INFO: safety margin for low PH: adding 232, margin is now 252
[12:29:43.486] <TB1> INFO: safety margin for low PH: adding 233, margin is now 253
[12:29:43.493] <TB1> INFO: safety margin for low PH: adding 234, margin is now 254
[12:29:43.501] <TB1> INFO: safety margin for low PH: adding 235, margin is now 255
[12:29:43.509] <TB1> INFO: For ROC 6: No solutions with PhScale > 20 could be found. Looking for a solution with PhScale < 20.
[12:29:43.509] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[12:29:43.516] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[12:29:43.522] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[12:29:43.529] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[12:29:43.536] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[12:29:43.543] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[12:29:43.549] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[12:29:43.556] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[12:29:43.563] <TB1> INFO: safety margin for low PH: adding 8, margin is now 28
[12:29:43.570] <TB1> INFO: safety margin for low PH: adding 9, margin is now 29
[12:29:43.577] <TB1> INFO: safety margin for low PH: adding 10, margin is now 30
[12:29:43.583] <TB1> INFO: safety margin for low PH: adding 11, margin is now 31
[12:29:43.590] <TB1> INFO: safety margin for low PH: adding 12, margin is now 32
[12:29:43.597] <TB1> INFO: safety margin for low PH: adding 13, margin is now 33
[12:29:43.604] <TB1> INFO: safety margin for low PH: adding 14, margin is now 34
[12:29:43.610] <TB1> INFO: safety margin for low PH: adding 15, margin is now 35
[12:29:43.617] <TB1> INFO: safety margin for low PH: adding 16, margin is now 36
[12:29:43.624] <TB1> INFO: safety margin for low PH: adding 17, margin is now 37
[12:29:43.631] <TB1> INFO: safety margin for low PH: adding 18, margin is now 38
[12:29:43.638] <TB1> INFO: safety margin for low PH: adding 19, margin is now 39
[12:29:43.644] <TB1> INFO: safety margin for low PH: adding 20, margin is now 40
[12:29:43.651] <TB1> INFO: safety margin for low PH: adding 21, margin is now 41
[12:29:43.658] <TB1> INFO: safety margin for low PH: adding 22, margin is now 42
[12:29:43.665] <TB1> INFO: safety margin for low PH: adding 23, margin is now 43
[12:29:43.671] <TB1> INFO: safety margin for low PH: adding 24, margin is now 44
[12:29:43.678] <TB1> INFO: safety margin for low PH: adding 25, margin is now 45
[12:29:43.685] <TB1> INFO: safety margin for low PH: adding 26, margin is now 46
[12:29:43.692] <TB1> INFO: safety margin for low PH: adding 27, margin is now 47
[12:29:43.698] <TB1> INFO: safety margin for low PH: adding 28, margin is now 48
[12:29:43.705] <TB1> INFO: safety margin for low PH: adding 29, margin is now 49
[12:29:43.712] <TB1> INFO: safety margin for low PH: adding 30, margin is now 50
[12:29:43.719] <TB1> INFO: safety margin for low PH: adding 31, margin is now 51
[12:29:43.726] <TB1> INFO: safety margin for low PH: adding 32, margin is now 52
[12:29:43.732] <TB1> INFO: safety margin for low PH: adding 33, margin is now 53
[12:29:43.739] <TB1> INFO: safety margin for low PH: adding 34, margin is now 54
[12:29:43.746] <TB1> INFO: safety margin for low PH: adding 35, margin is now 55
[12:29:43.753] <TB1> INFO: safety margin for low PH: adding 36, margin is now 56
[12:29:43.760] <TB1> INFO: safety margin for low PH: adding 37, margin is now 57
[12:29:43.766] <TB1> INFO: safety margin for low PH: adding 38, margin is now 58
[12:29:43.773] <TB1> INFO: safety margin for low PH: adding 39, margin is now 59
[12:29:43.780] <TB1> INFO: safety margin for low PH: adding 40, margin is now 60
[12:29:43.787] <TB1> INFO: safety margin for low PH: adding 41, margin is now 61
[12:29:43.793] <TB1> INFO: safety margin for low PH: adding 42, margin is now 62
[12:29:43.800] <TB1> INFO: safety margin for low PH: adding 43, margin is now 63
[12:29:43.807] <TB1> INFO: safety margin for low PH: adding 44, margin is now 64
[12:29:43.814] <TB1> INFO: safety margin for low PH: adding 45, margin is now 65
[12:29:43.821] <TB1> INFO: safety margin for low PH: adding 46, margin is now 66
[12:29:43.827] <TB1> INFO: safety margin for low PH: adding 47, margin is now 67
[12:29:43.834] <TB1> INFO: safety margin for low PH: adding 48, margin is now 68
[12:29:43.841] <TB1> INFO: safety margin for low PH: adding 49, margin is now 69
[12:29:43.848] <TB1> INFO: safety margin for low PH: adding 50, margin is now 70
[12:29:43.854] <TB1> INFO: safety margin for low PH: adding 51, margin is now 71
[12:29:43.861] <TB1> INFO: safety margin for low PH: adding 52, margin is now 72
[12:29:43.868] <TB1> INFO: safety margin for low PH: adding 53, margin is now 73
[12:29:43.875] <TB1> INFO: safety margin for low PH: adding 54, margin is now 74
[12:29:43.882] <TB1> INFO: safety margin for low PH: adding 55, margin is now 75
[12:29:43.888] <TB1> INFO: safety margin for low PH: adding 56, margin is now 76
[12:29:43.895] <TB1> INFO: safety margin for low PH: adding 57, margin is now 77
[12:29:43.902] <TB1> INFO: safety margin for low PH: adding 58, margin is now 78
[12:29:43.909] <TB1> INFO: safety margin for low PH: adding 59, margin is now 79
[12:29:43.915] <TB1> INFO: safety margin for low PH: adding 60, margin is now 80
[12:29:43.922] <TB1> INFO: safety margin for low PH: adding 61, margin is now 81
[12:29:43.929] <TB1> INFO: safety margin for low PH: adding 62, margin is now 82
[12:29:43.936] <TB1> INFO: safety margin for low PH: adding 63, margin is now 83
[12:29:43.942] <TB1> INFO: safety margin for low PH: adding 64, margin is now 84
[12:29:43.949] <TB1> INFO: safety margin for low PH: adding 65, margin is now 85
[12:29:43.956] <TB1> INFO: safety margin for low PH: adding 66, margin is now 86
[12:29:43.963] <TB1> INFO: safety margin for low PH: adding 67, margin is now 87
[12:29:43.970] <TB1> INFO: safety margin for low PH: adding 68, margin is now 88
[12:29:43.977] <TB1> INFO: safety margin for low PH: adding 69, margin is now 89
[12:29:43.983] <TB1> INFO: safety margin for low PH: adding 70, margin is now 90
[12:29:43.990] <TB1> INFO: safety margin for low PH: adding 71, margin is now 91
[12:29:43.997] <TB1> INFO: safety margin for low PH: adding 72, margin is now 92
[12:29:44.004] <TB1> INFO: safety margin for low PH: adding 73, margin is now 93
[12:29:44.010] <TB1> INFO: safety margin for low PH: adding 74, margin is now 94
[12:29:44.017] <TB1> INFO: safety margin for low PH: adding 75, margin is now 95
[12:29:44.024] <TB1> INFO: safety margin for low PH: adding 76, margin is now 96
[12:29:44.031] <TB1> INFO: safety margin for low PH: adding 77, margin is now 97
[12:29:44.037] <TB1> INFO: safety margin for low PH: adding 78, margin is now 98
[12:29:44.044] <TB1> INFO: safety margin for low PH: adding 79, margin is now 99
[12:29:44.051] <TB1> INFO: safety margin for low PH: adding 80, margin is now 100
[12:29:44.058] <TB1> INFO: safety margin for low PH: adding 81, margin is now 101
[12:29:44.064] <TB1> INFO: safety margin for low PH: adding 82, margin is now 102
[12:29:44.071] <TB1> INFO: safety margin for low PH: adding 83, margin is now 103
[12:29:44.078] <TB1> INFO: safety margin for low PH: adding 84, margin is now 104
[12:29:44.085] <TB1> INFO: safety margin for low PH: adding 85, margin is now 105
[12:29:44.091] <TB1> INFO: safety margin for low PH: adding 86, margin is now 106
[12:29:44.098] <TB1> INFO: safety margin for low PH: adding 87, margin is now 107
[12:29:44.105] <TB1> INFO: safety margin for low PH: adding 88, margin is now 108
[12:29:44.111] <TB1> INFO: safety margin for low PH: adding 89, margin is now 109
[12:29:44.118] <TB1> INFO: safety margin for low PH: adding 90, margin is now 110
[12:29:44.125] <TB1> INFO: safety margin for low PH: adding 91, margin is now 111
[12:29:44.132] <TB1> INFO: safety margin for low PH: adding 92, margin is now 112
[12:29:44.138] <TB1> INFO: safety margin for low PH: adding 93, margin is now 113
[12:29:44.145] <TB1> INFO: safety margin for low PH: adding 94, margin is now 114
[12:29:44.152] <TB1> INFO: safety margin for low PH: adding 95, margin is now 115
[12:29:44.159] <TB1> INFO: safety margin for low PH: adding 96, margin is now 116
[12:29:44.165] <TB1> INFO: safety margin for low PH: adding 97, margin is now 117
[12:29:44.172] <TB1> INFO: safety margin for low PH: adding 98, margin is now 118
[12:29:44.179] <TB1> INFO: safety margin for low PH: adding 99, margin is now 119
[12:29:44.186] <TB1> INFO: safety margin for low PH: adding 100, margin is now 120
[12:29:44.193] <TB1> INFO: safety margin for low PH: adding 101, margin is now 121
[12:29:44.199] <TB1> INFO: safety margin for low PH: adding 102, margin is now 122
[12:29:44.207] <TB1> INFO: safety margin for low PH: adding 103, margin is now 123
[12:29:44.216] <TB1> INFO: safety margin for low PH: adding 104, margin is now 124
[12:29:44.224] <TB1> INFO: safety margin for low PH: adding 105, margin is now 125
[12:29:44.233] <TB1> INFO: safety margin for low PH: adding 106, margin is now 126
[12:29:44.241] <TB1> INFO: safety margin for low PH: adding 107, margin is now 127
[12:29:44.249] <TB1> INFO: safety margin for low PH: adding 108, margin is now 128
[12:29:44.256] <TB1> INFO: safety margin for low PH: adding 109, margin is now 129
[12:29:44.264] <TB1> INFO: safety margin for low PH: adding 110, margin is now 130
[12:29:44.271] <TB1> INFO: safety margin for low PH: adding 111, margin is now 131
[12:29:44.277] <TB1> INFO: safety margin for low PH: adding 112, margin is now 132
[12:29:44.284] <TB1> INFO: safety margin for low PH: adding 113, margin is now 133
[12:29:44.291] <TB1> INFO: safety margin for low PH: adding 114, margin is now 134
[12:29:44.298] <TB1> INFO: safety margin for low PH: adding 115, margin is now 135
[12:29:44.304] <TB1> INFO: safety margin for low PH: adding 116, margin is now 136
[12:29:44.311] <TB1> INFO: safety margin for low PH: adding 117, margin is now 137
[12:29:44.318] <TB1> INFO: safety margin for low PH: adding 118, margin is now 138
[12:29:44.325] <TB1> INFO: safety margin for low PH: adding 119, margin is now 139
[12:29:44.331] <TB1> INFO: safety margin for low PH: adding 120, margin is now 140
[12:29:44.338] <TB1> INFO: safety margin for low PH: adding 121, margin is now 141
[12:29:44.345] <TB1> INFO: safety margin for low PH: adding 122, margin is now 142
[12:29:44.352] <TB1> INFO: safety margin for low PH: adding 123, margin is now 143
[12:29:44.358] <TB1> INFO: safety margin for low PH: adding 124, margin is now 144
[12:29:44.365] <TB1> INFO: safety margin for low PH: adding 125, margin is now 145
[12:29:44.372] <TB1> INFO: safety margin for low PH: adding 126, margin is now 146
[12:29:44.379] <TB1> INFO: safety margin for low PH: adding 127, margin is now 147
[12:29:44.385] <TB1> INFO: safety margin for low PH: adding 128, margin is now 148
[12:29:44.392] <TB1> INFO: safety margin for low PH: adding 129, margin is now 149
[12:29:44.399] <TB1> INFO: safety margin for low PH: adding 130, margin is now 150
[12:29:44.406] <TB1> INFO: safety margin for low PH: adding 131, margin is now 151
[12:29:44.412] <TB1> INFO: safety margin for low PH: adding 132, margin is now 152
[12:29:44.419] <TB1> INFO: safety margin for low PH: adding 133, margin is now 153
[12:29:44.426] <TB1> INFO: safety margin for low PH: adding 134, margin is now 154
[12:29:44.433] <TB1> INFO: safety margin for low PH: adding 135, margin is now 155
[12:29:44.439] <TB1> INFO: safety margin for low PH: adding 136, margin is now 156
[12:29:44.446] <TB1> INFO: safety margin for low PH: adding 137, margin is now 157
[12:29:44.453] <TB1> INFO: safety margin for low PH: adding 138, margin is now 158
[12:29:44.460] <TB1> INFO: safety margin for low PH: adding 139, margin is now 159
[12:29:44.466] <TB1> INFO: safety margin for low PH: adding 140, margin is now 160
[12:29:44.473] <TB1> INFO: safety margin for low PH: adding 141, margin is now 161
[12:29:44.480] <TB1> INFO: safety margin for low PH: adding 142, margin is now 162
[12:29:44.487] <TB1> INFO: safety margin for low PH: adding 143, margin is now 163
[12:29:44.493] <TB1> INFO: safety margin for low PH: adding 144, margin is now 164
[12:29:44.500] <TB1> INFO: safety margin for low PH: adding 145, margin is now 165
[12:29:44.507] <TB1> INFO: safety margin for low PH: adding 146, margin is now 166
[12:29:44.514] <TB1> INFO: safety margin for low PH: adding 147, margin is now 167
[12:29:44.520] <TB1> INFO: safety margin for low PH: adding 148, margin is now 168
[12:29:44.527] <TB1> INFO: safety margin for low PH: adding 149, margin is now 169
[12:29:44.534] <TB1> INFO: safety margin for low PH: adding 150, margin is now 170
[12:29:44.541] <TB1> INFO: safety margin for low PH: adding 151, margin is now 171
[12:29:44.547] <TB1> INFO: safety margin for low PH: adding 152, margin is now 172
[12:29:44.554] <TB1> INFO: safety margin for low PH: adding 153, margin is now 173
[12:29:44.561] <TB1> INFO: safety margin for low PH: adding 154, margin is now 174
[12:29:44.568] <TB1> INFO: safety margin for low PH: adding 155, margin is now 175
[12:29:44.574] <TB1> INFO: safety margin for low PH: adding 156, margin is now 176
[12:29:44.581] <TB1> INFO: safety margin for low PH: adding 157, margin is now 177
[12:29:44.588] <TB1> INFO: safety margin for low PH: adding 158, margin is now 178
[12:29:44.595] <TB1> INFO: safety margin for low PH: adding 159, margin is now 179
[12:29:44.602] <TB1> INFO: safety margin for low PH: adding 160, margin is now 180
[12:29:44.609] <TB1> INFO: safety margin for low PH: adding 161, margin is now 181
[12:29:44.616] <TB1> INFO: safety margin for low PH: adding 162, margin is now 182
[12:29:44.623] <TB1> INFO: safety margin for low PH: adding 163, margin is now 183
[12:29:44.630] <TB1> INFO: safety margin for low PH: adding 164, margin is now 184
[12:29:44.636] <TB1> INFO: safety margin for low PH: adding 165, margin is now 185
[12:29:44.643] <TB1> INFO: safety margin for low PH: adding 166, margin is now 186
[12:29:44.650] <TB1> INFO: safety margin for low PH: adding 167, margin is now 187
[12:29:44.657] <TB1> INFO: safety margin for low PH: adding 168, margin is now 188
[12:29:44.664] <TB1> INFO: safety margin for low PH: adding 169, margin is now 189
[12:29:44.671] <TB1> INFO: safety margin for low PH: adding 170, margin is now 190
[12:29:44.678] <TB1> INFO: safety margin for low PH: adding 171, margin is now 191
[12:29:44.685] <TB1> INFO: safety margin for low PH: adding 172, margin is now 192
[12:29:44.692] <TB1> INFO: safety margin for low PH: adding 173, margin is now 193
[12:29:44.699] <TB1> INFO: safety margin for low PH: adding 174, margin is now 194
[12:29:44.706] <TB1> INFO: safety margin for low PH: adding 175, margin is now 195
[12:29:44.713] <TB1> INFO: safety margin for low PH: adding 176, margin is now 196
[12:29:44.719] <TB1> INFO: safety margin for low PH: adding 177, margin is now 197
[12:29:44.726] <TB1> INFO: safety margin for low PH: adding 178, margin is now 198
[12:29:44.733] <TB1> INFO: safety margin for low PH: adding 179, margin is now 199
[12:29:44.740] <TB1> INFO: safety margin for low PH: adding 180, margin is now 200
[12:29:44.747] <TB1> INFO: safety margin for low PH: adding 181, margin is now 201
[12:29:44.754] <TB1> INFO: safety margin for low PH: adding 182, margin is now 202
[12:29:44.761] <TB1> INFO: safety margin for low PH: adding 183, margin is now 203
[12:29:44.768] <TB1> INFO: safety margin for low PH: adding 184, margin is now 204
[12:29:44.775] <TB1> INFO: safety margin for low PH: adding 185, margin is now 205
[12:29:44.782] <TB1> INFO: safety margin for low PH: adding 186, margin is now 206
[12:29:44.789] <TB1> INFO: safety margin for low PH: adding 187, margin is now 207
[12:29:44.795] <TB1> INFO: safety margin for low PH: adding 188, margin is now 208
[12:29:44.802] <TB1> INFO: safety margin for low PH: adding 189, margin is now 209
[12:29:44.809] <TB1> INFO: safety margin for low PH: adding 190, margin is now 210
[12:29:44.816] <TB1> INFO: safety margin for low PH: adding 191, margin is now 211
[12:29:44.823] <TB1> INFO: safety margin for low PH: adding 192, margin is now 212
[12:29:44.830] <TB1> INFO: safety margin for low PH: adding 193, margin is now 213
[12:29:44.837] <TB1> INFO: safety margin for low PH: adding 194, margin is now 214
[12:29:44.844] <TB1> INFO: safety margin for low PH: adding 195, margin is now 215
[12:29:44.851] <TB1> INFO: safety margin for low PH: adding 196, margin is now 216
[12:29:44.858] <TB1> INFO: safety margin for low PH: adding 197, margin is now 217
[12:29:44.865] <TB1> INFO: safety margin for low PH: adding 198, margin is now 218
[12:29:44.871] <TB1> INFO: safety margin for low PH: adding 199, margin is now 219
[12:29:44.878] <TB1> INFO: safety margin for low PH: adding 200, margin is now 220
[12:29:44.885] <TB1> INFO: safety margin for low PH: adding 201, margin is now 221
[12:29:44.892] <TB1> INFO: safety margin for low PH: adding 202, margin is now 222
[12:29:44.899] <TB1> INFO: safety margin for low PH: adding 203, margin is now 223
[12:29:44.906] <TB1> INFO: safety margin for low PH: adding 204, margin is now 224
[12:29:44.913] <TB1> INFO: safety margin for low PH: adding 205, margin is now 225
[12:29:44.920] <TB1> INFO: safety margin for low PH: adding 206, margin is now 226
[12:29:44.927] <TB1> INFO: safety margin for low PH: adding 207, margin is now 227
[12:29:44.934] <TB1> INFO: safety margin for low PH: adding 208, margin is now 228
[12:29:44.941] <TB1> INFO: safety margin for low PH: adding 209, margin is now 229
[12:29:44.947] <TB1> INFO: safety margin for low PH: adding 210, margin is now 230
[12:29:44.954] <TB1> INFO: safety margin for low PH: adding 211, margin is now 231
[12:29:44.961] <TB1> INFO: safety margin for low PH: adding 212, margin is now 232
[12:29:44.968] <TB1> INFO: safety margin for low PH: adding 213, margin is now 233
[12:29:44.975] <TB1> INFO: safety margin for low PH: adding 214, margin is now 234
[12:29:44.982] <TB1> INFO: safety margin for low PH: adding 215, margin is now 235
[12:29:44.989] <TB1> INFO: safety margin for low PH: adding 216, margin is now 236
[12:29:44.996] <TB1> INFO: safety margin for low PH: adding 217, margin is now 237
[12:29:45.003] <TB1> INFO: safety margin for low PH: adding 218, margin is now 238
[12:29:45.010] <TB1> INFO: safety margin for low PH: adding 219, margin is now 239
[12:29:45.017] <TB1> INFO: safety margin for low PH: adding 220, margin is now 240
[12:29:45.023] <TB1> INFO: safety margin for low PH: adding 221, margin is now 241
[12:29:45.030] <TB1> INFO: safety margin for low PH: adding 222, margin is now 242
[12:29:45.037] <TB1> INFO: safety margin for low PH: adding 223, margin is now 243
[12:29:45.044] <TB1> INFO: safety margin for low PH: adding 224, margin is now 244
[12:29:45.051] <TB1> INFO: safety margin for low PH: adding 225, margin is now 245
[12:29:45.058] <TB1> INFO: safety margin for low PH: adding 226, margin is now 246
[12:29:45.065] <TB1> INFO: safety margin for low PH: adding 227, margin is now 247
[12:29:45.072] <TB1> INFO: safety margin for low PH: adding 228, margin is now 248
[12:29:45.079] <TB1> INFO: safety margin for low PH: adding 229, margin is now 249
[12:29:45.086] <TB1> INFO: safety margin for low PH: adding 230, margin is now 250
[12:29:45.093] <TB1> INFO: safety margin for low PH: adding 231, margin is now 251
[12:29:45.099] <TB1> INFO: safety margin for low PH: adding 232, margin is now 252
[12:29:45.106] <TB1> INFO: safety margin for low PH: adding 233, margin is now 253
[12:29:45.113] <TB1> INFO: safety margin for low PH: adding 234, margin is now 254
[12:29:45.120] <TB1> INFO: safety margin for low PH: adding 235, margin is now 255
[12:29:45.127] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[12:29:45.134] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[12:29:45.141] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[12:29:45.148] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[12:29:45.155] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[12:29:45.161] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[12:29:45.168] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[12:29:45.175] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[12:29:45.182] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[12:29:45.238] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C0.dat
[12:29:45.238] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C1.dat
[12:29:45.238] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C2.dat
[12:29:45.238] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C3.dat
[12:29:45.239] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C4.dat
[12:29:45.239] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C5.dat
[12:29:45.239] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C6.dat
[12:29:45.239] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C7.dat
[12:29:45.239] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C8.dat
[12:29:45.239] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C9.dat
[12:29:45.239] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C10.dat
[12:29:45.240] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C11.dat
[12:29:45.240] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C12.dat
[12:29:45.240] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C13.dat
[12:29:45.240] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C14.dat
[12:29:45.240] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//dacParameters35_C15.dat
[12:29:45.588] <TB1> INFO: Expecting 41600 events.
[12:29:49.548] <TB1> INFO: 41600 events read in total (3243ms).
[12:29:49.549] <TB1> INFO: Test took 4304ms.
[12:29:50.091] <TB1> INFO: Expecting 41600 events.
[12:29:54.092] <TB1> INFO: 41600 events read in total (3285ms).
[12:29:54.093] <TB1> INFO: Test took 4305ms.
[12:29:54.650] <TB1> INFO: Expecting 41600 events.
[12:29:58.559] <TB1> INFO: 41600 events read in total (3193ms).
[12:29:58.561] <TB1> INFO: Test took 4246ms.
[12:29:58.813] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:29:58.945] <TB1> INFO: Expecting 2560 events.
[12:30:00.015] <TB1> INFO: 2560 events read in total (353ms).
[12:30:00.016] <TB1> INFO: Test took 1203ms.
[12:30:00.019] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:00.431] <TB1> INFO: Expecting 2560 events.
[12:30:01.495] <TB1> INFO: 2560 events read in total (348ms).
[12:30:01.496] <TB1> INFO: Test took 1477ms.
[12:30:01.499] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:01.912] <TB1> INFO: Expecting 2560 events.
[12:30:02.981] <TB1> INFO: 2560 events read in total (353ms).
[12:30:02.982] <TB1> INFO: Test took 1483ms.
[12:30:02.985] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:03.398] <TB1> INFO: Expecting 2560 events.
[12:30:04.468] <TB1> INFO: 2560 events read in total (354ms).
[12:30:04.468] <TB1> INFO: Test took 1483ms.
[12:30:04.471] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:04.884] <TB1> INFO: Expecting 2560 events.
[12:30:05.954] <TB1> INFO: 2560 events read in total (354ms).
[12:30:05.954] <TB1> INFO: Test took 1483ms.
[12:30:05.957] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:06.370] <TB1> INFO: Expecting 2560 events.
[12:30:07.440] <TB1> INFO: 2560 events read in total (354ms).
[12:30:07.440] <TB1> INFO: Test took 1483ms.
[12:30:07.442] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:07.856] <TB1> INFO: Expecting 2560 events.
[12:30:08.922] <TB1> INFO: 2560 events read in total (349ms).
[12:30:08.923] <TB1> INFO: Test took 1481ms.
[12:30:08.926] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:09.338] <TB1> INFO: Expecting 2560 events.
[12:30:10.403] <TB1> INFO: 2560 events read in total (349ms).
[12:30:10.403] <TB1> INFO: Test took 1477ms.
[12:30:10.406] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:10.820] <TB1> INFO: Expecting 2560 events.
[12:30:11.889] <TB1> INFO: 2560 events read in total (353ms).
[12:30:11.889] <TB1> INFO: Test took 1483ms.
[12:30:11.892] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:12.306] <TB1> INFO: Expecting 2560 events.
[12:30:13.371] <TB1> INFO: 2560 events read in total (349ms).
[12:30:13.371] <TB1> INFO: Test took 1479ms.
[12:30:13.373] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:13.786] <TB1> INFO: Expecting 2560 events.
[12:30:14.857] <TB1> INFO: 2560 events read in total (354ms).
[12:30:14.858] <TB1> INFO: Test took 1485ms.
[12:30:14.861] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:15.273] <TB1> INFO: Expecting 2560 events.
[12:30:16.343] <TB1> INFO: 2560 events read in total (353ms).
[12:30:16.343] <TB1> INFO: Test took 1482ms.
[12:30:16.346] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:16.760] <TB1> INFO: Expecting 2560 events.
[12:30:17.820] <TB1> INFO: 2560 events read in total (344ms).
[12:30:17.820] <TB1> INFO: Test took 1474ms.
[12:30:17.823] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:18.237] <TB1> INFO: Expecting 2560 events.
[12:30:19.297] <TB1> INFO: 2560 events read in total (344ms).
[12:30:19.298] <TB1> INFO: Test took 1476ms.
[12:30:19.300] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:19.715] <TB1> INFO: Expecting 2560 events.
[12:30:20.778] <TB1> INFO: 2560 events read in total (347ms).
[12:30:20.778] <TB1> INFO: Test took 1478ms.
[12:30:20.780] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:21.194] <TB1> INFO: Expecting 2560 events.
[12:30:22.258] <TB1> INFO: 2560 events read in total (348ms).
[12:30:22.258] <TB1> INFO: Test took 1478ms.
[12:30:22.261] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:22.674] <TB1> INFO: Expecting 2560 events.
[12:30:23.738] <TB1> INFO: 2560 events read in total (348ms).
[12:30:23.739] <TB1> INFO: Test took 1478ms.
[12:30:23.742] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:24.154] <TB1> INFO: Expecting 2560 events.
[12:30:25.224] <TB1> INFO: 2560 events read in total (353ms).
[12:30:25.224] <TB1> INFO: Test took 1482ms.
[12:30:25.227] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:25.640] <TB1> INFO: Expecting 2560 events.
[12:30:26.713] <TB1> INFO: 2560 events read in total (356ms).
[12:30:26.714] <TB1> INFO: Test took 1487ms.
[12:30:26.716] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:27.130] <TB1> INFO: Expecting 2560 events.
[12:30:28.201] <TB1> INFO: 2560 events read in total (354ms).
[12:30:28.201] <TB1> INFO: Test took 1485ms.
[12:30:28.203] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:28.617] <TB1> INFO: Expecting 2560 events.
[12:30:29.687] <TB1> INFO: 2560 events read in total (353ms).
[12:30:29.687] <TB1> INFO: Test took 1484ms.
[12:30:29.690] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:30.103] <TB1> INFO: Expecting 2560 events.
[12:30:31.167] <TB1> INFO: 2560 events read in total (347ms).
[12:30:31.167] <TB1> INFO: Test took 1477ms.
[12:30:31.171] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:31.584] <TB1> INFO: Expecting 2560 events.
[12:30:32.657] <TB1> INFO: 2560 events read in total (357ms).
[12:30:32.658] <TB1> INFO: Test took 1487ms.
[12:30:32.661] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:33.074] <TB1> INFO: Expecting 2560 events.
[12:30:34.145] <TB1> INFO: 2560 events read in total (354ms).
[12:30:34.146] <TB1> INFO: Test took 1485ms.
[12:30:34.149] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:34.562] <TB1> INFO: Expecting 2560 events.
[12:30:35.627] <TB1> INFO: 2560 events read in total (348ms).
[12:30:35.628] <TB1> INFO: Test took 1479ms.
[12:30:35.630] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:36.044] <TB1> INFO: Expecting 2560 events.
[12:30:37.116] <TB1> INFO: 2560 events read in total (355ms).
[12:30:37.116] <TB1> INFO: Test took 1486ms.
[12:30:37.119] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:37.533] <TB1> INFO: Expecting 2560 events.
[12:30:38.606] <TB1> INFO: 2560 events read in total (357ms).
[12:30:38.606] <TB1> INFO: Test took 1487ms.
[12:30:38.609] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:39.023] <TB1> INFO: Expecting 2560 events.
[12:30:40.088] <TB1> INFO: 2560 events read in total (349ms).
[12:30:40.088] <TB1> INFO: Test took 1479ms.
[12:30:40.091] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:40.505] <TB1> INFO: Expecting 2560 events.
[12:30:41.570] <TB1> INFO: 2560 events read in total (349ms).
[12:30:41.571] <TB1> INFO: Test took 1480ms.
[12:30:41.573] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:41.987] <TB1> INFO: Expecting 2560 events.
[12:30:43.063] <TB1> INFO: 2560 events read in total (359ms).
[12:30:43.064] <TB1> INFO: Test took 1491ms.
[12:30:43.067] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:43.480] <TB1> INFO: Expecting 2560 events.
[12:30:44.551] <TB1> INFO: 2560 events read in total (354ms).
[12:30:44.551] <TB1> INFO: Test took 1484ms.
[12:30:44.555] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:30:44.967] <TB1> INFO: Expecting 2560 events.
[12:30:46.032] <TB1> INFO: 2560 events read in total (349ms).
[12:30:46.032] <TB1> INFO: Test took 1477ms.
[12:30:46.702] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 560 seconds
[12:30:46.702] <TB1> INFO: PH scale (per ROC): 83 95 89 86 92 100 0 85 81 100 97 99 90 92 84 86
[12:30:46.702] <TB1> INFO: PH offset (per ROC): 147 158 143 144 166 134 0 134 167 133 141 162 154 155 164 164
[12:30:46.709] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:30:46.709] <TB1> INFO: Decoding statistics:
[12:30:46.709] <TB1> INFO: General information:
[12:30:46.709] <TB1> INFO: 16bit words read: 91924
[12:30:46.709] <TB1> INFO: valid events total: 10240
[12:30:46.709] <TB1> INFO: empty events: 7739
[12:30:46.709] <TB1> INFO: valid events with pixels: 2501
[12:30:46.709] <TB1> INFO: valid pixel hits: 2501
[12:30:46.709] <TB1> INFO: Event errors: 0
[12:30:46.709] <TB1> INFO: start marker: 0
[12:30:46.709] <TB1> INFO: stop marker: 0
[12:30:46.709] <TB1> INFO: overflow: 0
[12:30:46.709] <TB1> INFO: invalid 5bit words: 0
[12:30:46.709] <TB1> INFO: invalid XOR eye diagram: 0
[12:30:46.709] <TB1> INFO: TBM errors: 0
[12:30:46.709] <TB1> INFO: flawed TBM headers: 0
[12:30:46.709] <TB1> INFO: flawed TBM trailers: 0
[12:30:46.709] <TB1> INFO: event ID mismatches: 0
[12:30:46.709] <TB1> INFO: ROC errors: 0
[12:30:46.709] <TB1> INFO: missing ROC header(s): 0
[12:30:46.709] <TB1> INFO: misplaced readback start: 0
[12:30:46.709] <TB1> INFO: Pixel decoding errors: 0
[12:30:46.709] <TB1> INFO: pixel data incomplete: 0
[12:30:46.709] <TB1> INFO: pixel address: 0
[12:30:46.709] <TB1> INFO: pulse height fill bit: 0
[12:30:46.709] <TB1> INFO: buffer corruption: 0
[12:30:46.873] <TB1> INFO: ######################################################################
[12:30:46.873] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[12:30:46.873] <TB1> INFO: ######################################################################
[12:30:46.883] <TB1> INFO: scanning low vcal = 10
[12:30:47.188] <TB1> INFO: Expecting 41600 events.
[12:30:50.987] <TB1> INFO: 41600 events read in total (3083ms).
[12:30:50.987] <TB1> INFO: Test took 4104ms.
[12:30:50.990] <TB1> INFO: scanning low vcal = 20
[12:30:51.403] <TB1> INFO: Expecting 41600 events.
[12:30:55.221] <TB1> INFO: 41600 events read in total (3101ms).
[12:30:55.222] <TB1> INFO: Test took 4232ms.
[12:30:55.224] <TB1> INFO: scanning low vcal = 30
[12:30:55.637] <TB1> INFO: Expecting 41600 events.
[12:30:59.443] <TB1> INFO: 41600 events read in total (3090ms).
[12:30:59.443] <TB1> INFO: Test took 4219ms.
[12:30:59.446] <TB1> INFO: scanning low vcal = 40
[12:30:59.852] <TB1> INFO: Expecting 41600 events.
[12:31:04.111] <TB1> INFO: 41600 events read in total (3543ms).
[12:31:04.111] <TB1> INFO: Test took 4665ms.
[12:31:04.115] <TB1> INFO: scanning low vcal = 50
[12:31:04.471] <TB1> INFO: Expecting 41600 events.
[12:31:08.777] <TB1> INFO: 41600 events read in total (3590ms).
[12:31:08.778] <TB1> INFO: Test took 4663ms.
[12:31:08.781] <TB1> INFO: scanning low vcal = 60
[12:31:09.136] <TB1> INFO: Expecting 41600 events.
[12:31:13.432] <TB1> INFO: 41600 events read in total (3580ms).
[12:31:13.433] <TB1> INFO: Test took 4652ms.
[12:31:13.436] <TB1> INFO: scanning low vcal = 70
[12:31:13.774] <TB1> INFO: Expecting 41600 events.
[12:31:18.126] <TB1> INFO: 41600 events read in total (3636ms).
[12:31:18.126] <TB1> INFO: Test took 4690ms.
[12:31:18.130] <TB1> INFO: scanning low vcal = 80
[12:31:18.471] <TB1> INFO: Expecting 41600 events.
[12:31:22.747] <TB1> INFO: 41600 events read in total (3560ms).
[12:31:22.748] <TB1> INFO: Test took 4618ms.
[12:31:22.751] <TB1> INFO: scanning low vcal = 90
[12:31:23.108] <TB1> INFO: Expecting 41600 events.
[12:31:27.431] <TB1> INFO: 41600 events read in total (3606ms).
[12:31:27.432] <TB1> INFO: Test took 4681ms.
[12:31:27.436] <TB1> INFO: scanning low vcal = 100
[12:31:27.791] <TB1> INFO: Expecting 41600 events.
[12:31:32.208] <TB1> INFO: 41600 events read in total (3700ms).
[12:31:32.208] <TB1> INFO: Test took 4772ms.
[12:31:32.212] <TB1> INFO: scanning low vcal = 110
[12:31:32.568] <TB1> INFO: Expecting 41600 events.
[12:31:36.903] <TB1> INFO: 41600 events read in total (3618ms).
[12:31:36.904] <TB1> INFO: Test took 4692ms.
[12:31:36.907] <TB1> INFO: scanning low vcal = 120
[12:31:37.257] <TB1> INFO: Expecting 41600 events.
[12:31:41.540] <TB1> INFO: 41600 events read in total (3567ms).
[12:31:41.541] <TB1> INFO: Test took 4634ms.
[12:31:41.544] <TB1> INFO: scanning low vcal = 130
[12:31:41.892] <TB1> INFO: Expecting 41600 events.
[12:31:46.192] <TB1> INFO: 41600 events read in total (3583ms).
[12:31:46.193] <TB1> INFO: Test took 4649ms.
[12:31:46.196] <TB1> INFO: scanning low vcal = 140
[12:31:46.549] <TB1> INFO: Expecting 41600 events.
[12:31:50.898] <TB1> INFO: 41600 events read in total (3633ms).
[12:31:50.899] <TB1> INFO: Test took 4703ms.
[12:31:50.902] <TB1> INFO: scanning low vcal = 150
[12:31:51.261] <TB1> INFO: Expecting 41600 events.
[12:31:55.686] <TB1> INFO: 41600 events read in total (3708ms).
[12:31:55.687] <TB1> INFO: Test took 4785ms.
[12:31:55.690] <TB1> INFO: scanning low vcal = 160
[12:31:56.044] <TB1> INFO: Expecting 41600 events.
[12:32:00.337] <TB1> INFO: 41600 events read in total (3577ms).
[12:32:00.338] <TB1> INFO: Test took 4648ms.
[12:32:00.341] <TB1> INFO: scanning low vcal = 170
[12:32:00.696] <TB1> INFO: Expecting 41600 events.
[12:32:05.033] <TB1> INFO: 41600 events read in total (3621ms).
[12:32:05.034] <TB1> INFO: Test took 4693ms.
[12:32:05.039] <TB1> INFO: scanning low vcal = 180
[12:32:05.389] <TB1> INFO: Expecting 41600 events.
[12:32:09.725] <TB1> INFO: 41600 events read in total (3620ms).
[12:32:09.726] <TB1> INFO: Test took 4687ms.
[12:32:09.729] <TB1> INFO: scanning low vcal = 190
[12:32:10.067] <TB1> INFO: Expecting 41600 events.
[12:32:14.367] <TB1> INFO: 41600 events read in total (3584ms).
[12:32:14.368] <TB1> INFO: Test took 4639ms.
[12:32:14.371] <TB1> INFO: scanning low vcal = 200
[12:32:14.711] <TB1> INFO: Expecting 41600 events.
[12:32:19.070] <TB1> INFO: 41600 events read in total (3642ms).
[12:32:19.071] <TB1> INFO: Test took 4700ms.
[12:32:19.074] <TB1> INFO: scanning low vcal = 210
[12:32:19.419] <TB1> INFO: Expecting 41600 events.
[12:32:23.791] <TB1> INFO: 41600 events read in total (3655ms).
[12:32:23.791] <TB1> INFO: Test took 4717ms.
[12:32:23.795] <TB1> INFO: scanning low vcal = 220
[12:32:24.141] <TB1> INFO: Expecting 41600 events.
[12:32:28.427] <TB1> INFO: 41600 events read in total (3570ms).
[12:32:28.428] <TB1> INFO: Test took 4633ms.
[12:32:28.432] <TB1> INFO: scanning low vcal = 230
[12:32:28.789] <TB1> INFO: Expecting 41600 events.
[12:32:33.110] <TB1> INFO: 41600 events read in total (3604ms).
[12:32:33.111] <TB1> INFO: Test took 4679ms.
[12:32:33.114] <TB1> INFO: scanning low vcal = 240
[12:32:33.457] <TB1> INFO: Expecting 41600 events.
[12:32:37.781] <TB1> INFO: 41600 events read in total (3608ms).
[12:32:37.782] <TB1> INFO: Test took 4668ms.
[12:32:37.785] <TB1> INFO: scanning low vcal = 250
[12:32:38.117] <TB1> INFO: Expecting 41600 events.
[12:32:42.439] <TB1> INFO: 41600 events read in total (3606ms).
[12:32:42.440] <TB1> INFO: Test took 4655ms.
[12:32:42.445] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[12:32:42.792] <TB1> INFO: Expecting 41600 events.
[12:32:47.200] <TB1> INFO: 41600 events read in total (3691ms).
[12:32:47.201] <TB1> INFO: Test took 4756ms.
[12:32:47.204] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[12:32:47.556] <TB1> INFO: Expecting 41600 events.
[12:32:51.931] <TB1> INFO: 41600 events read in total (3658ms).
[12:32:51.932] <TB1> INFO: Test took 4728ms.
[12:32:51.935] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[12:32:52.290] <TB1> INFO: Expecting 41600 events.
[12:32:56.570] <TB1> INFO: 41600 events read in total (3564ms).
[12:32:56.572] <TB1> INFO: Test took 4637ms.
[12:32:56.575] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[12:32:56.921] <TB1> INFO: Expecting 41600 events.
[12:33:01.202] <TB1> INFO: 41600 events read in total (3564ms).
[12:33:01.203] <TB1> INFO: Test took 4628ms.
[12:33:01.206] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[12:33:01.551] <TB1> INFO: Expecting 41600 events.
[12:33:05.902] <TB1> INFO: 41600 events read in total (3634ms).
[12:33:05.903] <TB1> INFO: Test took 4697ms.
[12:33:06.480] <TB1> INFO: PixTestGainPedestal::measure() done
[12:33:41.285] <TB1> INFO: PixTestGainPedestal::fit() done
[12:33:41.285] <TB1> INFO: non-linearity mean: 0.963 0.962 0.954 0.962 0.959 0.967 1.049 0.957 0.962 0.963 0.960 0.954 0.959 0.961 0.948 0.960
[12:33:41.285] <TB1> INFO: non-linearity RMS: 0.003 0.005 0.005 0.009 0.006 0.005 0.119 0.007 0.005 0.005 0.005 0.006 0.007 0.006 0.005 0.004
[12:33:41.285] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[12:33:41.308] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[12:33:41.331] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[12:33:41.354] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[12:33:41.377] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[12:33:41.400] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[12:33:41.423] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[12:33:41.445] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[12:33:41.468] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[12:33:41.491] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[12:33:41.514] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[12:33:41.536] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[12:33:41.559] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[12:33:41.581] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[12:33:41.604] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[12:33:41.627] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[12:33:41.650] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 174 seconds
[12:33:41.650] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:41.650] <TB1> INFO: Decoding statistics:
[12:33:41.650] <TB1> INFO: General information:
[12:33:41.650] <TB1> INFO: 16bit words read: 2661748
[12:33:41.650] <TB1> INFO: valid events total: 166400
[12:33:41.650] <TB1> INFO: empty events: 0
[12:33:41.650] <TB1> INFO: valid events with pixels: 166400
[12:33:41.650] <TB1> INFO: valid pixel hits: 623757
[12:33:41.650] <TB1> INFO: Event errors: 0
[12:33:41.650] <TB1> INFO: start marker: 0
[12:33:41.650] <TB1> INFO: stop marker: 0
[12:33:41.650] <TB1> INFO: overflow: 0
[12:33:41.650] <TB1> INFO: invalid 5bit words: 0
[12:33:41.650] <TB1> INFO: invalid XOR eye diagram: 0
[12:33:41.650] <TB1> INFO: TBM errors: 0
[12:33:41.650] <TB1> INFO: flawed TBM headers: 0
[12:33:41.650] <TB1> INFO: flawed TBM trailers: 0
[12:33:41.650] <TB1> INFO: event ID mismatches: 0
[12:33:41.650] <TB1> INFO: ROC errors: 0
[12:33:41.650] <TB1> INFO: missing ROC header(s): 0
[12:33:41.650] <TB1> INFO: misplaced readback start: 0
[12:33:41.650] <TB1> INFO: Pixel decoding errors: 0
[12:33:41.650] <TB1> INFO: pixel data incomplete: 0
[12:33:41.650] <TB1> INFO: pixel address: 0
[12:33:41.650] <TB1> INFO: pulse height fill bit: 0
[12:33:41.650] <TB1> INFO: buffer corruption: 0
[12:33:41.657] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C15.dat
[12:33:41.658] <TB1> INFO: ######################################################################
[12:33:41.658] <TB1> INFO: PixTestReadback::doTest()
[12:33:41.658] <TB1> INFO: ######################################################################
[12:33:41.659] <TB1> INFO: PixTestReadback::RES sent once
[12:33:53.001] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C0.dat
[12:33:53.001] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C1.dat
[12:33:53.001] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C2.dat
[12:33:53.001] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C3.dat
[12:33:53.001] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C4.dat
[12:33:53.001] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C5.dat
[12:33:53.001] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C6.dat
[12:33:53.001] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C7.dat
[12:33:53.001] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C8.dat
[12:33:53.001] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C9.dat
[12:33:53.001] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C10.dat
[12:33:53.002] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C11.dat
[12:33:53.002] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C12.dat
[12:33:53.002] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C13.dat
[12:33:53.002] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C14.dat
[12:33:53.002] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C15.dat
[12:33:53.048] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[12:33:53.049] <TB1> INFO: PixTestReadback::RES sent once
[12:34:04.322] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C0.dat
[12:34:04.322] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C1.dat
[12:34:04.322] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C2.dat
[12:34:04.322] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C3.dat
[12:34:04.322] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C4.dat
[12:34:04.322] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C5.dat
[12:34:04.322] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C6.dat
[12:34:04.322] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C7.dat
[12:34:04.323] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C8.dat
[12:34:04.323] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C9.dat
[12:34:04.323] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C10.dat
[12:34:04.323] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C11.dat
[12:34:04.323] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C12.dat
[12:34:04.323] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C13.dat
[12:34:04.323] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C14.dat
[12:34:04.323] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C15.dat
[12:34:04.369] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[12:34:04.370] <TB1> INFO: PixTestReadback::RES sent once
[12:34:13.038] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[12:34:13.038] <TB1> INFO: Vbg will be calibrated using Vd calibration
[12:34:13.038] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 145.3calibrated Vbg = 1.21972 :::*/*/*/*/
[12:34:13.038] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 148.9calibrated Vbg = 1.22438 :::*/*/*/*/
[12:34:13.038] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 146.2calibrated Vbg = 1.23636 :::*/*/*/*/
[12:34:13.038] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 151.8calibrated Vbg = 1.23666 :::*/*/*/*/
[12:34:13.038] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 154.1calibrated Vbg = 1.24723 :::*/*/*/*/
[12:34:13.038] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 148.9calibrated Vbg = 1.24463 :::*/*/*/*/
[12:34:13.038] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 143.8calibrated Vbg = 1.24185 :::*/*/*/*/
[12:34:13.038] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 143calibrated Vbg = 1.21185 :::*/*/*/*/
[12:34:13.038] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 148calibrated Vbg = 1.22727 :::*/*/*/*/
[12:34:13.038] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 149.2calibrated Vbg = 1.2252 :::*/*/*/*/
[12:34:13.038] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 146.4calibrated Vbg = 1.2163 :::*/*/*/*/
[12:34:13.038] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 149.2calibrated Vbg = 1.20984 :::*/*/*/*/
[12:34:13.038] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 143.9calibrated Vbg = 1.21505 :::*/*/*/*/
[12:34:13.038] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 143.3calibrated Vbg = 1.21049 :::*/*/*/*/
[12:34:13.038] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 153.1calibrated Vbg = 1.19605 :::*/*/*/*/
[12:34:13.038] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 145.6calibrated Vbg = 1.20288 :::*/*/*/*/
[12:34:13.042] <TB1> INFO: PixTestReadback::RES sent once
[12:37:10.134] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C0.dat
[12:37:10.134] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C1.dat
[12:37:10.134] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C2.dat
[12:37:10.134] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C3.dat
[12:37:10.134] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C4.dat
[12:37:10.134] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C5.dat
[12:37:10.134] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C6.dat
[12:37:10.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C7.dat
[12:37:10.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C8.dat
[12:37:10.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C9.dat
[12:37:10.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C10.dat
[12:37:10.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C11.dat
[12:37:10.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C12.dat
[12:37:10.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C13.dat
[12:37:10.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C14.dat
[12:37:10.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2137_FullQualification_2016-01-20_11h57m_1453287477//000_FulltestPxar_m20//readbackCal_C15.dat
[12:37:10.182] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[12:37:10.183] <TB1> INFO: PixTestReadback::doTest() done
[12:37:10.183] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:37:10.183] <TB1> INFO: Decoding statistics:
[12:37:10.183] <TB1> INFO: General information:
[12:37:10.183] <TB1> INFO: 16bit words read: 2424
[12:37:10.183] <TB1> INFO: valid events total: 128
[12:37:10.183] <TB1> INFO: empty events: 100
[12:37:10.183] <TB1> INFO: valid events with pixels: 28
[12:37:10.183] <TB1> INFO: valid pixel hits: 350
[12:37:10.183] <TB1> INFO: Event errors: 0
[12:37:10.183] <TB1> INFO: start marker: 0
[12:37:10.183] <TB1> INFO: stop marker: 0
[12:37:10.183] <TB1> INFO: overflow: 0
[12:37:10.183] <TB1> INFO: invalid 5bit words: 0
[12:37:10.183] <TB1> INFO: invalid XOR eye diagram: 0
[12:37:10.183] <TB1> INFO: TBM errors: 0
[12:37:10.183] <TB1> INFO: flawed TBM headers: 0
[12:37:10.183] <TB1> INFO: flawed TBM trailers: 0
[12:37:10.183] <TB1> INFO: event ID mismatches: 0
[12:37:10.183] <TB1> INFO: ROC errors: 0
[12:37:10.183] <TB1> INFO: missing ROC header(s): 0
[12:37:10.183] <TB1> INFO: misplaced readback start: 0
[12:37:10.183] <TB1> INFO: Pixel decoding errors: 0
[12:37:10.183] <TB1> INFO: pixel data incomplete: 0
[12:37:10.183] <TB1> INFO: pixel address: 0
[12:37:10.183] <TB1> INFO: pulse height fill bit: 0
[12:37:10.183] <TB1> INFO: buffer corruption: 0
[12:37:10.202] <TB1> INFO: Decoding statistics:
[12:37:10.202] <TB1> INFO: General information:
[12:37:10.202] <TB1> INFO: 16bit words read: 2756096
[12:37:10.202] <TB1> INFO: valid events total: 176768
[12:37:10.202] <TB1> INFO: empty events: 7839
[12:37:10.202] <TB1> INFO: valid events with pixels: 168929
[12:37:10.202] <TB1> INFO: valid pixel hits: 626608
[12:37:10.202] <TB1> INFO: Event errors: 0
[12:37:10.202] <TB1> INFO: start marker: 0
[12:37:10.202] <TB1> INFO: stop marker: 0
[12:37:10.202] <TB1> INFO: overflow: 0
[12:37:10.202] <TB1> INFO: invalid 5bit words: 0
[12:37:10.202] <TB1> INFO: invalid XOR eye diagram: 0
[12:37:10.202] <TB1> INFO: TBM errors: 0
[12:37:10.202] <TB1> INFO: flawed TBM headers: 0
[12:37:10.202] <TB1> INFO: flawed TBM trailers: 0
[12:37:10.202] <TB1> INFO: event ID mismatches: 0
[12:37:10.202] <TB1> INFO: ROC errors: 0
[12:37:10.202] <TB1> INFO: missing ROC header(s): 0
[12:37:10.202] <TB1> INFO: misplaced readback start: 0
[12:37:10.202] <TB1> INFO: Pixel decoding errors: 0
[12:37:10.202] <TB1> INFO: pixel data incomplete: 0
[12:37:10.202] <TB1> INFO: pixel address: 0
[12:37:10.202] <TB1> INFO: pulse height fill bit: 0
[12:37:10.202] <TB1> INFO: buffer corruption: 0
[12:37:10.202] <TB1> INFO: enter test to run
[12:37:10.202] <TB1> INFO: test: exit no parameter change
[12:37:10.757] <TB1> QUIET: Connection to board 153 closed.
[12:37:10.846] <TB1> INFO: pXar: this is the end, my friend