[07:30:20.395] <TB3> INFO: *** Welcome to pxar ***
[07:30:20.395] <TB3> INFO: *** Today: 2016/06/09
[07:30:20.481] <TB3> INFO: *** Version: 9751-dirty
[07:30:20.481] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters_C15.dat
[07:30:20.483] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//tbmParameters_C0b.dat
[07:30:20.483] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//defaultMaskFile.dat
[07:30:20.483] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//trimParameters_C15.dat
[07:30:20.556] <TB3> INFO: clk: 4
[07:30:20.556] <TB3> INFO: ctr: 4
[07:30:20.556] <TB3> INFO: sda: 19
[07:30:20.556] <TB3> INFO: tin: 9
[07:30:20.556] <TB3> INFO: level: 15
[07:30:20.556] <TB3> INFO: triggerdelay: 0
[07:30:20.556] <TB3> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[07:30:20.556] <TB3> INFO: Log level: INFO
[07:30:20.564] <TB3> INFO: Found DTB DTB_WZ4I6J
[07:30:20.572] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[07:30:20.574] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[07:30:20.576] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[07:30:22.135] <TB3> INFO: DUT info:
[07:30:22.135] <TB3> INFO: The DUT currently contains the following objects:
[07:30:22.135] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[07:30:22.135] <TB3> INFO: TBM Core alpha (0): 7 registers set
[07:30:22.135] <TB3> INFO: TBM Core beta (1): 7 registers set
[07:30:22.135] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:30:22.135] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:30:22.136] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:30:22.136] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:30:22.136] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:30:22.136] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:30:22.136] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:30:22.136] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:30:22.136] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:30:22.136] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:30:22.136] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:30:22.136] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:30:22.136] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:30:22.136] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:30:22.136] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:30:22.136] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:30:22.136] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:30:22.537] <TB3> INFO: enter 'restricted' command line mode
[07:30:22.537] <TB3> INFO: enter test to run
[07:30:22.537] <TB3> INFO: test: pretest no parameter change
[07:30:22.537] <TB3> INFO: running: pretest
[07:30:22.547] <TB3> INFO: ----------------------------------------------------------------------
[07:30:22.547] <TB3> INFO: PixTestPretest::programROC()
[07:30:22.547] <TB3> INFO: ----------------------------------------------------------------------
[07:30:40.561] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[07:30:40.561] <TB3> INFO: IA differences per ROC: 19.3 20.9 17.7 18.5 21.7 19.3 20.1 17.7 19.3 21.7 20.9 18.5 20.1 20.1 17.7 20.1
[07:30:40.668] <TB3> INFO: enter test to run
[07:30:40.668] <TB3> INFO: test: pretest no parameter change
[07:30:40.668] <TB3> INFO: running: pretest
[07:30:40.670] <TB3> INFO: ----------------------------------------------------------------------
[07:30:40.670] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[07:30:40.670] <TB3> INFO: ----------------------------------------------------------------------
[07:30:48.975] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 394.7 mA = 24.6687 mA/ROC
[07:30:48.975] <TB3> INFO: i(loss) [mA/ROC]: 19.3 19.3 20.1 19.3 19.3 20.9 19.3 20.1 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3
[07:30:49.013] <TB3> INFO: enter test to run
[07:30:49.013] <TB3> INFO: test: pretest no parameter change
[07:30:49.013] <TB3> INFO: running: pretest
[07:30:49.013] <TB3> INFO: ----------------------------------------------------------------------
[07:30:49.013] <TB3> INFO: PixTestPretest::findTiming()
[07:30:49.013] <TB3> INFO: ----------------------------------------------------------------------
[07:30:49.013] <TB3> INFO: PixTestCmd::init()

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


[07:32:28.640] <TB3> INFO: 231680 events read in total (9693ms).
[07:32:28.644] <TB3> INFO: Test took 10341ms.
[07:32:28.891] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[07:32:28.948] <TB3> INFO: enter test to run
[07:32:28.948] <TB3> INFO: test: pretest no parameter change
[07:32:28.948] <TB3> INFO: running: pretest
[07:32:28.950] <TB3> INFO: ----------------------------------------------------------------------
[07:32:28.950] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[07:32:28.950] <TB3> INFO: ----------------------------------------------------------------------
[07:32:29.043] <TB3> INFO: Expecting 231680 events.
[07:32:39.339] <TB3> INFO: 231680 events read in total (9740ms).
[07:32:39.343] <TB3> INFO: Test took 10389ms.
[07:32:39.592] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[07:32:39.592] <TB3> INFO: CalDel: 154 140 131 157 141 139 160 143 146 140 162 161 149 143 148 146
[07:32:39.592] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[07:32:39.626] <TB3> INFO: enter test to run
[07:32:39.626] <TB3> INFO: test: pretest no parameter change
[07:32:39.626] <TB3> INFO: running: pretest
[07:32:39.626] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters_C0.dat
[07:32:39.626] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters_C1.dat
[07:32:39.626] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters_C2.dat
[07:32:39.627] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters_C3.dat
[07:32:39.627] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters_C4.dat
[07:32:39.627] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters_C5.dat
[07:32:39.627] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters_C6.dat
[07:32:39.627] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters_C7.dat
[07:32:39.627] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters_C8.dat
[07:32:39.627] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters_C9.dat
[07:32:39.627] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters_C10.dat
[07:32:39.627] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters_C11.dat
[07:32:39.627] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters_C12.dat
[07:32:39.627] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters_C13.dat
[07:32:39.628] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters_C14.dat
[07:32:39.628] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters_C15.dat
[07:32:39.628] <TB3> INFO: enter test to run
[07:32:39.628] <TB3> INFO: test: fulltest no parameter change
[07:32:39.628] <TB3> INFO: running: fulltest
[07:32:39.628] <TB3> INFO: ######################################################################
[07:32:39.628] <TB3> INFO: PixTestFullTest::doTest()
[07:32:39.628] <TB3> INFO: ######################################################################
[07:32:39.629] <TB3> INFO: ######################################################################
[07:32:39.629] <TB3> INFO: PixTestAlive::doTest()
[07:32:39.629] <TB3> INFO: ######################################################################
[07:32:39.630] <TB3> INFO: ----------------------------------------------------------------------
[07:32:39.630] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:32:39.630] <TB3> INFO: ----------------------------------------------------------------------
[07:32:39.896] <TB3> INFO: Expecting 41600 events.
[07:32:43.736] <TB3> INFO: 41600 events read in total (3284ms).
[07:32:43.736] <TB3> INFO: Test took 4104ms.
[07:32:43.956] <TB3> INFO: PixTestAlive::aliveTest() done
[07:32:43.956] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:32:43.958] <TB3> INFO: ----------------------------------------------------------------------
[07:32:43.958] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:32:43.958] <TB3> INFO: ----------------------------------------------------------------------
[07:32:44.241] <TB3> INFO: Expecting 41600 events.
[07:32:47.216] <TB3> INFO: 41600 events read in total (2419ms).
[07:32:47.216] <TB3> INFO: Test took 3257ms.
[07:32:47.217] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[07:32:47.455] <TB3> INFO: PixTestAlive::maskTest() done
[07:32:47.455] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:32:47.457] <TB3> INFO: ----------------------------------------------------------------------
[07:32:47.457] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:32:47.457] <TB3> INFO: ----------------------------------------------------------------------
[07:32:47.724] <TB3> INFO: Expecting 41600 events.
[07:32:51.510] <TB3> INFO: 41600 events read in total (3231ms).
[07:32:51.511] <TB3> INFO: Test took 4051ms.
[07:32:51.733] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[07:32:51.733] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:32:51.733] <TB3> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[07:32:51.733] <TB3> INFO: Decoding statistics:
[07:32:51.733] <TB3> INFO: General information:
[07:32:51.733] <TB3> INFO: 16bit words read: 0
[07:32:51.733] <TB3> INFO: valid events total: 0
[07:32:51.733] <TB3> INFO: empty events: 0
[07:32:51.733] <TB3> INFO: valid events with pixels: 0
[07:32:51.733] <TB3> INFO: valid pixel hits: 0
[07:32:51.733] <TB3> INFO: Event errors: 0
[07:32:51.733] <TB3> INFO: start marker: 0
[07:32:51.733] <TB3> INFO: stop marker: 0
[07:32:51.733] <TB3> INFO: overflow: 0
[07:32:51.733] <TB3> INFO: invalid 5bit words: 0
[07:32:51.733] <TB3> INFO: invalid XOR eye diagram: 0
[07:32:51.733] <TB3> INFO: frame (failed synchr.): 0
[07:32:51.733] <TB3> INFO: idle data (no TBM trl): 0
[07:32:51.733] <TB3> INFO: no data (only TBM hdr): 0
[07:32:51.733] <TB3> INFO: TBM errors: 0
[07:32:51.733] <TB3> INFO: flawed TBM headers: 0
[07:32:51.733] <TB3> INFO: flawed TBM trailers: 0
[07:32:51.733] <TB3> INFO: event ID mismatches: 0
[07:32:51.733] <TB3> INFO: ROC errors: 0
[07:32:51.733] <TB3> INFO: missing ROC header(s): 0
[07:32:51.733] <TB3> INFO: misplaced readback start: 0
[07:32:51.733] <TB3> INFO: Pixel decoding errors: 0
[07:32:51.733] <TB3> INFO: pixel data incomplete: 0
[07:32:51.733] <TB3> INFO: pixel address: 0
[07:32:51.733] <TB3> INFO: pulse height fill bit: 0
[07:32:51.733] <TB3> INFO: buffer corruption: 0
[07:32:51.743] <TB3> INFO: ######################################################################
[07:32:51.743] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[07:32:51.743] <TB3> INFO: ######################################################################
[07:32:51.746] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[07:32:51.862] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[07:32:51.862] <TB3> INFO: run 1 of 1
[07:32:52.132] <TB3> INFO: Expecting 3120000 events.
[07:33:32.858] <TB3> INFO: 835055 events read in total (40170ms).
[07:34:13.205] <TB3> INFO: 1664615 events read in total (80518ms).
[07:34:53.340] <TB3> INFO: 2502630 events read in total (120653ms).
[07:35:23.716] <TB3> INFO: 3120000 events read in total (151028ms).
[07:35:23.797] <TB3> INFO: Test took 151935ms.
[07:35:49.112] <TB3> INFO: PixTestBBMap::doTest() done, duration: 177 seconds
[07:35:49.112] <TB3> INFO: number of dead bumps (per ROC): 35 0 0 0 9 31 1 16 0 0 0 0 0 1 0 0
[07:35:49.112] <TB3> INFO: separation cut (per ROC): 65 95 91 81 78 79 73 67 72 70 82 70 65 80 76 87
[07:35:49.112] <TB3> INFO: Decoding statistics:
[07:35:49.112] <TB3> INFO: General information:
[07:35:49.112] <TB3> INFO: 16bit words read: 0
[07:35:49.112] <TB3> INFO: valid events total: 0
[07:35:49.112] <TB3> INFO: empty events: 0
[07:35:49.112] <TB3> INFO: valid events with pixels: 0
[07:35:49.112] <TB3> INFO: valid pixel hits: 0
[07:35:49.112] <TB3> INFO: Event errors: 0
[07:35:49.112] <TB3> INFO: start marker: 0
[07:35:49.112] <TB3> INFO: stop marker: 0
[07:35:49.112] <TB3> INFO: overflow: 0
[07:35:49.112] <TB3> INFO: invalid 5bit words: 0
[07:35:49.112] <TB3> INFO: invalid XOR eye diagram: 0
[07:35:49.112] <TB3> INFO: frame (failed synchr.): 0
[07:35:49.112] <TB3> INFO: idle data (no TBM trl): 0
[07:35:49.112] <TB3> INFO: no data (only TBM hdr): 0
[07:35:49.112] <TB3> INFO: TBM errors: 0
[07:35:49.112] <TB3> INFO: flawed TBM headers: 0
[07:35:49.112] <TB3> INFO: flawed TBM trailers: 0
[07:35:49.112] <TB3> INFO: event ID mismatches: 0
[07:35:49.112] <TB3> INFO: ROC errors: 0
[07:35:49.112] <TB3> INFO: missing ROC header(s): 0
[07:35:49.112] <TB3> INFO: misplaced readback start: 0
[07:35:49.112] <TB3> INFO: Pixel decoding errors: 0
[07:35:49.112] <TB3> INFO: pixel data incomplete: 0
[07:35:49.112] <TB3> INFO: pixel address: 0
[07:35:49.112] <TB3> INFO: pulse height fill bit: 0
[07:35:49.112] <TB3> INFO: buffer corruption: 0
[07:35:49.212] <TB3> INFO: ######################################################################
[07:35:49.212] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[07:35:49.212] <TB3> INFO: ######################################################################
[07:35:49.212] <TB3> INFO: ----------------------------------------------------------------------
[07:35:49.212] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[07:35:49.212] <TB3> INFO: ----------------------------------------------------------------------
[07:35:49.212] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[07:35:49.222] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:35:49.222] <TB3> INFO: run 1 of 1
[07:35:49.508] <TB3> INFO: Expecting 26208000 events.
[07:36:23.123] <TB3> INFO: 880500 events read in total (33059ms).
[07:36:56.023] <TB3> INFO: 1747850 events read in total (65959ms).
[07:37:28.640] <TB3> INFO: 2612850 events read in total (98576ms).
[07:38:01.639] <TB3> INFO: 3475000 events read in total (131575ms).
[07:38:34.369] <TB3> INFO: 4336750 events read in total (164305ms).
[07:39:07.160] <TB3> INFO: 5199200 events read in total (197096ms).
[07:39:39.969] <TB3> INFO: 6062150 events read in total (229905ms).
[07:40:12.935] <TB3> INFO: 6922650 events read in total (262871ms).
[07:40:46.011] <TB3> INFO: 7783900 events read in total (295947ms).
[07:41:19.155] <TB3> INFO: 8643100 events read in total (329091ms).
[07:41:51.967] <TB3> INFO: 9504450 events read in total (361903ms).
[07:42:24.694] <TB3> INFO: 10363100 events read in total (394630ms).
[07:42:57.743] <TB3> INFO: 11222400 events read in total (427679ms).
[07:43:30.685] <TB3> INFO: 12079850 events read in total (460621ms).
[07:44:03.682] <TB3> INFO: 12936000 events read in total (493618ms).
[07:44:37.719] <TB3> INFO: 13785900 events read in total (527655ms).
[07:45:11.770] <TB3> INFO: 14633400 events read in total (561706ms).
[07:45:45.068] <TB3> INFO: 15478750 events read in total (595004ms).
[07:46:18.063] <TB3> INFO: 16323600 events read in total (627999ms).
[07:46:51.393] <TB3> INFO: 17169700 events read in total (661329ms).
[07:47:24.293] <TB3> INFO: 18013100 events read in total (694229ms).
[07:47:57.150] <TB3> INFO: 18857800 events read in total (727086ms).
[07:48:30.085] <TB3> INFO: 19700850 events read in total (760021ms).
[07:49:02.643] <TB3> INFO: 20543450 events read in total (792579ms).
[07:49:35.349] <TB3> INFO: 21387050 events read in total (825285ms).
[07:50:08.159] <TB3> INFO: 22229500 events read in total (858095ms).
[07:50:41.174] <TB3> INFO: 23070600 events read in total (891110ms).
[07:51:13.905] <TB3> INFO: 23913100 events read in total (923841ms).
[07:51:46.787] <TB3> INFO: 24758000 events read in total (956723ms).
[07:52:19.852] <TB3> INFO: 25601850 events read in total (989788ms).
[07:52:44.175] <TB3> INFO: 26208000 events read in total (1014111ms).
[07:52:44.208] <TB3> INFO: Test took 1014985ms.
[07:52:44.481] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[07:52:45.905] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[07:52:47.308] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[07:52:49.012] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[07:52:50.453] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[07:52:51.931] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[07:52:53.388] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[07:52:54.846] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[07:52:56.312] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[07:52:57.759] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[07:52:59.198] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[07:53:00.599] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[07:53:02.048] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[07:53:03.537] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[07:53:04.930] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[07:53:06.373] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[07:53:07.832] <TB3> INFO: PixTestScurves::scurves() done
[07:53:07.832] <TB3> INFO: Vcal mean: 80.14 103.10 88.77 84.05 82.47 91.27 81.43 81.24 85.13 80.04 83.80 74.98 72.00 89.13 89.82 95.10
[07:53:07.832] <TB3> INFO: Vcal RMS: 3.93 5.68 5.13 6.02 4.68 5.13 4.15 4.62 4.87 4.13 4.33 4.36 4.70 4.68 5.04 5.20
[07:53:07.832] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1038 seconds
[07:53:07.832] <TB3> INFO: Decoding statistics:
[07:53:07.832] <TB3> INFO: General information:
[07:53:07.832] <TB3> INFO: 16bit words read: 0
[07:53:07.832] <TB3> INFO: valid events total: 0
[07:53:07.832] <TB3> INFO: empty events: 0
[07:53:07.832] <TB3> INFO: valid events with pixels: 0
[07:53:07.832] <TB3> INFO: valid pixel hits: 0
[07:53:07.832] <TB3> INFO: Event errors: 0
[07:53:07.832] <TB3> INFO: start marker: 0
[07:53:07.832] <TB3> INFO: stop marker: 0
[07:53:07.832] <TB3> INFO: overflow: 0
[07:53:07.832] <TB3> INFO: invalid 5bit words: 0
[07:53:07.832] <TB3> INFO: invalid XOR eye diagram: 0
[07:53:07.832] <TB3> INFO: frame (failed synchr.): 0
[07:53:07.832] <TB3> INFO: idle data (no TBM trl): 0
[07:53:07.832] <TB3> INFO: no data (only TBM hdr): 0
[07:53:07.832] <TB3> INFO: TBM errors: 0
[07:53:07.832] <TB3> INFO: flawed TBM headers: 0
[07:53:07.832] <TB3> INFO: flawed TBM trailers: 0
[07:53:07.832] <TB3> INFO: event ID mismatches: 0
[07:53:07.832] <TB3> INFO: ROC errors: 0
[07:53:07.832] <TB3> INFO: missing ROC header(s): 0
[07:53:07.832] <TB3> INFO: misplaced readback start: 0
[07:53:07.832] <TB3> INFO: Pixel decoding errors: 0
[07:53:07.832] <TB3> INFO: pixel data incomplete: 0
[07:53:07.832] <TB3> INFO: pixel address: 0
[07:53:07.832] <TB3> INFO: pulse height fill bit: 0
[07:53:07.832] <TB3> INFO: buffer corruption: 0
[07:53:07.902] <TB3> INFO: ######################################################################
[07:53:07.902] <TB3> INFO: PixTestTrim::doTest()
[07:53:07.902] <TB3> INFO: ######################################################################
[07:53:07.903] <TB3> INFO: ----------------------------------------------------------------------
[07:53:07.903] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[07:53:07.903] <TB3> INFO: ----------------------------------------------------------------------
[07:53:07.982] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[07:53:07.982] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[07:53:07.991] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[07:53:07.991] <TB3> INFO: run 1 of 1
[07:53:08.251] <TB3> INFO: Expecting 6281600 events.
[07:53:48.309] <TB3> INFO: 1062790 events read in total (39502ms).
[07:54:27.546] <TB3> INFO: 2120070 events read in total (78739ms).
[07:55:06.484] <TB3> INFO: 3171730 events read in total (117677ms).
[07:55:45.565] <TB3> INFO: 4230680 events read in total (156758ms).
[07:56:24.719] <TB3> INFO: 5292470 events read in total (195912ms).
[07:57:02.773] <TB3> INFO: 6281600 events read in total (233966ms).
[07:57:02.804] <TB3> INFO: Test took 234813ms.
[07:57:21.993] <TB3> INFO: ROC 0 VthrComp = 86
[07:57:21.993] <TB3> INFO: ROC 1 VthrComp = 104
[07:57:21.993] <TB3> INFO: ROC 2 VthrComp = 96
[07:57:21.993] <TB3> INFO: ROC 3 VthrComp = 84
[07:57:21.993] <TB3> INFO: ROC 4 VthrComp = 92
[07:57:21.993] <TB3> INFO: ROC 5 VthrComp = 100
[07:57:21.993] <TB3> INFO: ROC 6 VthrComp = 91
[07:57:21.993] <TB3> INFO: ROC 7 VthrComp = 87
[07:57:21.994] <TB3> INFO: ROC 8 VthrComp = 92
[07:57:21.994] <TB3> INFO: ROC 9 VthrComp = 88
[07:57:21.994] <TB3> INFO: ROC 10 VthrComp = 92
[07:57:21.994] <TB3> INFO: ROC 11 VthrComp = 80
[07:57:21.994] <TB3> INFO: ROC 12 VthrComp = 77
[07:57:21.994] <TB3> INFO: ROC 13 VthrComp = 98
[07:57:21.994] <TB3> INFO: ROC 14 VthrComp = 93
[07:57:21.994] <TB3> INFO: ROC 15 VthrComp = 98
[07:57:21.994] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[07:57:21.994] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[07:57:22.003] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[07:57:22.003] <TB3> INFO: run 1 of 1
[07:57:22.264] <TB3> INFO: Expecting 6281600 events.
[07:57:58.319] <TB3> INFO: 756860 events read in total (35499ms).
[07:58:33.230] <TB3> INFO: 1511420 events read in total (70410ms).
[07:59:08.707] <TB3> INFO: 2266410 events read in total (105887ms).
[07:59:43.874] <TB3> INFO: 3021100 events read in total (141054ms).
[08:00:19.040] <TB3> INFO: 3769690 events read in total (176220ms).
[08:00:54.365] <TB3> INFO: 4514210 events read in total (211545ms).
[08:01:29.576] <TB3> INFO: 5257120 events read in total (246756ms).
[08:02:04.481] <TB3> INFO: 5998990 events read in total (281661ms).
[08:02:18.147] <TB3> INFO: 6281600 events read in total (295327ms).
[08:02:18.197] <TB3> INFO: Test took 296194ms.
[08:02:42.145] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 55.9199 for pixel 11/79 mean/min/max = 44.3207/32.5448/56.0967
[08:02:42.145] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 63.0911 for pixel 8/77 mean/min/max = 48.1161/33.0983/63.1338
[08:02:42.145] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 58.6269 for pixel 7/79 mean/min/max = 45.0752/31.5227/58.6277
[08:02:42.145] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 61.8475 for pixel 0/26 mean/min/max = 46.8924/31.8443/61.9405
[08:02:42.146] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 57.6728 for pixel 4/3 mean/min/max = 44.973/32.1702/57.7758
[08:02:42.146] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 57.1228 for pixel 2/72 mean/min/max = 44.6143/31.7293/57.4993
[08:02:42.146] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 54.786 for pixel 18/73 mean/min/max = 43.7206/32.5848/54.8565
[08:02:42.147] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 58.4312 for pixel 0/12 mean/min/max = 45.2444/31.8938/58.595
[08:02:42.147] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 56.9531 for pixel 17/36 mean/min/max = 44.5688/31.8625/57.275
[08:02:42.147] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 56.5621 for pixel 4/5 mean/min/max = 44.8962/33.0607/56.7316
[08:02:42.147] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 56.0781 for pixel 4/63 mean/min/max = 44.6373/32.804/56.4707
[08:02:42.147] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 57.7405 for pixel 0/11 mean/min/max = 45.374/32.9163/57.8318
[08:02:42.148] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 57.8228 for pixel 0/35 mean/min/max = 46.1258/34.4253/57.8263
[08:02:42.148] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 55.8591 for pixel 51/28 mean/min/max = 43.491/31.1024/55.8797
[08:02:42.148] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 58.4971 for pixel 16/57 mean/min/max = 45.6909/32.7078/58.674
[08:02:42.148] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 60.0629 for pixel 22/79 mean/min/max = 45.6897/31.2467/60.1327
[08:02:42.149] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:02:42.238] <TB3> INFO: Expecting 514560 events.
[08:02:55.232] <TB3> INFO: 514560 events read in total (12438ms).
[08:02:55.237] <TB3> INFO: Expecting 514560 events.
[08:03:08.252] <TB3> INFO: 514560 events read in total (12592ms).
[08:03:08.261] <TB3> INFO: Expecting 514560 events.
[08:03:21.163] <TB3> INFO: 514560 events read in total (12488ms).
[08:03:21.174] <TB3> INFO: Expecting 514560 events.
[08:03:33.955] <TB3> INFO: 514560 events read in total (12363ms).
[08:03:33.966] <TB3> INFO: Expecting 514560 events.
[08:03:47.096] <TB3> INFO: 514560 events read in total (12716ms).
[08:03:47.110] <TB3> INFO: Expecting 514560 events.
[08:04:00.069] <TB3> INFO: 514560 events read in total (12557ms).
[08:04:00.084] <TB3> INFO: Expecting 514560 events.
[08:04:12.938] <TB3> INFO: 514560 events read in total (12450ms).
[08:04:12.956] <TB3> INFO: Expecting 514560 events.
[08:04:25.828] <TB3> INFO: 514560 events read in total (12467ms).
[08:04:25.848] <TB3> INFO: Expecting 514560 events.
[08:04:38.701] <TB3> INFO: 514560 events read in total (12447ms).
[08:04:38.723] <TB3> INFO: Expecting 514560 events.
[08:04:51.807] <TB3> INFO: 514560 events read in total (12682ms).
[08:04:51.833] <TB3> INFO: Expecting 514560 events.
[08:05:04.759] <TB3> INFO: 514560 events read in total (12524ms).
[08:05:04.786] <TB3> INFO: Expecting 514560 events.
[08:05:17.760] <TB3> INFO: 514560 events read in total (12572ms).
[08:05:17.788] <TB3> INFO: Expecting 514560 events.
[08:05:30.600] <TB3> INFO: 514560 events read in total (12410ms).
[08:05:30.628] <TB3> INFO: Expecting 514560 events.
[08:05:43.350] <TB3> INFO: 514560 events read in total (12320ms).
[08:05:43.381] <TB3> INFO: Expecting 514560 events.
[08:05:56.381] <TB3> INFO: 514560 events read in total (12599ms).
[08:05:56.415] <TB3> INFO: Expecting 514560 events.
[08:06:09.267] <TB3> INFO: 514560 events read in total (12451ms).
[08:06:09.302] <TB3> INFO: Test took 207153ms.
[08:06:10.333] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[08:06:10.341] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[08:06:10.342] <TB3> INFO: run 1 of 1
[08:06:10.601] <TB3> INFO: Expecting 6281600 events.
[08:06:46.400] <TB3> INFO: 744580 events read in total (35243ms).
[08:07:21.573] <TB3> INFO: 1487540 events read in total (70416ms).
[08:07:56.673] <TB3> INFO: 2230920 events read in total (105516ms).
[08:08:31.843] <TB3> INFO: 2973690 events read in total (140686ms).
[08:09:07.093] <TB3> INFO: 3710730 events read in total (175936ms).
[08:09:41.922] <TB3> INFO: 4443870 events read in total (210765ms).
[08:10:16.888] <TB3> INFO: 5174680 events read in total (245731ms).
[08:10:52.137] <TB3> INFO: 5904560 events read in total (280980ms).
[08:11:10.502] <TB3> INFO: 6281600 events read in total (299345ms).
[08:11:10.549] <TB3> INFO: Test took 300207ms.
[08:11:34.510] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.099186 .. 52.809542
[08:11:34.590] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 62 (-1/-1) hits flags = 528 (plus default)
[08:11:34.599] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[08:11:34.599] <TB3> INFO: run 1 of 1
[08:11:34.867] <TB3> INFO: Expecting 2620800 events.
[08:12:13.040] <TB3> INFO: 918130 events read in total (37617ms).
[08:12:50.951] <TB3> INFO: 1835060 events read in total (75528ms).
[08:13:23.347] <TB3> INFO: 2620800 events read in total (107925ms).
[08:13:23.367] <TB3> INFO: Test took 108769ms.
[08:13:37.713] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 17.419517 .. 45.972114
[08:13:37.790] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 7 .. 55 (-1/-1) hits flags = 528 (plus default)
[08:13:37.799] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[08:13:37.799] <TB3> INFO: run 1 of 1
[08:13:38.065] <TB3> INFO: Expecting 2038400 events.
[08:14:16.765] <TB3> INFO: 927640 events read in total (38142ms).
[08:14:55.563] <TB3> INFO: 1853040 events read in total (76940ms).
[08:15:03.796] <TB3> INFO: 2038400 events read in total (85174ms).
[08:15:03.814] <TB3> INFO: Test took 86015ms.
[08:15:17.670] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 21.614045 .. 42.646459
[08:15:17.761] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 11 .. 52 (-1/-1) hits flags = 528 (plus default)
[08:15:17.773] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[08:15:17.774] <TB3> INFO: run 1 of 1
[08:15:18.062] <TB3> INFO: Expecting 1747200 events.
[08:15:57.387] <TB3> INFO: 930590 events read in total (38769ms).
[08:16:30.455] <TB3> INFO: 1747200 events read in total (71837ms).
[08:16:30.469] <TB3> INFO: Test took 72695ms.
[08:16:43.650] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 24.162622 .. 42.589401
[08:16:43.744] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 14 .. 52 (-1/-1) hits flags = 528 (plus default)
[08:16:43.753] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[08:16:43.753] <TB3> INFO: run 1 of 1
[08:16:44.013] <TB3> INFO: Expecting 1622400 events.
[08:17:22.366] <TB3> INFO: 909800 events read in total (37797ms).
[08:17:52.439] <TB3> INFO: 1622400 events read in total (67870ms).
[08:17:52.449] <TB3> INFO: Test took 68696ms.
[08:18:05.318] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[08:18:05.318] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[08:18:05.326] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[08:18:05.327] <TB3> INFO: run 1 of 1
[08:18:05.590] <TB3> INFO: Expecting 1705600 events.
[08:18:43.591] <TB3> INFO: 879560 events read in total (37445ms).
[08:19:18.331] <TB3> INFO: 1705600 events read in total (72185ms).
[08:19:18.343] <TB3> INFO: Test took 73016ms.
[08:19:33.157] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:19:33.157] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:19:33.157] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:19:33.157] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:19:33.157] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:19:33.157] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:19:33.157] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:19:33.157] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:19:33.157] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:19:33.158] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:19:33.158] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:19:33.158] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:19:33.158] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:19:33.158] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:19:33.158] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:19:33.158] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:19:33.158] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//trimParameters35_C0.dat
[08:19:33.164] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//trimParameters35_C1.dat
[08:19:33.171] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//trimParameters35_C2.dat
[08:19:33.179] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//trimParameters35_C3.dat
[08:19:33.185] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//trimParameters35_C4.dat
[08:19:33.191] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//trimParameters35_C5.dat
[08:19:33.198] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//trimParameters35_C6.dat
[08:19:33.204] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//trimParameters35_C7.dat
[08:19:33.210] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//trimParameters35_C8.dat
[08:19:33.217] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//trimParameters35_C9.dat
[08:19:33.223] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//trimParameters35_C10.dat
[08:19:33.229] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//trimParameters35_C11.dat
[08:19:33.235] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//trimParameters35_C12.dat
[08:19:33.241] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//trimParameters35_C13.dat
[08:19:33.247] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//trimParameters35_C14.dat
[08:19:33.254] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//trimParameters35_C15.dat
[08:19:33.260] <TB3> INFO: PixTestTrim::trimTest() done
[08:19:33.260] <TB3> INFO: vtrim: 86 122 99 109 114 106 87 99 103 107 89 93 82 90 109 112
[08:19:33.260] <TB3> INFO: vthrcomp: 86 104 96 84 92 100 91 87 92 88 92 80 77 98 93 98
[08:19:33.260] <TB3> INFO: vcal mean: 34.95 34.97 34.99 34.97 35.01 34.95 34.98 34.96 34.98 34.95 34.98 34.94 34.98 34.98 34.92 34.95
[08:19:33.260] <TB3> INFO: vcal RMS: 0.80 0.88 0.82 0.89 0.83 0.83 0.78 0.82 0.83 0.77 0.79 0.82 0.76 0.84 0.86 0.86
[08:19:33.260] <TB3> INFO: bits mean: 9.64 8.63 9.28 8.90 9.73 9.61 9.93 9.27 9.79 9.42 9.48 8.87 8.34 9.63 9.42 9.43
[08:19:33.260] <TB3> INFO: bits RMS: 2.64 2.68 2.88 2.87 2.57 2.74 2.47 2.86 2.64 2.64 2.65 2.90 2.78 2.90 2.63 2.86
[08:19:33.267] <TB3> INFO: ----------------------------------------------------------------------
[08:19:33.267] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[08:19:33.267] <TB3> INFO: ----------------------------------------------------------------------
[08:19:33.270] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[08:19:33.278] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[08:19:33.278] <TB3> INFO: run 1 of 1
[08:19:33.539] <TB3> INFO: Expecting 4160000 events.
[08:20:16.301] <TB3> INFO: 909810 events read in total (42206ms).
[08:20:57.497] <TB3> INFO: 1814110 events read in total (83402ms).
[08:21:38.399] <TB3> INFO: 2710515 events read in total (124304ms).
[08:22:19.175] <TB3> INFO: 3603505 events read in total (165080ms).
[08:22:45.079] <TB3> INFO: 4160000 events read in total (190985ms).
[08:22:45.119] <TB3> INFO: Test took 191841ms.
[08:23:15.605] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 167 (-1/-1) hits flags = 528 (plus default)
[08:23:15.614] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[08:23:15.614] <TB3> INFO: run 1 of 1
[08:23:15.875] <TB3> INFO: Expecting 3494400 events.
[08:23:58.346] <TB3> INFO: 940755 events read in total (41915ms).
[08:24:40.356] <TB3> INFO: 1872455 events read in total (83925ms).
[08:25:22.273] <TB3> INFO: 2792950 events read in total (125842ms).
[08:25:54.250] <TB3> INFO: 3494400 events read in total (157819ms).
[08:25:54.285] <TB3> INFO: Test took 158671ms.
[08:26:21.611] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 155 (-1/-1) hits flags = 528 (plus default)
[08:26:21.620] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[08:26:21.620] <TB3> INFO: run 1 of 1
[08:26:21.881] <TB3> INFO: Expecting 3244800 events.
[08:27:04.694] <TB3> INFO: 970495 events read in total (42257ms).
[08:27:46.754] <TB3> INFO: 1928930 events read in total (84317ms).
[08:28:28.813] <TB3> INFO: 2876670 events read in total (126377ms).
[08:28:45.363] <TB3> INFO: 3244800 events read in total (142926ms).
[08:28:45.394] <TB3> INFO: Test took 143774ms.
[08:29:10.934] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 154 (-1/-1) hits flags = 528 (plus default)
[08:29:10.943] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[08:29:10.943] <TB3> INFO: run 1 of 1
[08:29:11.210] <TB3> INFO: Expecting 3224000 events.
[08:29:55.185] <TB3> INFO: 972370 events read in total (43419ms).
[08:30:37.530] <TB3> INFO: 1932545 events read in total (85764ms).
[08:31:19.672] <TB3> INFO: 2881870 events read in total (127906ms).
[08:31:35.048] <TB3> INFO: 3224000 events read in total (143282ms).
[08:31:35.081] <TB3> INFO: Test took 144138ms.
[08:31:59.457] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 154 (-1/-1) hits flags = 528 (plus default)
[08:31:59.469] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[08:31:59.469] <TB3> INFO: run 1 of 1
[08:31:59.763] <TB3> INFO: Expecting 3224000 events.
[08:32:42.628] <TB3> INFO: 971720 events read in total (42309ms).
[08:33:24.669] <TB3> INFO: 1931260 events read in total (84350ms).
[08:34:07.000] <TB3> INFO: 2880325 events read in total (126681ms).
[08:34:22.601] <TB3> INFO: 3224000 events read in total (142282ms).
[08:34:22.632] <TB3> INFO: Test took 143163ms.
[08:34:48.183] <TB3> INFO: PixTestTrim::trimBitTest() done
[08:34:48.184] <TB3> INFO: PixTestTrim::doTest() done, duration: 2500 seconds
[08:34:48.184] <TB3> INFO: Decoding statistics:
[08:34:48.184] <TB3> INFO: General information:
[08:34:48.184] <TB3> INFO: 16bit words read: 0
[08:34:48.184] <TB3> INFO: valid events total: 0
[08:34:48.184] <TB3> INFO: empty events: 0
[08:34:48.184] <TB3> INFO: valid events with pixels: 0
[08:34:48.184] <TB3> INFO: valid pixel hits: 0
[08:34:48.184] <TB3> INFO: Event errors: 0
[08:34:48.184] <TB3> INFO: start marker: 0
[08:34:48.184] <TB3> INFO: stop marker: 0
[08:34:48.184] <TB3> INFO: overflow: 0
[08:34:48.184] <TB3> INFO: invalid 5bit words: 0
[08:34:48.184] <TB3> INFO: invalid XOR eye diagram: 0
[08:34:48.184] <TB3> INFO: frame (failed synchr.): 0
[08:34:48.184] <TB3> INFO: idle data (no TBM trl): 0
[08:34:48.184] <TB3> INFO: no data (only TBM hdr): 0
[08:34:48.184] <TB3> INFO: TBM errors: 0
[08:34:48.184] <TB3> INFO: flawed TBM headers: 0
[08:34:48.184] <TB3> INFO: flawed TBM trailers: 0
[08:34:48.184] <TB3> INFO: event ID mismatches: 0
[08:34:48.184] <TB3> INFO: ROC errors: 0
[08:34:48.184] <TB3> INFO: missing ROC header(s): 0
[08:34:48.184] <TB3> INFO: misplaced readback start: 0
[08:34:48.184] <TB3> INFO: Pixel decoding errors: 0
[08:34:48.184] <TB3> INFO: pixel data incomplete: 0
[08:34:48.184] <TB3> INFO: pixel address: 0
[08:34:48.184] <TB3> INFO: pulse height fill bit: 0
[08:34:48.184] <TB3> INFO: buffer corruption: 0
[08:34:48.913] <TB3> INFO: ######################################################################
[08:34:48.913] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[08:34:48.913] <TB3> INFO: ######################################################################
[08:34:49.187] <TB3> INFO: Expecting 41600 events.
[08:34:52.923] <TB3> INFO: 41600 events read in total (3180ms).
[08:34:52.923] <TB3> INFO: Test took 4009ms.
[08:34:53.401] <TB3> INFO: Expecting 41600 events.
[08:34:57.190] <TB3> INFO: 41600 events read in total (3233ms).
[08:34:57.191] <TB3> INFO: Test took 4062ms.
[08:34:57.475] <TB3> INFO: Expecting 41600 events.
[08:35:01.296] <TB3> INFO: 41600 events read in total (3265ms).
[08:35:01.297] <TB3> INFO: Test took 4085ms.
[08:35:01.551] <TB3> INFO: Expecting 2560 events.
[08:35:02.407] <TB3> INFO: 2560 events read in total (299ms).
[08:35:02.407] <TB3> INFO: Test took 1098ms.
[08:35:02.712] <TB3> INFO: Expecting 2560 events.
[08:35:03.565] <TB3> INFO: 2560 events read in total (297ms).
[08:35:03.565] <TB3> INFO: Test took 1158ms.
[08:35:03.870] <TB3> INFO: Expecting 2560 events.
[08:35:04.722] <TB3> INFO: 2560 events read in total (296ms).
[08:35:04.723] <TB3> INFO: Test took 1158ms.
[08:35:05.028] <TB3> INFO: Expecting 2560 events.
[08:35:05.883] <TB3> INFO: 2560 events read in total (299ms).
[08:35:05.883] <TB3> INFO: Test took 1160ms.
[08:35:06.188] <TB3> INFO: Expecting 2560 events.
[08:35:07.040] <TB3> INFO: 2560 events read in total (296ms).
[08:35:07.040] <TB3> INFO: Test took 1157ms.
[08:35:07.346] <TB3> INFO: Expecting 2560 events.
[08:35:08.199] <TB3> INFO: 2560 events read in total (297ms).
[08:35:08.199] <TB3> INFO: Test took 1158ms.
[08:35:08.504] <TB3> INFO: Expecting 2560 events.
[08:35:09.357] <TB3> INFO: 2560 events read in total (297ms).
[08:35:09.357] <TB3> INFO: Test took 1158ms.
[08:35:09.662] <TB3> INFO: Expecting 2560 events.
[08:35:10.515] <TB3> INFO: 2560 events read in total (297ms).
[08:35:10.515] <TB3> INFO: Test took 1156ms.
[08:35:10.820] <TB3> INFO: Expecting 2560 events.
[08:35:11.675] <TB3> INFO: 2560 events read in total (299ms).
[08:35:11.676] <TB3> INFO: Test took 1159ms.
[08:35:11.980] <TB3> INFO: Expecting 2560 events.
[08:35:12.835] <TB3> INFO: 2560 events read in total (298ms).
[08:35:12.835] <TB3> INFO: Test took 1159ms.
[08:35:13.140] <TB3> INFO: Expecting 2560 events.
[08:35:13.996] <TB3> INFO: 2560 events read in total (300ms).
[08:35:13.997] <TB3> INFO: Test took 1160ms.
[08:35:14.302] <TB3> INFO: Expecting 2560 events.
[08:35:15.155] <TB3> INFO: 2560 events read in total (297ms).
[08:35:15.155] <TB3> INFO: Test took 1158ms.
[08:35:15.460] <TB3> INFO: Expecting 2560 events.
[08:35:16.315] <TB3> INFO: 2560 events read in total (298ms).
[08:35:16.315] <TB3> INFO: Test took 1158ms.
[08:35:16.620] <TB3> INFO: Expecting 2560 events.
[08:35:17.475] <TB3> INFO: 2560 events read in total (298ms).
[08:35:17.475] <TB3> INFO: Test took 1158ms.
[08:35:17.780] <TB3> INFO: Expecting 2560 events.
[08:35:18.635] <TB3> INFO: 2560 events read in total (298ms).
[08:35:18.635] <TB3> INFO: Test took 1159ms.
[08:35:18.940] <TB3> INFO: Expecting 2560 events.
[08:35:19.795] <TB3> INFO: 2560 events read in total (299ms).
[08:35:19.796] <TB3> INFO: Test took 1160ms.
[08:35:19.800] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:35:20.102] <TB3> INFO: Expecting 655360 events.
[08:35:36.877] <TB3> INFO: 655360 events read in total (16219ms).
[08:35:36.886] <TB3> INFO: Expecting 655360 events.
[08:35:53.421] <TB3> INFO: 655360 events read in total (16133ms).
[08:35:53.433] <TB3> INFO: Expecting 655360 events.
[08:36:10.181] <TB3> INFO: 655360 events read in total (16346ms).
[08:36:10.196] <TB3> INFO: Expecting 655360 events.
[08:36:26.829] <TB3> INFO: 655360 events read in total (16231ms).
[08:36:26.849] <TB3> INFO: Expecting 655360 events.
[08:36:43.509] <TB3> INFO: 655360 events read in total (16258ms).
[08:36:43.534] <TB3> INFO: Expecting 655360 events.
[08:37:00.293] <TB3> INFO: 655360 events read in total (16358ms).
[08:37:00.321] <TB3> INFO: Expecting 655360 events.
[08:37:17.032] <TB3> INFO: 655360 events read in total (16310ms).
[08:37:17.061] <TB3> INFO: Expecting 655360 events.
[08:37:33.656] <TB3> INFO: 655360 events read in total (16193ms).
[08:37:33.689] <TB3> INFO: Expecting 655360 events.
[08:37:50.494] <TB3> INFO: 655360 events read in total (16403ms).
[08:37:50.530] <TB3> INFO: Expecting 655360 events.
[08:38:07.104] <TB3> INFO: 655360 events read in total (16172ms).
[08:38:07.146] <TB3> INFO: Expecting 655360 events.
[08:38:23.572] <TB3> INFO: 655360 events read in total (16024ms).
[08:38:23.615] <TB3> INFO: Expecting 655360 events.
[08:38:40.218] <TB3> INFO: 655360 events read in total (16202ms).
[08:38:40.266] <TB3> INFO: Expecting 655360 events.
[08:38:56.852] <TB3> INFO: 655360 events read in total (16184ms).
[08:38:56.905] <TB3> INFO: Expecting 655360 events.
[08:39:13.514] <TB3> INFO: 655360 events read in total (16207ms).
[08:39:13.582] <TB3> INFO: Expecting 655360 events.
[08:39:30.268] <TB3> INFO: 655360 events read in total (16285ms).
[08:39:30.328] <TB3> INFO: Expecting 655360 events.
[08:39:46.994] <TB3> INFO: 655360 events read in total (16264ms).
[08:39:47.054] <TB3> INFO: Test took 267254ms.
[08:39:47.136] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:47.365] <TB3> INFO: Expecting 655360 events.
[08:40:04.170] <TB3> INFO: 655360 events read in total (16248ms).
[08:40:04.179] <TB3> INFO: Expecting 655360 events.
[08:40:20.634] <TB3> INFO: 655360 events read in total (16053ms).
[08:40:20.649] <TB3> INFO: Expecting 655360 events.
[08:40:37.371] <TB3> INFO: 655360 events read in total (16321ms).
[08:40:37.388] <TB3> INFO: Expecting 655360 events.
[08:40:54.009] <TB3> INFO: 655360 events read in total (16219ms).
[08:40:54.036] <TB3> INFO: Expecting 655360 events.
[08:41:10.774] <TB3> INFO: 655360 events read in total (16336ms).
[08:41:10.799] <TB3> INFO: Expecting 655360 events.
[08:41:27.531] <TB3> INFO: 655360 events read in total (16330ms).
[08:41:27.559] <TB3> INFO: Expecting 655360 events.
[08:41:44.265] <TB3> INFO: 655360 events read in total (16305ms).
[08:41:44.316] <TB3> INFO: Expecting 655360 events.
[08:42:00.961] <TB3> INFO: 655360 events read in total (16244ms).
[08:42:00.995] <TB3> INFO: Expecting 655360 events.
[08:42:17.811] <TB3> INFO: 655360 events read in total (16414ms).
[08:42:17.851] <TB3> INFO: Expecting 655360 events.
[08:42:34.503] <TB3> INFO: 655360 events read in total (16250ms).
[08:42:34.545] <TB3> INFO: Expecting 655360 events.
[08:42:51.366] <TB3> INFO: 655360 events read in total (16419ms).
[08:42:51.410] <TB3> INFO: Expecting 655360 events.
[08:43:07.959] <TB3> INFO: 655360 events read in total (16147ms).
[08:43:08.010] <TB3> INFO: Expecting 655360 events.
[08:43:24.569] <TB3> INFO: 655360 events read in total (16157ms).
[08:43:24.620] <TB3> INFO: Expecting 655360 events.
[08:43:41.288] <TB3> INFO: 655360 events read in total (16266ms).
[08:43:41.346] <TB3> INFO: Expecting 655360 events.
[08:43:57.817] <TB3> INFO: 655360 events read in total (16069ms).
[08:43:57.876] <TB3> INFO: Expecting 655360 events.
[08:44:14.528] <TB3> INFO: 655360 events read in total (16251ms).
[08:44:14.593] <TB3> INFO: Test took 267457ms.
[08:44:14.792] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:44:14.799] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:44:14.807] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:44:14.814] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:44:14.821] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:44:14.829] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:44:14.837] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:44:14.844] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:44:14.853] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:44:14.860] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:44:14.868] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:44:14.875] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:44:14.883] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:44:14.891] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:44:14.901] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:44:14.909] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:44:14.972] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:44:14.972] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:44:14.972] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:44:14.972] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:44:14.972] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:44:14.972] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:44:14.972] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:44:14.972] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:44:14.973] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:44:14.973] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:44:14.973] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:44:14.973] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:44:14.973] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:44:14.973] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:44:14.973] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:44:14.973] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:44:15.241] <TB3> INFO: Expecting 41600 events.
[08:44:18.782] <TB3> INFO: 41600 events read in total (2985ms).
[08:44:18.783] <TB3> INFO: Test took 3806ms.
[08:44:19.255] <TB3> INFO: Expecting 41600 events.
[08:44:22.787] <TB3> INFO: 41600 events read in total (2976ms).
[08:44:22.788] <TB3> INFO: Test took 3822ms.
[08:44:23.235] <TB3> INFO: Expecting 41600 events.
[08:44:26.726] <TB3> INFO: 41600 events read in total (2935ms).
[08:44:26.727] <TB3> INFO: Test took 3760ms.
[08:44:26.912] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:27.000] <TB3> INFO: Expecting 2560 events.
[08:44:27.855] <TB3> INFO: 2560 events read in total (299ms).
[08:44:27.857] <TB3> INFO: Test took 945ms.
[08:44:27.860] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:28.160] <TB3> INFO: Expecting 2560 events.
[08:44:29.015] <TB3> INFO: 2560 events read in total (298ms).
[08:44:29.016] <TB3> INFO: Test took 1156ms.
[08:44:29.019] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:29.321] <TB3> INFO: Expecting 2560 events.
[08:44:30.174] <TB3> INFO: 2560 events read in total (297ms).
[08:44:30.174] <TB3> INFO: Test took 1156ms.
[08:44:30.177] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:30.480] <TB3> INFO: Expecting 2560 events.
[08:44:31.335] <TB3> INFO: 2560 events read in total (299ms).
[08:44:31.335] <TB3> INFO: Test took 1158ms.
[08:44:31.339] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:31.641] <TB3> INFO: Expecting 2560 events.
[08:44:32.496] <TB3> INFO: 2560 events read in total (299ms).
[08:44:32.496] <TB3> INFO: Test took 1158ms.
[08:44:32.498] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:32.801] <TB3> INFO: Expecting 2560 events.
[08:44:33.655] <TB3> INFO: 2560 events read in total (297ms).
[08:44:33.655] <TB3> INFO: Test took 1157ms.
[08:44:33.657] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:33.961] <TB3> INFO: Expecting 2560 events.
[08:44:34.814] <TB3> INFO: 2560 events read in total (297ms).
[08:44:34.814] <TB3> INFO: Test took 1157ms.
[08:44:34.816] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:35.120] <TB3> INFO: Expecting 2560 events.
[08:44:35.973] <TB3> INFO: 2560 events read in total (297ms).
[08:44:35.974] <TB3> INFO: Test took 1158ms.
[08:44:35.977] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:36.279] <TB3> INFO: Expecting 2560 events.
[08:44:37.132] <TB3> INFO: 2560 events read in total (297ms).
[08:44:37.133] <TB3> INFO: Test took 1156ms.
[08:44:37.135] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:37.439] <TB3> INFO: Expecting 2560 events.
[08:44:38.291] <TB3> INFO: 2560 events read in total (296ms).
[08:44:38.292] <TB3> INFO: Test took 1157ms.
[08:44:38.294] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:38.597] <TB3> INFO: Expecting 2560 events.
[08:44:39.450] <TB3> INFO: 2560 events read in total (297ms).
[08:44:39.450] <TB3> INFO: Test took 1156ms.
[08:44:39.453] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:39.756] <TB3> INFO: Expecting 2560 events.
[08:44:40.610] <TB3> INFO: 2560 events read in total (298ms).
[08:44:40.610] <TB3> INFO: Test took 1157ms.
[08:44:40.612] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:40.915] <TB3> INFO: Expecting 2560 events.
[08:44:41.769] <TB3> INFO: 2560 events read in total (298ms).
[08:44:41.769] <TB3> INFO: Test took 1158ms.
[08:44:41.772] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:42.075] <TB3> INFO: Expecting 2560 events.
[08:44:42.929] <TB3> INFO: 2560 events read in total (298ms).
[08:44:42.929] <TB3> INFO: Test took 1157ms.
[08:44:42.932] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:43.235] <TB3> INFO: Expecting 2560 events.
[08:44:44.087] <TB3> INFO: 2560 events read in total (296ms).
[08:44:44.087] <TB3> INFO: Test took 1155ms.
[08:44:44.090] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:44.393] <TB3> INFO: Expecting 2560 events.
[08:44:45.245] <TB3> INFO: 2560 events read in total (296ms).
[08:44:45.247] <TB3> INFO: Test took 1157ms.
[08:44:45.251] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:45.551] <TB3> INFO: Expecting 2560 events.
[08:44:46.405] <TB3> INFO: 2560 events read in total (298ms).
[08:44:46.406] <TB3> INFO: Test took 1155ms.
[08:44:46.408] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:46.711] <TB3> INFO: Expecting 2560 events.
[08:44:47.564] <TB3> INFO: 2560 events read in total (297ms).
[08:44:47.564] <TB3> INFO: Test took 1157ms.
[08:44:47.566] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:47.870] <TB3> INFO: Expecting 2560 events.
[08:44:48.723] <TB3> INFO: 2560 events read in total (296ms).
[08:44:48.723] <TB3> INFO: Test took 1157ms.
[08:44:48.726] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:49.029] <TB3> INFO: Expecting 2560 events.
[08:44:49.882] <TB3> INFO: 2560 events read in total (297ms).
[08:44:49.882] <TB3> INFO: Test took 1156ms.
[08:44:49.884] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:50.192] <TB3> INFO: Expecting 2560 events.
[08:44:51.045] <TB3> INFO: 2560 events read in total (297ms).
[08:44:51.045] <TB3> INFO: Test took 1161ms.
[08:44:51.048] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:51.351] <TB3> INFO: Expecting 2560 events.
[08:44:52.206] <TB3> INFO: 2560 events read in total (299ms).
[08:44:52.206] <TB3> INFO: Test took 1158ms.
[08:44:52.209] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:52.512] <TB3> INFO: Expecting 2560 events.
[08:44:53.365] <TB3> INFO: 2560 events read in total (297ms).
[08:44:53.365] <TB3> INFO: Test took 1157ms.
[08:44:53.367] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:53.671] <TB3> INFO: Expecting 2560 events.
[08:44:54.524] <TB3> INFO: 2560 events read in total (297ms).
[08:44:54.524] <TB3> INFO: Test took 1157ms.
[08:44:54.526] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:54.829] <TB3> INFO: Expecting 2560 events.
[08:44:55.685] <TB3> INFO: 2560 events read in total (299ms).
[08:44:55.685] <TB3> INFO: Test took 1159ms.
[08:44:55.687] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:55.991] <TB3> INFO: Expecting 2560 events.
[08:44:56.846] <TB3> INFO: 2560 events read in total (299ms).
[08:44:56.846] <TB3> INFO: Test took 1159ms.
[08:44:56.850] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:57.152] <TB3> INFO: Expecting 2560 events.
[08:44:58.007] <TB3> INFO: 2560 events read in total (299ms).
[08:44:58.007] <TB3> INFO: Test took 1158ms.
[08:44:58.009] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:58.313] <TB3> INFO: Expecting 2560 events.
[08:44:59.166] <TB3> INFO: 2560 events read in total (297ms).
[08:44:59.166] <TB3> INFO: Test took 1157ms.
[08:44:59.169] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:59.471] <TB3> INFO: Expecting 2560 events.
[08:45:00.325] <TB3> INFO: 2560 events read in total (297ms).
[08:45:00.325] <TB3> INFO: Test took 1157ms.
[08:45:00.327] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:45:00.631] <TB3> INFO: Expecting 2560 events.
[08:45:01.484] <TB3> INFO: 2560 events read in total (297ms).
[08:45:01.484] <TB3> INFO: Test took 1158ms.
[08:45:01.485] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:45:01.789] <TB3> INFO: Expecting 2560 events.
[08:45:02.642] <TB3> INFO: 2560 events read in total (297ms).
[08:45:02.643] <TB3> INFO: Test took 1158ms.
[08:45:02.645] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:45:02.948] <TB3> INFO: Expecting 2560 events.
[08:45:03.802] <TB3> INFO: 2560 events read in total (297ms).
[08:45:03.802] <TB3> INFO: Test took 1157ms.
[08:45:04.259] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 615 seconds
[08:45:04.259] <TB3> INFO: PH scale (per ROC): 91 77 82 84 87 86 93 85 89 89 89 88 82 84 79 90
[08:45:04.259] <TB3> INFO: PH offset (per ROC): 147 160 158 155 156 157 134 164 158 157 142 144 144 140 167 147
[08:45:04.263] <TB3> INFO: Decoding statistics:
[08:45:04.263] <TB3> INFO: General information:
[08:45:04.263] <TB3> INFO: 16bit words read: 91912
[08:45:04.263] <TB3> INFO: valid events total: 10240
[08:45:04.264] <TB3> INFO: empty events: 7742
[08:45:04.264] <TB3> INFO: valid events with pixels: 2498
[08:45:04.264] <TB3> INFO: valid pixel hits: 2498
[08:45:04.264] <TB3> INFO: Event errors: 0
[08:45:04.264] <TB3> INFO: start marker: 0
[08:45:04.264] <TB3> INFO: stop marker: 0
[08:45:04.264] <TB3> INFO: overflow: 0
[08:45:04.264] <TB3> INFO: invalid 5bit words: 0
[08:45:04.264] <TB3> INFO: invalid XOR eye diagram: 0
[08:45:04.264] <TB3> INFO: frame (failed synchr.): 0
[08:45:04.264] <TB3> INFO: idle data (no TBM trl): 0
[08:45:04.264] <TB3> INFO: no data (only TBM hdr): 0
[08:45:04.264] <TB3> INFO: TBM errors: 0
[08:45:04.264] <TB3> INFO: flawed TBM headers: 0
[08:45:04.264] <TB3> INFO: flawed TBM trailers: 0
[08:45:04.264] <TB3> INFO: event ID mismatches: 0
[08:45:04.264] <TB3> INFO: ROC errors: 0
[08:45:04.264] <TB3> INFO: missing ROC header(s): 0
[08:45:04.264] <TB3> INFO: misplaced readback start: 0
[08:45:04.264] <TB3> INFO: Pixel decoding errors: 0
[08:45:04.264] <TB3> INFO: pixel data incomplete: 0
[08:45:04.264] <TB3> INFO: pixel address: 0
[08:45:04.264] <TB3> INFO: pulse height fill bit: 0
[08:45:04.264] <TB3> INFO: buffer corruption: 0
[08:45:04.442] <TB3> INFO: ######################################################################
[08:45:04.442] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[08:45:04.442] <TB3> INFO: ######################################################################
[08:45:04.452] <TB3> INFO: scanning low vcal = 10
[08:45:04.719] <TB3> INFO: Expecting 41600 events.
[08:45:08.246] <TB3> INFO: 41600 events read in total (2971ms).
[08:45:08.246] <TB3> INFO: Test took 3793ms.
[08:45:08.247] <TB3> INFO: scanning low vcal = 20
[08:45:08.551] <TB3> INFO: Expecting 41600 events.
[08:45:12.090] <TB3> INFO: 41600 events read in total (2983ms).
[08:45:12.090] <TB3> INFO: Test took 3843ms.
[08:45:12.095] <TB3> INFO: scanning low vcal = 30
[08:45:12.395] <TB3> INFO: Expecting 41600 events.
[08:45:15.939] <TB3> INFO: 41600 events read in total (2988ms).
[08:45:15.940] <TB3> INFO: Test took 3845ms.
[08:45:15.943] <TB3> INFO: scanning low vcal = 40
[08:45:16.240] <TB3> INFO: Expecting 41600 events.
[08:45:20.457] <TB3> INFO: 41600 events read in total (3661ms).
[08:45:20.458] <TB3> INFO: Test took 4515ms.
[08:45:20.461] <TB3> INFO: scanning low vcal = 50
[08:45:20.738] <TB3> INFO: Expecting 41600 events.
[08:45:24.954] <TB3> INFO: 41600 events read in total (3660ms).
[08:45:24.955] <TB3> INFO: Test took 4494ms.
[08:45:24.958] <TB3> INFO: scanning low vcal = 60
[08:45:25.227] <TB3> INFO: Expecting 41600 events.
[08:45:29.404] <TB3> INFO: 41600 events read in total (3620ms).
[08:45:29.404] <TB3> INFO: Test took 4446ms.
[08:45:29.407] <TB3> INFO: scanning low vcal = 70
[08:45:29.679] <TB3> INFO: Expecting 41600 events.
[08:45:33.860] <TB3> INFO: 41600 events read in total (3625ms).
[08:45:33.860] <TB3> INFO: Test took 4453ms.
[08:45:33.863] <TB3> INFO: scanning low vcal = 80
[08:45:34.140] <TB3> INFO: Expecting 41600 events.
[08:45:38.333] <TB3> INFO: 41600 events read in total (3637ms).
[08:45:38.334] <TB3> INFO: Test took 4470ms.
[08:45:38.336] <TB3> INFO: scanning low vcal = 90
[08:45:38.599] <TB3> INFO: Expecting 41600 events.
[08:45:42.770] <TB3> INFO: 41600 events read in total (3616ms).
[08:45:42.771] <TB3> INFO: Test took 4435ms.
[08:45:42.774] <TB3> INFO: scanning low vcal = 100
[08:45:43.037] <TB3> INFO: Expecting 41600 events.
[08:45:47.213] <TB3> INFO: 41600 events read in total (3620ms).
[08:45:47.213] <TB3> INFO: Test took 4439ms.
[08:45:47.216] <TB3> INFO: scanning low vcal = 110
[08:45:47.489] <TB3> INFO: Expecting 41600 events.
[08:45:51.675] <TB3> INFO: 41600 events read in total (3630ms).
[08:45:51.676] <TB3> INFO: Test took 4460ms.
[08:45:51.679] <TB3> INFO: scanning low vcal = 120
[08:45:51.955] <TB3> INFO: Expecting 41600 events.
[08:45:56.139] <TB3> INFO: 41600 events read in total (3627ms).
[08:45:56.140] <TB3> INFO: Test took 4461ms.
[08:45:56.142] <TB3> INFO: scanning low vcal = 130
[08:45:56.463] <TB3> INFO: Expecting 41600 events.
[08:46:00.678] <TB3> INFO: 41600 events read in total (3659ms).
[08:46:00.679] <TB3> INFO: Test took 4537ms.
[08:46:00.682] <TB3> INFO: scanning low vcal = 140
[08:46:00.992] <TB3> INFO: Expecting 41600 events.
[08:46:05.220] <TB3> INFO: 41600 events read in total (3672ms).
[08:46:05.220] <TB3> INFO: Test took 4538ms.
[08:46:05.223] <TB3> INFO: scanning low vcal = 150
[08:46:05.504] <TB3> INFO: Expecting 41600 events.
[08:46:09.671] <TB3> INFO: 41600 events read in total (3611ms).
[08:46:09.672] <TB3> INFO: Test took 4449ms.
[08:46:09.675] <TB3> INFO: scanning low vcal = 160
[08:46:09.935] <TB3> INFO: Expecting 41600 events.
[08:46:14.143] <TB3> INFO: 41600 events read in total (3652ms).
[08:46:14.144] <TB3> INFO: Test took 4469ms.
[08:46:14.147] <TB3> INFO: scanning low vcal = 170
[08:46:14.418] <TB3> INFO: Expecting 41600 events.
[08:46:18.597] <TB3> INFO: 41600 events read in total (3623ms).
[08:46:18.597] <TB3> INFO: Test took 4450ms.
[08:46:18.601] <TB3> INFO: scanning low vcal = 180
[08:46:18.876] <TB3> INFO: Expecting 41600 events.
[08:46:23.088] <TB3> INFO: 41600 events read in total (3656ms).
[08:46:23.088] <TB3> INFO: Test took 4486ms.
[08:46:23.091] <TB3> INFO: scanning low vcal = 190
[08:46:23.365] <TB3> INFO: Expecting 41600 events.
[08:46:27.578] <TB3> INFO: 41600 events read in total (3657ms).
[08:46:27.578] <TB3> INFO: Test took 4487ms.
[08:46:27.581] <TB3> INFO: scanning low vcal = 200
[08:46:27.854] <TB3> INFO: Expecting 41600 events.
[08:46:32.060] <TB3> INFO: 41600 events read in total (3649ms).
[08:46:32.060] <TB3> INFO: Test took 4479ms.
[08:46:32.062] <TB3> INFO: scanning low vcal = 210
[08:46:32.328] <TB3> INFO: Expecting 41600 events.
[08:46:36.531] <TB3> INFO: 41600 events read in total (3647ms).
[08:46:36.531] <TB3> INFO: Test took 4468ms.
[08:46:36.535] <TB3> INFO: scanning low vcal = 220
[08:46:36.813] <TB3> INFO: Expecting 41600 events.
[08:46:41.015] <TB3> INFO: 41600 events read in total (3646ms).
[08:46:41.016] <TB3> INFO: Test took 4481ms.
[08:46:41.019] <TB3> INFO: scanning low vcal = 230
[08:46:41.289] <TB3> INFO: Expecting 41600 events.
[08:46:45.474] <TB3> INFO: 41600 events read in total (3629ms).
[08:46:45.474] <TB3> INFO: Test took 4455ms.
[08:46:45.477] <TB3> INFO: scanning low vcal = 240
[08:46:45.740] <TB3> INFO: Expecting 41600 events.
[08:46:49.952] <TB3> INFO: 41600 events read in total (3656ms).
[08:46:49.952] <TB3> INFO: Test took 4475ms.
[08:46:49.955] <TB3> INFO: scanning low vcal = 250
[08:46:50.231] <TB3> INFO: Expecting 41600 events.
[08:46:54.448] <TB3> INFO: 41600 events read in total (3660ms).
[08:46:54.449] <TB3> INFO: Test took 4494ms.
[08:46:54.453] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[08:46:54.719] <TB3> INFO: Expecting 41600 events.
[08:46:58.934] <TB3> INFO: 41600 events read in total (3658ms).
[08:46:58.934] <TB3> INFO: Test took 4481ms.
[08:46:58.937] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[08:46:59.213] <TB3> INFO: Expecting 41600 events.
[08:47:03.451] <TB3> INFO: 41600 events read in total (3681ms).
[08:47:03.452] <TB3> INFO: Test took 4515ms.
[08:47:03.455] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[08:47:03.725] <TB3> INFO: Expecting 41600 events.
[08:47:07.934] <TB3> INFO: 41600 events read in total (3653ms).
[08:47:07.934] <TB3> INFO: Test took 4479ms.
[08:47:07.937] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[08:47:08.207] <TB3> INFO: Expecting 41600 events.
[08:47:12.408] <TB3> INFO: 41600 events read in total (3644ms).
[08:47:12.409] <TB3> INFO: Test took 4472ms.
[08:47:12.411] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[08:47:12.671] <TB3> INFO: Expecting 41600 events.
[08:47:16.882] <TB3> INFO: 41600 events read in total (3654ms).
[08:47:16.882] <TB3> INFO: Test took 4470ms.
[08:47:17.335] <TB3> INFO: PixTestGainPedestal::measure() done
[08:47:48.861] <TB3> INFO: PixTestGainPedestal::fit() done
[08:47:48.861] <TB3> INFO: non-linearity mean: 0.954 0.960 0.952 0.953 0.951 0.951 0.959 0.959 0.953 0.960 0.955 0.954 0.949 0.956 0.953 0.961
[08:47:48.861] <TB3> INFO: non-linearity RMS: 0.006 0.006 0.005 0.006 0.006 0.006 0.006 0.005 0.007 0.005 0.007 0.006 0.006 0.005 0.006 0.006
[08:47:48.862] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[08:47:48.880] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[08:47:48.898] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[08:47:48.916] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[08:47:48.935] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[08:47:48.953] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[08:47:48.971] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[08:47:48.989] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[08:47:49.008] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[08:47:49.026] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[08:47:49.044] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[08:47:49.062] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[08:47:49.081] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[08:47:49.099] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[08:47:49.117] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[08:47:49.135] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[08:47:49.154] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 164 seconds
[08:47:49.154] <TB3> INFO: Decoding statistics:
[08:47:49.154] <TB3> INFO: General information:
[08:47:49.154] <TB3> INFO: 16bit words read: 2662400
[08:47:49.154] <TB3> INFO: valid events total: 166400
[08:47:49.154] <TB3> INFO: empty events: 0
[08:47:49.154] <TB3> INFO: valid events with pixels: 166400
[08:47:49.154] <TB3> INFO: valid pixel hits: 665600
[08:47:49.154] <TB3> INFO: Event errors: 0
[08:47:49.154] <TB3> INFO: start marker: 0
[08:47:49.154] <TB3> INFO: stop marker: 0
[08:47:49.154] <TB3> INFO: overflow: 0
[08:47:49.154] <TB3> INFO: invalid 5bit words: 0
[08:47:49.154] <TB3> INFO: invalid XOR eye diagram: 0
[08:47:49.154] <TB3> INFO: frame (failed synchr.): 0
[08:47:49.154] <TB3> INFO: idle data (no TBM trl): 0
[08:47:49.154] <TB3> INFO: no data (only TBM hdr): 0
[08:47:49.154] <TB3> INFO: TBM errors: 0
[08:47:49.154] <TB3> INFO: flawed TBM headers: 0
[08:47:49.154] <TB3> INFO: flawed TBM trailers: 0
[08:47:49.154] <TB3> INFO: event ID mismatches: 0
[08:47:49.154] <TB3> INFO: ROC errors: 0
[08:47:49.154] <TB3> INFO: missing ROC header(s): 0
[08:47:49.154] <TB3> INFO: misplaced readback start: 0
[08:47:49.154] <TB3> INFO: Pixel decoding errors: 0
[08:47:49.154] <TB3> INFO: pixel data incomplete: 0
[08:47:49.154] <TB3> INFO: pixel address: 0
[08:47:49.154] <TB3> INFO: pulse height fill bit: 0
[08:47:49.154] <TB3> INFO: buffer corruption: 0
[08:47:49.161] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C15.dat
[08:47:49.219] <TB3> INFO: ######################################################################
[08:47:49.219] <TB3> INFO: PixTestReadback::doTest()
[08:47:49.219] <TB3> INFO: ######################################################################
[08:47:49.219] <TB3> INFO: ----------------------------------------------------------------------
[08:47:49.219] <TB3> INFO: PixTestReadback::CalibrateVd()
[08:47:49.219] <TB3> INFO: ----------------------------------------------------------------------
[08:47:58.706] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C0.dat
[08:47:58.707] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C1.dat
[08:47:58.707] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C2.dat
[08:47:58.707] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C3.dat
[08:47:58.707] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C4.dat
[08:47:58.707] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C5.dat
[08:47:58.707] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C6.dat
[08:47:58.707] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C7.dat
[08:47:58.707] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C8.dat
[08:47:58.707] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C9.dat
[08:47:58.707] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C10.dat
[08:47:58.707] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C11.dat
[08:47:58.707] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C12.dat
[08:47:58.707] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C13.dat
[08:47:58.708] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C14.dat
[08:47:58.708] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C15.dat
[08:47:58.734] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[08:47:58.734] <TB3> INFO: ----------------------------------------------------------------------
[08:47:58.734] <TB3> INFO: PixTestReadback::CalibrateVa()
[08:47:58.734] <TB3> INFO: ----------------------------------------------------------------------
[08:48:09.115] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C0.dat
[08:48:09.117] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C1.dat
[08:48:09.117] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C2.dat
[08:48:09.117] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C3.dat
[08:48:09.117] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C4.dat
[08:48:09.117] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C5.dat
[08:48:09.117] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C6.dat
[08:48:09.118] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C7.dat
[08:48:09.118] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C8.dat
[08:48:09.118] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C9.dat
[08:48:09.118] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C10.dat
[08:48:09.118] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C11.dat
[08:48:09.118] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C12.dat
[08:48:09.118] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C13.dat
[08:48:09.118] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C14.dat
[08:48:09.118] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C15.dat
[08:48:09.144] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[08:48:09.144] <TB3> INFO: ----------------------------------------------------------------------
[08:48:09.144] <TB3> INFO: PixTestReadback::readbackVbg()
[08:48:09.144] <TB3> INFO: ----------------------------------------------------------------------
[08:48:16.441] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[08:48:16.441] <TB3> INFO: ----------------------------------------------------------------------
[08:48:16.441] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[08:48:16.441] <TB3> INFO: ----------------------------------------------------------------------
[08:48:16.441] <TB3> INFO: Vbg will be calibrated using Vd calibration
[08:48:16.441] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 154.3calibrated Vbg = 1.20242 :::*/*/*/*/
[08:48:16.441] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 156.3calibrated Vbg = 1.20139 :::*/*/*/*/
[08:48:16.441] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 150.2calibrated Vbg = 1.19604 :::*/*/*/*/
[08:48:16.441] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 163.3calibrated Vbg = 1.20857 :::*/*/*/*/
[08:48:16.441] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 158calibrated Vbg = 1.2081 :::*/*/*/*/
[08:48:16.441] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 149.7calibrated Vbg = 1.21174 :::*/*/*/*/
[08:48:16.441] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 140.3calibrated Vbg = 1.21981 :::*/*/*/*/
[08:48:16.441] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 153.7calibrated Vbg = 1.21602 :::*/*/*/*/
[08:48:16.441] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 148.2calibrated Vbg = 1.2147 :::*/*/*/*/
[08:48:16.441] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 142.8calibrated Vbg = 1.21877 :::*/*/*/*/
[08:48:16.441] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 149calibrated Vbg = 1.21526 :::*/*/*/*/
[08:48:16.441] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 149.8calibrated Vbg = 1.2057 :::*/*/*/*/
[08:48:16.441] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 148.2calibrated Vbg = 1.19372 :::*/*/*/*/
[08:48:16.441] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 144.8calibrated Vbg = 1.19399 :::*/*/*/*/
[08:48:16.441] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 156.9calibrated Vbg = 1.20402 :::*/*/*/*/
[08:48:16.441] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 147.4calibrated Vbg = 1.20241 :::*/*/*/*/
[08:48:16.443] <TB3> INFO: ----------------------------------------------------------------------
[08:48:16.443] <TB3> INFO: PixTestReadback::CalibrateIa()
[08:48:16.443] <TB3> INFO: ----------------------------------------------------------------------
[08:50:51.795] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C0.dat
[08:50:51.795] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C1.dat
[08:50:51.795] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C2.dat
[08:50:51.795] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C3.dat
[08:50:51.795] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C4.dat
[08:50:51.795] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C5.dat
[08:50:51.795] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C6.dat
[08:50:51.796] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C7.dat
[08:50:51.796] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C8.dat
[08:50:51.796] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C9.dat
[08:50:51.796] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C10.dat
[08:50:51.796] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C11.dat
[08:50:51.796] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C12.dat
[08:50:51.796] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C13.dat
[08:50:51.796] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C14.dat
[08:50:51.796] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2252_FullQualification_2016-06-09_09h17m_1465456637//000_FulltestPxar_m20//readbackCal_C15.dat
[08:50:51.825] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[08:50:51.828] <TB3> INFO: PixTestReadback::doTest() done
[08:50:51.828] <TB3> INFO: Decoding statistics:
[08:50:51.828] <TB3> INFO: General information:
[08:50:51.828] <TB3> INFO: 16bit words read: 1024
[08:50:51.828] <TB3> INFO: valid events total: 128
[08:50:51.828] <TB3> INFO: empty events: 128
[08:50:51.828] <TB3> INFO: valid events with pixels: 0
[08:50:51.828] <TB3> INFO: valid pixel hits: 0
[08:50:51.828] <TB3> INFO: Event errors: 0
[08:50:51.828] <TB3> INFO: start marker: 0
[08:50:51.828] <TB3> INFO: stop marker: 0
[08:50:51.828] <TB3> INFO: overflow: 0
[08:50:51.828] <TB3> INFO: invalid 5bit words: 0
[08:50:51.828] <TB3> INFO: invalid XOR eye diagram: 0
[08:50:51.828] <TB3> INFO: frame (failed synchr.): 0
[08:50:51.828] <TB3> INFO: idle data (no TBM trl): 0
[08:50:51.828] <TB3> INFO: no data (only TBM hdr): 0
[08:50:51.828] <TB3> INFO: TBM errors: 0
[08:50:51.828] <TB3> INFO: flawed TBM headers: 0
[08:50:51.828] <TB3> INFO: flawed TBM trailers: 0
[08:50:51.828] <TB3> INFO: event ID mismatches: 0
[08:50:51.828] <TB3> INFO: ROC errors: 0
[08:50:51.828] <TB3> INFO: missing ROC header(s): 0
[08:50:51.828] <TB3> INFO: misplaced readback start: 0
[08:50:51.828] <TB3> INFO: Pixel decoding errors: 0
[08:50:51.828] <TB3> INFO: pixel data incomplete: 0
[08:50:51.828] <TB3> INFO: pixel address: 0
[08:50:51.828] <TB3> INFO: pulse height fill bit: 0
[08:50:51.828] <TB3> INFO: buffer corruption: 0
[08:50:51.857] <TB3> INFO: Decoding statistics:
[08:50:51.857] <TB3> INFO: General information:
[08:50:51.857] <TB3> INFO: 16bit words read: 2755336
[08:50:51.857] <TB3> INFO: valid events total: 176768
[08:50:51.857] <TB3> INFO: empty events: 7870
[08:50:51.857] <TB3> INFO: valid events with pixels: 168898
[08:50:51.857] <TB3> INFO: valid pixel hits: 668098
[08:50:51.857] <TB3> INFO: Event errors: 0
[08:50:51.857] <TB3> INFO: start marker: 0
[08:50:51.857] <TB3> INFO: stop marker: 0
[08:50:51.857] <TB3> INFO: overflow: 0
[08:50:51.857] <TB3> INFO: invalid 5bit words: 0
[08:50:51.857] <TB3> INFO: invalid XOR eye diagram: 0
[08:50:51.857] <TB3> INFO: frame (failed synchr.): 0
[08:50:51.857] <TB3> INFO: idle data (no TBM trl): 0
[08:50:51.857] <TB3> INFO: no data (only TBM hdr): 0
[08:50:51.857] <TB3> INFO: TBM errors: 0
[08:50:51.857] <TB3> INFO: flawed TBM headers: 0
[08:50:51.857] <TB3> INFO: flawed TBM trailers: 0
[08:50:51.857] <TB3> INFO: event ID mismatches: 0
[08:50:51.857] <TB3> INFO: ROC errors: 0
[08:50:51.857] <TB3> INFO: missing ROC header(s): 0
[08:50:51.857] <TB3> INFO: misplaced readback start: 0
[08:50:51.857] <TB3> INFO: Pixel decoding errors: 0
[08:50:51.857] <TB3> INFO: pixel data incomplete: 0
[08:50:51.857] <TB3> INFO: pixel address: 0
[08:50:51.857] <TB3> INFO: pulse height fill bit: 0
[08:50:51.857] <TB3> INFO: buffer corruption: 0
[08:50:51.857] <TB3> INFO: enter test to run
[08:50:51.857] <TB3> INFO: test: exit no parameter change
[08:50:52.027] <TB3> QUIET: Connection to board 170 closed.
[08:50:52.106] <TB3> INFO: pXar: this is the end, my friend