[08:14:10.416] <TB1> INFO: *** Welcome to pxar ***
[08:14:10.416] <TB1> INFO: *** Today: 2016/10/03
[08:14:10.519] <TB1> INFO: *** Version: 0b00-dirty
[08:14:10.519] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C15.dat
[08:14:10.520] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//tbmParameters_C0b.dat
[08:14:10.520] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//defaultMaskFile.dat
[08:14:10.520] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters_C15.dat
[08:14:10.609] <TB1> INFO: clk: 4
[08:14:10.609] <TB1> INFO: ctr: 4
[08:14:10.609] <TB1> INFO: sda: 19
[08:14:10.609] <TB1> INFO: tin: 9
[08:14:10.609] <TB1> INFO: level: 15
[08:14:10.609] <TB1> INFO: triggerdelay: 0
[08:14:10.609] <TB1> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[08:14:10.609] <TB1> INFO: Log level: INFO
[08:14:10.618] <TB1> INFO: Found DTB DTB_WXBYFL
[08:14:10.628] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[08:14:10.630] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[08:14:10.631] <TB1> INFO: RPC call hashes of host and DTB match: 486171790
[08:14:12.137] <TB1> INFO: DUT info:
[08:14:12.137] <TB1> INFO: The DUT currently contains the following objects:
[08:14:12.137] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[08:14:12.137] <TB1> INFO: TBM Core alpha (0): 7 registers set
[08:14:12.137] <TB1> INFO: TBM Core beta (1): 7 registers set
[08:14:12.137] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:14:12.137] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:12.137] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:12.137] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:12.137] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:12.137] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:12.137] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:12.137] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:12.137] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:12.137] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:12.137] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:12.137] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:12.137] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:12.137] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:12.137] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:12.137] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:12.137] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:12.538] <TB1> INFO: enter 'restricted' command line mode
[08:14:12.538] <TB1> INFO: enter test to run
[08:14:12.538] <TB1> INFO: test: pretest no parameter change
[08:14:12.538] <TB1> INFO: running: pretest
[08:14:12.544] <TB1> INFO: ----------------------------------------------------------------------
[08:14:12.544] <TB1> INFO: PixTestPretest::programROC()
[08:14:12.544] <TB1> INFO: ----------------------------------------------------------------------
[08:14:30.559] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[08:14:30.559] <TB1> INFO: IA differences per ROC: 18.5 19.3 19.3 17.7 20.1 20.9 19.3 21.7 19.3 19.3 19.3 16.9 17.7 17.7 19.3 16.9
[08:14:30.645] <TB1> INFO: enter test to run
[08:14:30.645] <TB1> INFO: test: pretest no parameter change
[08:14:30.645] <TB1> INFO: running: pretest
[08:14:30.646] <TB1> INFO: ----------------------------------------------------------------------
[08:14:30.646] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[08:14:30.646] <TB1> INFO: ----------------------------------------------------------------------
[08:14:38.636] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 383.5 mA = 23.9688 mA/ROC
[08:14:38.636] <TB1> INFO: i(loss) [mA/ROC]: 20.1 19.3 20.1 19.3 19.3 19.3 19.3 19.3 19.3 18.5 18.5 18.5 19.3 19.3 18.5 18.5
[08:14:38.666] <TB1> INFO: enter test to run
[08:14:38.666] <TB1> INFO: test: pretest no parameter change
[08:14:38.666] <TB1> INFO: running: pretest
[08:14:38.666] <TB1> INFO: ----------------------------------------------------------------------
[08:14:38.666] <TB1> INFO: PixTestPretest::findTiming()
[08:14:38.666] <TB1> INFO: ----------------------------------------------------------------------
[08:14:38.666] <TB1> INFO: PixTestCmd::init()

[08:16:09.663] <TB1> INFO: TBM phases: 160MHz: 0, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[08:16:09.663] <TB1> INFO: (success/tries = 100/100), width = 4
[08:16:09.664] <TB1> INFO: enter test to run
[08:16:09.664] <TB1> INFO: test: pretest no parameter change
[08:16:09.664] <TB1> INFO: running: pretest
[08:16:09.665] <TB1> INFO: ----------------------------------------------------------------------
[08:16:09.665] <TB1> INFO: PixTestPretest::findWorkingPixel()
[08:16:09.665] <TB1> INFO: ----------------------------------------------------------------------
[08:16:09.755] <TB1> INFO: Expecting 231680 events.


[08:16:18.855] <TB1> INFO: 231680 events read in total (8544ms).
[08:16:18.861] <TB1> INFO: Test took 9193ms.
[08:16:19.064] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[08:16:19.125] <TB1> INFO: enter test to run
[08:16:19.125] <TB1> INFO: test: pretest no parameter change
[08:16:19.125] <TB1> INFO: running: pretest
[08:16:19.127] <TB1> INFO: ----------------------------------------------------------------------
[08:16:19.127] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[08:16:19.127] <TB1> INFO: ----------------------------------------------------------------------
[08:16:19.218] <TB1> INFO: Expecting 231680 events.
[08:16:28.362] <TB1> INFO: 231680 events read in total (8588ms).
[08:16:28.367] <TB1> INFO: Test took 9237ms.
[08:16:28.584] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[08:16:28.584] <TB1> INFO: CalDel: 161 149 152 145 129 144 141 142 141 158 139 168 139 145 139 140
[08:16:28.584] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[08:16:28.616] <TB1> INFO: enter test to run
[08:16:28.616] <TB1> INFO: test: pretest no parameter change
[08:16:28.616] <TB1> INFO: running: pretest
[08:16:28.617] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C0.dat
[08:16:28.617] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C1.dat
[08:16:28.617] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C2.dat
[08:16:28.617] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C3.dat
[08:16:28.617] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C4.dat
[08:16:28.617] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C5.dat
[08:16:28.617] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C6.dat
[08:16:28.617] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C7.dat
[08:16:28.617] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C8.dat
[08:16:28.618] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C9.dat
[08:16:28.618] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C10.dat
[08:16:28.618] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C11.dat
[08:16:28.618] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C12.dat
[08:16:28.618] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C13.dat
[08:16:28.618] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C14.dat
[08:16:28.618] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C15.dat
[08:16:28.619] <TB1> INFO: enter test to run
[08:16:28.619] <TB1> INFO: test: fulltest no parameter change
[08:16:28.619] <TB1> INFO: running: fulltest
[08:16:28.619] <TB1> INFO: ######################################################################
[08:16:28.619] <TB1> INFO: PixTestFullTest::doTest()
[08:16:28.619] <TB1> INFO: ######################################################################
[08:16:28.620] <TB1> INFO: ######################################################################
[08:16:28.620] <TB1> INFO: PixTestAlive::doTest()
[08:16:28.620] <TB1> INFO: ######################################################################
[08:16:28.621] <TB1> INFO: ----------------------------------------------------------------------
[08:16:28.621] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:16:28.621] <TB1> INFO: ----------------------------------------------------------------------
[08:16:28.904] <TB1> INFO: Expecting 41600 events.
[08:16:32.332] <TB1> INFO: 41600 events read in total (2872ms).
[08:16:32.333] <TB1> INFO: Test took 3710ms.
[08:16:32.542] <TB1> INFO: PixTestAlive::aliveTest() done
[08:16:32.542] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 4 0 0
[08:16:32.543] <TB1> INFO: ----------------------------------------------------------------------
[08:16:32.543] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:16:32.543] <TB1> INFO: ----------------------------------------------------------------------
[08:16:32.831] <TB1> INFO: Expecting 41600 events.
[08:16:35.549] <TB1> INFO: 41600 events read in total (2162ms).
[08:16:35.549] <TB1> INFO: Test took 3004ms.
[08:16:35.549] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:16:35.790] <TB1> INFO: PixTestAlive::maskTest() done
[08:16:35.790] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:16:35.791] <TB1> INFO: ----------------------------------------------------------------------
[08:16:35.791] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:16:35.791] <TB1> INFO: ----------------------------------------------------------------------
[08:16:36.053] <TB1> INFO: Expecting 41600 events.
[08:16:39.435] <TB1> INFO: 41600 events read in total (2826ms).
[08:16:39.435] <TB1> INFO: Test took 3642ms.
[08:16:39.649] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[08:16:39.649] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:16:39.649] <TB1> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[08:16:39.650] <TB1> INFO: Decoding statistics:
[08:16:39.650] <TB1> INFO: General information:
[08:16:39.650] <TB1> INFO: 16bit words read: 0
[08:16:39.650] <TB1> INFO: valid events total: 0
[08:16:39.650] <TB1> INFO: empty events: 0
[08:16:39.650] <TB1> INFO: valid events with pixels: 0
[08:16:39.650] <TB1> INFO: valid pixel hits: 0
[08:16:39.650] <TB1> INFO: Event errors: 0
[08:16:39.650] <TB1> INFO: start marker: 0
[08:16:39.650] <TB1> INFO: stop marker: 0
[08:16:39.650] <TB1> INFO: overflow: 0
[08:16:39.650] <TB1> INFO: invalid 5bit words: 0
[08:16:39.650] <TB1> INFO: invalid XOR eye diagram: 0
[08:16:39.650] <TB1> INFO: frame (failed synchr.): 0
[08:16:39.650] <TB1> INFO: idle data (no TBM trl): 0
[08:16:39.650] <TB1> INFO: no data (only TBM hdr): 0
[08:16:39.650] <TB1> INFO: TBM errors: 0
[08:16:39.650] <TB1> INFO: flawed TBM headers: 0
[08:16:39.650] <TB1> INFO: flawed TBM trailers: 0
[08:16:39.650] <TB1> INFO: event ID mismatches: 0
[08:16:39.650] <TB1> INFO: ROC errors: 0
[08:16:39.650] <TB1> INFO: missing ROC header(s): 0
[08:16:39.650] <TB1> INFO: misplaced readback start: 0
[08:16:39.650] <TB1> INFO: Pixel decoding errors: 0
[08:16:39.650] <TB1> INFO: pixel data incomplete: 0
[08:16:39.650] <TB1> INFO: pixel address: 0
[08:16:39.650] <TB1> INFO: pulse height fill bit: 0
[08:16:39.650] <TB1> INFO: buffer corruption: 0
[08:16:39.659] <TB1> INFO: ######################################################################
[08:16:39.659] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[08:16:39.659] <TB1> INFO: ######################################################################
[08:16:39.661] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[08:16:39.762] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[08:16:39.762] <TB1> INFO: run 1 of 1
[08:16:40.023] <TB1> INFO: Expecting 3120000 events.
[08:17:18.871] <TB1> INFO: 869810 events read in total (38292ms).
[08:17:55.774] <TB1> INFO: 1729930 events read in total (75196ms).
[08:18:33.600] <TB1> INFO: 2601385 events read in total (113021ms).
[08:18:57.390] <TB1> INFO: 3120000 events read in total (136811ms).
[08:18:57.466] <TB1> INFO: Test took 137703ms.
[08:19:24.232] <TB1> INFO: PixTestBBMap::doTest() done, duration: 164 seconds
[08:19:24.232] <TB1> INFO: number of dead bumps (per ROC): 0 0 1 1 2 1 0 0 4 0 1 0 3 2 0 2
[08:19:24.232] <TB1> INFO: separation cut (per ROC): 86 73 99 87 87 98 66 95 77 76 76 72 86 74 75 93
[08:19:24.232] <TB1> INFO: Decoding statistics:
[08:19:24.232] <TB1> INFO: General information:
[08:19:24.232] <TB1> INFO: 16bit words read: 0
[08:19:24.232] <TB1> INFO: valid events total: 0
[08:19:24.232] <TB1> INFO: empty events: 0
[08:19:24.232] <TB1> INFO: valid events with pixels: 0
[08:19:24.232] <TB1> INFO: valid pixel hits: 0
[08:19:24.232] <TB1> INFO: Event errors: 0
[08:19:24.232] <TB1> INFO: start marker: 0
[08:19:24.232] <TB1> INFO: stop marker: 0
[08:19:24.232] <TB1> INFO: overflow: 0
[08:19:24.232] <TB1> INFO: invalid 5bit words: 0
[08:19:24.232] <TB1> INFO: invalid XOR eye diagram: 0
[08:19:24.232] <TB1> INFO: frame (failed synchr.): 0
[08:19:24.232] <TB1> INFO: idle data (no TBM trl): 0
[08:19:24.232] <TB1> INFO: no data (only TBM hdr): 0
[08:19:24.233] <TB1> INFO: TBM errors: 0
[08:19:24.233] <TB1> INFO: flawed TBM headers: 0
[08:19:24.233] <TB1> INFO: flawed TBM trailers: 0
[08:19:24.233] <TB1> INFO: event ID mismatches: 0
[08:19:24.233] <TB1> INFO: ROC errors: 0
[08:19:24.233] <TB1> INFO: missing ROC header(s): 0
[08:19:24.233] <TB1> INFO: misplaced readback start: 0
[08:19:24.233] <TB1> INFO: Pixel decoding errors: 0
[08:19:24.233] <TB1> INFO: pixel data incomplete: 0
[08:19:24.233] <TB1> INFO: pixel address: 0
[08:19:24.233] <TB1> INFO: pulse height fill bit: 0
[08:19:24.233] <TB1> INFO: buffer corruption: 0
[08:19:24.350] <TB1> INFO: ######################################################################
[08:19:24.350] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:19:24.350] <TB1> INFO: ######################################################################
[08:19:24.350] <TB1> INFO: ----------------------------------------------------------------------
[08:19:24.350] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:19:24.350] <TB1> INFO: ----------------------------------------------------------------------
[08:19:24.350] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[08:19:24.360] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[08:19:24.360] <TB1> INFO: run 1 of 1
[08:19:24.692] <TB1> INFO: Expecting 36608000 events.
[08:19:56.861] <TB1> INFO: 806700 events read in total (31606ms).
[08:20:28.246] <TB1> INFO: 1599000 events read in total (62991ms).
[08:20:57.353] <TB1> INFO: 2392200 events read in total (92098ms).
[08:21:26.979] <TB1> INFO: 3184250 events read in total (121724ms).
[08:21:57.229] <TB1> INFO: 3975850 events read in total (151974ms).
[08:22:26.062] <TB1> INFO: 4766800 events read in total (180807ms).
[08:22:56.453] <TB1> INFO: 5557350 events read in total (211198ms).
[08:23:25.823] <TB1> INFO: 6348050 events read in total (240568ms).
[08:23:54.866] <TB1> INFO: 7136050 events read in total (269611ms).
[08:24:24.323] <TB1> INFO: 7926650 events read in total (299068ms).
[08:24:53.564] <TB1> INFO: 8716700 events read in total (328309ms).
[08:25:23.156] <TB1> INFO: 9503750 events read in total (357901ms).
[08:25:52.394] <TB1> INFO: 10292250 events read in total (387139ms).
[08:26:21.389] <TB1> INFO: 11080900 events read in total (416134ms).
[08:26:50.806] <TB1> INFO: 11868050 events read in total (445551ms).
[08:27:20.431] <TB1> INFO: 12656000 events read in total (475176ms).
[08:27:50.560] <TB1> INFO: 13445000 events read in total (505305ms).
[08:28:19.576] <TB1> INFO: 14231750 events read in total (534321ms).
[08:28:49.640] <TB1> INFO: 15018450 events read in total (564385ms).
[08:29:19.152] <TB1> INFO: 15803650 events read in total (593897ms).
[08:29:48.882] <TB1> INFO: 16590000 events read in total (623627ms).
[08:30:18.319] <TB1> INFO: 17375850 events read in total (653064ms).
[08:30:47.965] <TB1> INFO: 18159550 events read in total (682710ms).
[08:31:16.578] <TB1> INFO: 18941300 events read in total (711323ms).
[08:31:45.201] <TB1> INFO: 19720600 events read in total (739946ms).
[08:32:14.237] <TB1> INFO: 20501700 events read in total (768982ms).
[08:32:42.903] <TB1> INFO: 21281150 events read in total (797648ms).
[08:33:11.579] <TB1> INFO: 22060200 events read in total (826324ms).
[08:33:40.702] <TB1> INFO: 22838950 events read in total (855447ms).
[08:34:08.841] <TB1> INFO: 23617150 events read in total (883586ms).
[08:34:37.833] <TB1> INFO: 24395800 events read in total (912578ms).
[08:35:06.641] <TB1> INFO: 25172550 events read in total (941386ms).
[08:35:35.294] <TB1> INFO: 25950100 events read in total (970039ms).
[08:36:04.835] <TB1> INFO: 26727750 events read in total (999580ms).
[08:36:33.634] <TB1> INFO: 27504900 events read in total (1028379ms).
[08:37:02.963] <TB1> INFO: 28281850 events read in total (1057708ms).
[08:37:32.822] <TB1> INFO: 29059050 events read in total (1087567ms).
[08:38:02.268] <TB1> INFO: 29835550 events read in total (1117013ms).
[08:38:33.371] <TB1> INFO: 30611400 events read in total (1148116ms).
[08:39:03.843] <TB1> INFO: 31386100 events read in total (1178588ms).
[08:39:35.095] <TB1> INFO: 32162550 events read in total (1209840ms).
[08:40:06.527] <TB1> INFO: 32940900 events read in total (1241272ms).
[08:40:35.403] <TB1> INFO: 33715950 events read in total (1270148ms).
[08:41:04.774] <TB1> INFO: 34492350 events read in total (1299519ms).
[08:41:33.656] <TB1> INFO: 35268200 events read in total (1328401ms).
[08:42:03.759] <TB1> INFO: 36048850 events read in total (1358504ms).
[08:42:24.580] <TB1> INFO: 36608000 events read in total (1379325ms).
[08:42:24.651] <TB1> INFO: Test took 1380290ms.
[08:42:25.191] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[08:42:27.221] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[08:42:29.227] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[08:42:31.379] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[08:42:33.538] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[08:42:35.622] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[08:42:37.744] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[08:42:39.894] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[08:42:41.980] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[08:42:44.345] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[08:42:46.982] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[08:42:49.538] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[08:42:51.979] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[08:42:54.624] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[08:42:57.179] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[08:42:59.645] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[08:43:01.911] <TB1> INFO: PixTestScurves::scurves() done
[08:43:01.911] <TB1> INFO: Vcal mean: 82.06 70.62 99.32 90.45 85.27 92.34 71.58 83.95 72.33 81.73 77.13 79.79 80.47 80.83 79.71 86.74
[08:43:01.911] <TB1> INFO: Vcal RMS: 4.37 4.45 5.15 4.62 4.94 5.00 4.44 4.70 4.75 5.14 4.54 3.97 4.83 5.10 4.07 5.03
[08:43:01.911] <TB1> INFO: PixTestScurves::fullTest() done, duration: 1417 seconds
[08:43:01.911] <TB1> INFO: Decoding statistics:
[08:43:01.911] <TB1> INFO: General information:
[08:43:01.911] <TB1> INFO: 16bit words read: 0
[08:43:01.911] <TB1> INFO: valid events total: 0
[08:43:01.911] <TB1> INFO: empty events: 0
[08:43:01.911] <TB1> INFO: valid events with pixels: 0
[08:43:01.911] <TB1> INFO: valid pixel hits: 0
[08:43:01.911] <TB1> INFO: Event errors: 0
[08:43:01.911] <TB1> INFO: start marker: 0
[08:43:01.911] <TB1> INFO: stop marker: 0
[08:43:01.911] <TB1> INFO: overflow: 0
[08:43:01.911] <TB1> INFO: invalid 5bit words: 0
[08:43:01.911] <TB1> INFO: invalid XOR eye diagram: 0
[08:43:01.911] <TB1> INFO: frame (failed synchr.): 0
[08:43:01.911] <TB1> INFO: idle data (no TBM trl): 0
[08:43:01.911] <TB1> INFO: no data (only TBM hdr): 0
[08:43:01.911] <TB1> INFO: TBM errors: 0
[08:43:01.911] <TB1> INFO: flawed TBM headers: 0
[08:43:01.911] <TB1> INFO: flawed TBM trailers: 0
[08:43:01.911] <TB1> INFO: event ID mismatches: 0
[08:43:01.911] <TB1> INFO: ROC errors: 0
[08:43:01.911] <TB1> INFO: missing ROC header(s): 0
[08:43:01.911] <TB1> INFO: misplaced readback start: 0
[08:43:01.911] <TB1> INFO: Pixel decoding errors: 0
[08:43:01.911] <TB1> INFO: pixel data incomplete: 0
[08:43:01.911] <TB1> INFO: pixel address: 0
[08:43:01.911] <TB1> INFO: pulse height fill bit: 0
[08:43:01.911] <TB1> INFO: buffer corruption: 0
[08:43:02.011] <TB1> INFO: ######################################################################
[08:43:02.011] <TB1> INFO: PixTestTrim::doTest()
[08:43:02.011] <TB1> INFO: ######################################################################
[08:43:02.012] <TB1> INFO: ----------------------------------------------------------------------
[08:43:02.012] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[08:43:02.012] <TB1> INFO: ----------------------------------------------------------------------
[08:43:02.136] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[08:43:02.136] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[08:43:02.147] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[08:43:02.147] <TB1> INFO: run 1 of 1
[08:43:02.489] <TB1> INFO: Expecting 6281600 events.
[08:43:40.152] <TB1> INFO: 1097810 events read in total (37091ms).
[08:44:16.267] <TB1> INFO: 2188690 events read in total (73206ms).
[08:44:53.662] <TB1> INFO: 3272500 events read in total (110601ms).
[08:45:30.181] <TB1> INFO: 4364820 events read in total (147120ms).
[08:46:06.808] <TB1> INFO: 5459640 events read in total (183747ms).
[08:46:34.705] <TB1> INFO: 6281600 events read in total (211644ms).
[08:46:34.760] <TB1> INFO: Test took 212613ms.
[08:46:56.831] <TB1> INFO: ROC 0 VthrComp = 87
[08:46:56.831] <TB1> INFO: ROC 1 VthrComp = 78
[08:46:56.831] <TB1> INFO: ROC 2 VthrComp = 104
[08:46:56.831] <TB1> INFO: ROC 3 VthrComp = 98
[08:46:56.831] <TB1> INFO: ROC 4 VthrComp = 92
[08:46:56.831] <TB1> INFO: ROC 5 VthrComp = 99
[08:46:56.831] <TB1> INFO: ROC 6 VthrComp = 79
[08:46:56.831] <TB1> INFO: ROC 7 VthrComp = 91
[08:46:56.832] <TB1> INFO: ROC 8 VthrComp = 79
[08:46:56.833] <TB1> INFO: ROC 9 VthrComp = 87
[08:46:56.833] <TB1> INFO: ROC 10 VthrComp = 86
[08:46:56.833] <TB1> INFO: ROC 11 VthrComp = 85
[08:46:56.833] <TB1> INFO: ROC 12 VthrComp = 86
[08:46:56.833] <TB1> INFO: ROC 13 VthrComp = 85
[08:46:56.834] <TB1> INFO: ROC 14 VthrComp = 87
[08:46:56.834] <TB1> INFO: ROC 15 VthrComp = 93
[08:46:56.834] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[08:46:56.834] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[08:46:56.842] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[08:46:56.842] <TB1> INFO: run 1 of 1
[08:46:57.106] <TB1> INFO: Expecting 6281600 events.
[08:47:32.652] <TB1> INFO: 757570 events read in total (34989ms).
[08:48:04.054] <TB1> INFO: 1514070 events read in total (66391ms).
[08:48:35.587] <TB1> INFO: 2271590 events read in total (97924ms).
[08:49:07.374] <TB1> INFO: 3029960 events read in total (129711ms).
[08:49:38.666] <TB1> INFO: 3782540 events read in total (161003ms).
[08:50:09.369] <TB1> INFO: 4531870 events read in total (191706ms).
[08:50:41.073] <TB1> INFO: 5278580 events read in total (223410ms).
[08:51:12.522] <TB1> INFO: 6024970 events read in total (254859ms).
[08:51:23.622] <TB1> INFO: 6281600 events read in total (265959ms).
[08:51:23.678] <TB1> INFO: Test took 266835ms.
[08:51:52.377] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 56.3082 for pixel 29/79 mean/min/max = 44.3232/32.2638/56.3826
[08:51:52.377] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 56.22 for pixel 11/79 mean/min/max = 45.3047/34.276/56.3335
[08:51:52.377] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 59.6593 for pixel 26/74 mean/min/max = 46.7448/33.6822/59.8073
[08:51:52.378] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 56.5182 for pixel 51/34 mean/min/max = 44.2765/31.9836/56.5694
[08:51:52.378] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 57.5565 for pixel 25/50 mean/min/max = 45.2458/32.9179/57.5738
[08:51:52.378] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 57.8322 for pixel 12/4 mean/min/max = 44.7955/31.6529/57.9381
[08:51:52.378] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 57.46 for pixel 0/14 mean/min/max = 45.8294/34.1614/57.4974
[08:51:52.379] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 57.2747 for pixel 0/47 mean/min/max = 45.0202/32.4286/57.6117
[08:51:52.379] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 58.3755 for pixel 3/67 mean/min/max = 46.2909/34.2055/58.3762
[08:51:52.379] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 57.8158 for pixel 8/79 mean/min/max = 44.8352/31.5007/58.1697
[08:51:52.379] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 57.9972 for pixel 0/7 mean/min/max = 44.7196/31.3604/58.0788
[08:51:52.380] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 56.1696 for pixel 1/20 mean/min/max = 44.4526/32.4301/56.475
[08:51:52.380] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 59.8957 for pixel 0/30 mean/min/max = 45.7622/31.5583/59.9661
[08:51:52.380] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 58.4517 for pixel 0/40 mean/min/max = 45.4527/32.3158/58.5897
[08:51:52.380] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 56.2619 for pixel 14/40 mean/min/max = 44.08/31.6245/56.5356
[08:51:52.381] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 58.0386 for pixel 3/2 mean/min/max = 45.25/32.1146/58.3855
[08:51:52.381] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:51:52.470] <TB1> INFO: Expecting 514560 events.
[08:52:04.114] <TB1> INFO: 514560 events read in total (11088ms).
[08:52:04.124] <TB1> INFO: Expecting 514560 events.
[08:52:15.499] <TB1> INFO: 514560 events read in total (10973ms).
[08:52:15.512] <TB1> INFO: Expecting 514560 events.
[08:52:26.512] <TB1> INFO: 514560 events read in total (10598ms).
[08:52:26.525] <TB1> INFO: Expecting 514560 events.
[08:52:37.236] <TB1> INFO: 514560 events read in total (10310ms).
[08:52:37.250] <TB1> INFO: Expecting 514560 events.
[08:52:48.017] <TB1> INFO: 514560 events read in total (10365ms).
[08:52:48.041] <TB1> INFO: Expecting 514560 events.
[08:52:58.742] <TB1> INFO: 514560 events read in total (10299ms).
[08:52:58.762] <TB1> INFO: Expecting 514560 events.
[08:53:09.645] <TB1> INFO: 514560 events read in total (10481ms).
[08:53:09.667] <TB1> INFO: Expecting 514560 events.
[08:53:20.279] <TB1> INFO: 514560 events read in total (10210ms).
[08:53:20.306] <TB1> INFO: Expecting 514560 events.
[08:53:31.149] <TB1> INFO: 514560 events read in total (10441ms).
[08:53:31.193] <TB1> INFO: Expecting 514560 events.
[08:53:42.109] <TB1> INFO: 514560 events read in total (10514ms).
[08:53:42.155] <TB1> INFO: Expecting 514560 events.
[08:53:53.071] <TB1> INFO: 514560 events read in total (10514ms).
[08:53:53.104] <TB1> INFO: Expecting 514560 events.
[08:54:03.978] <TB1> INFO: 514560 events read in total (10472ms).
[08:54:04.013] <TB1> INFO: Expecting 514560 events.
[08:54:14.833] <TB1> INFO: 514560 events read in total (10418ms).
[08:54:14.871] <TB1> INFO: Expecting 514560 events.
[08:54:25.534] <TB1> INFO: 514560 events read in total (10261ms).
[08:54:25.574] <TB1> INFO: Expecting 514560 events.
[08:54:36.338] <TB1> INFO: 514560 events read in total (10362ms).
[08:54:36.382] <TB1> INFO: Expecting 514560 events.
[08:54:47.301] <TB1> INFO: 514560 events read in total (10517ms).
[08:54:47.375] <TB1> INFO: Test took 174994ms.
[08:54:48.391] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[08:54:48.399] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[08:54:48.399] <TB1> INFO: run 1 of 1
[08:54:48.661] <TB1> INFO: Expecting 6281600 events.
[08:55:19.976] <TB1> INFO: 746360 events read in total (30759ms).
[08:55:51.446] <TB1> INFO: 1492070 events read in total (62229ms).
[08:56:22.865] <TB1> INFO: 2238600 events read in total (93648ms).
[08:56:54.031] <TB1> INFO: 2985520 events read in total (124814ms).
[08:57:25.131] <TB1> INFO: 3726990 events read in total (155914ms).
[08:57:56.768] <TB1> INFO: 4463890 events read in total (187551ms).
[08:58:27.563] <TB1> INFO: 5198050 events read in total (218346ms).
[08:58:59.260] <TB1> INFO: 5931260 events read in total (250043ms).
[08:59:14.597] <TB1> INFO: 6281600 events read in total (265380ms).
[08:59:14.655] <TB1> INFO: Test took 266257ms.
[08:59:41.690] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 11.085169 .. 50.854624
[08:59:41.956] <TB1> INFO: Expecting 208000 events.
[08:59:53.242] <TB1> INFO: 208000 events read in total (10730ms).
[08:59:53.243] <TB1> INFO: Test took 11551ms.
[08:59:53.380] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 1 .. 60 (-1/-1) hits flags = 528 (plus default)
[08:59:53.391] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[08:59:53.391] <TB1> INFO: run 1 of 1
[08:59:53.734] <TB1> INFO: Expecting 2496000 events.
[09:00:29.962] <TB1> INFO: 944990 events read in total (35672ms).
[09:01:04.827] <TB1> INFO: 1885990 events read in total (70538ms).
[09:01:28.347] <TB1> INFO: 2496000 events read in total (94057ms).
[09:01:28.372] <TB1> INFO: Test took 94982ms.
[09:01:44.290] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 21.922812 .. 44.993489
[09:01:44.587] <TB1> INFO: Expecting 208000 events.
[09:01:55.091] <TB1> INFO: 208000 events read in total (9948ms).
[09:01:55.092] <TB1> INFO: Test took 10801ms.
[09:01:55.213] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 11 .. 54 (-1/-1) hits flags = 528 (plus default)
[09:01:55.222] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:01:55.222] <TB1> INFO: run 1 of 1
[09:01:55.510] <TB1> INFO: Expecting 1830400 events.
[09:02:31.752] <TB1> INFO: 929330 events read in total (35686ms).
[09:03:06.276] <TB1> INFO: 1830400 events read in total (70210ms).
[09:03:06.298] <TB1> INFO: Test took 71076ms.
[09:03:20.940] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 22.200208 .. 42.073951
[09:03:21.251] <TB1> INFO: Expecting 208000 events.
[09:03:31.558] <TB1> INFO: 208000 events read in total (9751ms).
[09:03:31.559] <TB1> INFO: Test took 10618ms.
[09:03:31.655] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 12 .. 52 (-1/-1) hits flags = 528 (plus default)
[09:03:31.664] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:03:31.664] <TB1> INFO: run 1 of 1
[09:03:31.924] <TB1> INFO: Expecting 1705600 events.
[09:04:08.821] <TB1> INFO: 940720 events read in total (36341ms).
[09:04:38.344] <TB1> INFO: 1705600 events read in total (65864ms).
[09:04:38.362] <TB1> INFO: Test took 66699ms.
[09:04:51.778] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 24.618959 .. 41.971922
[09:04:52.043] <TB1> INFO: Expecting 208000 events.
[09:05:02.269] <TB1> INFO: 208000 events read in total (9670ms).
[09:05:02.270] <TB1> INFO: Test took 10491ms.
[09:05:02.407] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 14 .. 51 (-1/-1) hits flags = 528 (plus default)
[09:05:02.416] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:05:02.416] <TB1> INFO: run 1 of 1
[09:05:02.729] <TB1> INFO: Expecting 1580800 events.
[09:05:40.380] <TB1> INFO: 937330 events read in total (37095ms).
[09:06:05.775] <TB1> INFO: 1580800 events read in total (62490ms).
[09:06:05.799] <TB1> INFO: Test took 63383ms.
[09:06:19.883] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[09:06:19.883] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:06:19.892] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[09:06:19.892] <TB1> INFO: run 1 of 1
[09:06:20.157] <TB1> INFO: Expecting 1705600 events.
[09:06:55.980] <TB1> INFO: 893750 events read in total (35267ms).
[09:07:27.274] <TB1> INFO: 1705600 events read in total (66561ms).
[09:07:27.294] <TB1> INFO: Test took 67402ms.
[09:07:42.650] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:07:42.651] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:07:42.651] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:07:42.651] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:07:42.651] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:07:42.651] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:07:42.651] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:07:42.651] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:07:42.651] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:07:42.651] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:07:42.652] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:07:42.652] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:07:42.652] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:07:42.652] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:07:42.652] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:07:42.652] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:07:42.690] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C0.dat
[09:07:42.697] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C1.dat
[09:07:42.704] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C2.dat
[09:07:42.710] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C3.dat
[09:07:42.717] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C4.dat
[09:07:42.723] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C5.dat
[09:07:42.730] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C6.dat
[09:07:42.737] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C7.dat
[09:07:42.743] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C8.dat
[09:07:42.751] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C9.dat
[09:07:42.758] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C10.dat
[09:07:42.764] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C11.dat
[09:07:42.772] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C12.dat
[09:07:42.780] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C13.dat
[09:07:42.786] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C14.dat
[09:07:42.794] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C15.dat
[09:07:42.801] <TB1> INFO: PixTestTrim::trimTest() done
[09:07:42.801] <TB1> INFO: vtrim: 100 90 117 97 103 108 93 104 99 90 96 95 95 107 107 100
[09:07:42.801] <TB1> INFO: vthrcomp: 87 78 104 98 92 99 79 91 79 87 86 85 86 85 87 93
[09:07:42.801] <TB1> INFO: vcal mean: 34.96 34.99 34.93 35.11 34.97 35.04 34.98 34.96 34.98 34.94 34.96 34.95 35.00 34.89 34.93 34.97
[09:07:42.801] <TB1> INFO: vcal RMS: 0.80 0.73 0.83 0.80 0.77 0.82 0.75 0.76 0.78 0.80 0.78 0.80 0.83 1.35 0.83 0.80
[09:07:42.801] <TB1> INFO: bits mean: 9.93 8.88 8.96 9.77 9.40 9.63 8.63 9.06 9.07 9.48 9.29 9.78 9.08 9.12 10.28 9.60
[09:07:42.801] <TB1> INFO: bits RMS: 2.52 2.64 2.63 2.65 2.64 2.74 2.69 2.89 2.52 2.82 2.94 2.55 2.89 2.87 2.47 2.65
[09:07:42.894] <TB1> INFO: ----------------------------------------------------------------------
[09:07:42.894] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[09:07:42.894] <TB1> INFO: ----------------------------------------------------------------------
[09:07:42.896] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[09:07:42.905] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[09:07:42.905] <TB1> INFO: run 1 of 1
[09:07:43.174] <TB1> INFO: Expecting 4160000 events.
[09:08:26.436] <TB1> INFO: 902765 events read in total (42706ms).
[09:09:05.599] <TB1> INFO: 1797545 events read in total (81869ms).
[09:09:46.933] <TB1> INFO: 2683565 events read in total (123203ms).
[09:10:24.267] <TB1> INFO: 3564570 events read in total (160537ms).
[09:10:49.545] <TB1> INFO: 4160000 events read in total (185815ms).
[09:10:49.621] <TB1> INFO: Test took 186717ms.
[09:11:23.173] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 158 (-1/-1) hits flags = 528 (plus default)
[09:11:23.181] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[09:11:23.181] <TB1> INFO: run 1 of 1
[09:11:23.447] <TB1> INFO: Expecting 3307200 events.
[09:12:04.559] <TB1> INFO: 951180 events read in total (40556ms).
[09:12:42.530] <TB1> INFO: 1888390 events read in total (78527ms).
[09:13:22.059] <TB1> INFO: 2813440 events read in total (118056ms).
[09:13:42.299] <TB1> INFO: 3307200 events read in total (138296ms).
[09:13:42.375] <TB1> INFO: Test took 139193ms.
[09:14:09.694] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:14:09.703] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[09:14:09.707] <TB1> INFO: run 1 of 1
[09:14:09.970] <TB1> INFO: Expecting 3140800 events.
[09:14:52.641] <TB1> INFO: 972155 events read in total (42115ms).
[09:15:32.476] <TB1> INFO: 1927395 events read in total (81950ms).
[09:16:11.237] <TB1> INFO: 2871005 events read in total (120711ms).
[09:16:22.212] <TB1> INFO: 3140800 events read in total (131686ms).
[09:16:22.267] <TB1> INFO: Test took 132559ms.
[09:16:48.588] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:16:48.597] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[09:16:48.597] <TB1> INFO: run 1 of 1
[09:16:48.864] <TB1> INFO: Expecting 3140800 events.
[09:17:32.440] <TB1> INFO: 970885 events read in total (43020ms).
[09:18:10.923] <TB1> INFO: 1925015 events read in total (81503ms).
[09:18:48.818] <TB1> INFO: 2867325 events read in total (119398ms).
[09:19:00.809] <TB1> INFO: 3140800 events read in total (131389ms).
[09:19:00.859] <TB1> INFO: Test took 132262ms.
[09:19:24.979] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 528 (plus default)
[09:19:24.991] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[09:19:24.991] <TB1> INFO: run 1 of 1
[09:19:25.261] <TB1> INFO: Expecting 3120000 events.
[09:20:08.132] <TB1> INFO: 972865 events read in total (42315ms).
[09:20:46.625] <TB1> INFO: 1928845 events read in total (80808ms).
[09:21:25.324] <TB1> INFO: 2872940 events read in total (119507ms).
[09:21:35.944] <TB1> INFO: 3120000 events read in total (130127ms).
[09:21:35.998] <TB1> INFO: Test took 131008ms.
[09:22:00.591] <TB1> INFO: PixTestTrim::trimBitTest() done
[09:22:00.592] <TB1> INFO: PixTestTrim::doTest() done, duration: 2338 seconds
[09:22:00.592] <TB1> INFO: Decoding statistics:
[09:22:00.592] <TB1> INFO: General information:
[09:22:00.592] <TB1> INFO: 16bit words read: 0
[09:22:00.592] <TB1> INFO: valid events total: 0
[09:22:00.592] <TB1> INFO: empty events: 0
[09:22:00.592] <TB1> INFO: valid events with pixels: 0
[09:22:00.592] <TB1> INFO: valid pixel hits: 0
[09:22:00.592] <TB1> INFO: Event errors: 0
[09:22:00.592] <TB1> INFO: start marker: 0
[09:22:00.592] <TB1> INFO: stop marker: 0
[09:22:00.592] <TB1> INFO: overflow: 0
[09:22:00.592] <TB1> INFO: invalid 5bit words: 0
[09:22:00.592] <TB1> INFO: invalid XOR eye diagram: 0
[09:22:00.592] <TB1> INFO: frame (failed synchr.): 0
[09:22:00.592] <TB1> INFO: idle data (no TBM trl): 0
[09:22:00.592] <TB1> INFO: no data (only TBM hdr): 0
[09:22:00.592] <TB1> INFO: TBM errors: 0
[09:22:00.592] <TB1> INFO: flawed TBM headers: 0
[09:22:00.592] <TB1> INFO: flawed TBM trailers: 0
[09:22:00.592] <TB1> INFO: event ID mismatches: 0
[09:22:00.592] <TB1> INFO: ROC errors: 0
[09:22:00.592] <TB1> INFO: missing ROC header(s): 0
[09:22:00.592] <TB1> INFO: misplaced readback start: 0
[09:22:00.592] <TB1> INFO: Pixel decoding errors: 0
[09:22:00.592] <TB1> INFO: pixel data incomplete: 0
[09:22:00.592] <TB1> INFO: pixel address: 0
[09:22:00.592] <TB1> INFO: pulse height fill bit: 0
[09:22:00.592] <TB1> INFO: buffer corruption: 0
[09:22:01.279] <TB1> INFO: ######################################################################
[09:22:01.279] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[09:22:01.279] <TB1> INFO: ######################################################################
[09:22:01.566] <TB1> INFO: Expecting 41600 events.
[09:22:05.055] <TB1> INFO: 41600 events read in total (2933ms).
[09:22:05.055] <TB1> INFO: Test took 3774ms.
[09:22:05.569] <TB1> INFO: Expecting 41600 events.
[09:22:09.262] <TB1> INFO: 41600 events read in total (3137ms).
[09:22:09.263] <TB1> INFO: Test took 4011ms.
[09:22:09.605] <TB1> INFO: Expecting 41600 events.
[09:22:13.321] <TB1> INFO: 41600 events read in total (3159ms).
[09:22:13.321] <TB1> INFO: Test took 4031ms.
[09:22:13.635] <TB1> INFO: Expecting 2560 events.
[09:22:14.498] <TB1> INFO: 2560 events read in total (302ms).
[09:22:14.498] <TB1> INFO: Test took 1163ms.
[09:22:14.803] <TB1> INFO: Expecting 2560 events.
[09:22:15.659] <TB1> INFO: 2560 events read in total (300ms).
[09:22:15.659] <TB1> INFO: Test took 1160ms.
[09:22:15.964] <TB1> INFO: Expecting 2560 events.
[09:22:16.820] <TB1> INFO: 2560 events read in total (300ms).
[09:22:16.821] <TB1> INFO: Test took 1161ms.
[09:22:17.125] <TB1> INFO: Expecting 2560 events.
[09:22:17.977] <TB1> INFO: 2560 events read in total (296ms).
[09:22:17.978] <TB1> INFO: Test took 1157ms.
[09:22:18.288] <TB1> INFO: Expecting 2560 events.
[09:22:19.145] <TB1> INFO: 2560 events read in total (300ms).
[09:22:19.145] <TB1> INFO: Test took 1167ms.
[09:22:19.450] <TB1> INFO: Expecting 2560 events.
[09:22:20.306] <TB1> INFO: 2560 events read in total (300ms).
[09:22:20.307] <TB1> INFO: Test took 1162ms.
[09:22:20.611] <TB1> INFO: Expecting 2560 events.
[09:22:21.468] <TB1> INFO: 2560 events read in total (300ms).
[09:22:21.468] <TB1> INFO: Test took 1161ms.
[09:22:21.773] <TB1> INFO: Expecting 2560 events.
[09:22:22.630] <TB1> INFO: 2560 events read in total (301ms).
[09:22:22.630] <TB1> INFO: Test took 1161ms.
[09:22:22.934] <TB1> INFO: Expecting 2560 events.
[09:22:23.791] <TB1> INFO: 2560 events read in total (300ms).
[09:22:23.791] <TB1> INFO: Test took 1161ms.
[09:22:24.095] <TB1> INFO: Expecting 2560 events.
[09:22:24.953] <TB1> INFO: 2560 events read in total (301ms).
[09:22:24.953] <TB1> INFO: Test took 1162ms.
[09:22:25.257] <TB1> INFO: Expecting 2560 events.
[09:22:26.114] <TB1> INFO: 2560 events read in total (301ms).
[09:22:26.114] <TB1> INFO: Test took 1161ms.
[09:22:26.419] <TB1> INFO: Expecting 2560 events.
[09:22:27.274] <TB1> INFO: 2560 events read in total (299ms).
[09:22:27.274] <TB1> INFO: Test took 1159ms.
[09:22:27.579] <TB1> INFO: Expecting 2560 events.
[09:22:28.436] <TB1> INFO: 2560 events read in total (300ms).
[09:22:28.436] <TB1> INFO: Test took 1161ms.
[09:22:28.740] <TB1> INFO: Expecting 2560 events.
[09:22:29.597] <TB1> INFO: 2560 events read in total (301ms).
[09:22:29.597] <TB1> INFO: Test took 1161ms.
[09:22:29.902] <TB1> INFO: Expecting 2560 events.
[09:22:30.757] <TB1> INFO: 2560 events read in total (299ms).
[09:22:30.759] <TB1> INFO: Test took 1162ms.
[09:22:31.062] <TB1> INFO: Expecting 2560 events.
[09:22:31.917] <TB1> INFO: 2560 events read in total (299ms).
[09:22:31.917] <TB1> INFO: Test took 1158ms.
[09:22:31.919] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:22:32.226] <TB1> INFO: Expecting 655360 events.
[09:22:47.150] <TB1> INFO: 655360 events read in total (14368ms).
[09:22:47.166] <TB1> INFO: Expecting 655360 events.
[09:23:02.042] <TB1> INFO: 655360 events read in total (14474ms).
[09:23:02.059] <TB1> INFO: Expecting 655360 events.
[09:23:16.066] <TB1> INFO: 655360 events read in total (13606ms).
[09:23:16.085] <TB1> INFO: Expecting 655360 events.
[09:23:30.017] <TB1> INFO: 655360 events read in total (13530ms).
[09:23:30.040] <TB1> INFO: Expecting 655360 events.
[09:23:43.935] <TB1> INFO: 655360 events read in total (13493ms).
[09:23:43.963] <TB1> INFO: Expecting 655360 events.
[09:23:57.914] <TB1> INFO: 655360 events read in total (13549ms).
[09:23:57.948] <TB1> INFO: Expecting 655360 events.
[09:24:11.958] <TB1> INFO: 655360 events read in total (13608ms).
[09:24:12.003] <TB1> INFO: Expecting 655360 events.
[09:24:25.870] <TB1> INFO: 655360 events read in total (13465ms).
[09:24:25.910] <TB1> INFO: Expecting 655360 events.
[09:24:39.868] <TB1> INFO: 655360 events read in total (13556ms).
[09:24:39.914] <TB1> INFO: Expecting 655360 events.
[09:24:54.303] <TB1> INFO: 655360 events read in total (13987ms).
[09:24:54.381] <TB1> INFO: Expecting 655360 events.
[09:25:08.623] <TB1> INFO: 655360 events read in total (13841ms).
[09:25:08.683] <TB1> INFO: Expecting 655360 events.
[09:25:22.639] <TB1> INFO: 655360 events read in total (13555ms).
[09:25:22.730] <TB1> INFO: Expecting 655360 events.
[09:25:36.507] <TB1> INFO: 655360 events read in total (13375ms).
[09:25:36.571] <TB1> INFO: Expecting 655360 events.
[09:25:50.348] <TB1> INFO: 655360 events read in total (13375ms).
[09:25:50.423] <TB1> INFO: Expecting 655360 events.
[09:26:04.142] <TB1> INFO: 655360 events read in total (13318ms).
[09:26:04.217] <TB1> INFO: Expecting 655360 events.
[09:26:18.070] <TB1> INFO: 655360 events read in total (13451ms).
[09:26:18.145] <TB1> INFO: Test took 226226ms.
[09:26:18.225] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:26:18.455] <TB1> INFO: Expecting 655360 events.
[09:26:32.595] <TB1> INFO: 655360 events read in total (13583ms).
[09:26:32.607] <TB1> INFO: Expecting 655360 events.
[09:26:46.484] <TB1> INFO: 655360 events read in total (13475ms).
[09:26:46.498] <TB1> INFO: Expecting 655360 events.
[09:27:00.350] <TB1> INFO: 655360 events read in total (13450ms).
[09:27:00.369] <TB1> INFO: Expecting 655360 events.
[09:27:14.231] <TB1> INFO: 655360 events read in total (13461ms).
[09:27:14.255] <TB1> INFO: Expecting 655360 events.
[09:27:27.932] <TB1> INFO: 655360 events read in total (13276ms).
[09:27:27.972] <TB1> INFO: Expecting 655360 events.
[09:27:41.794] <TB1> INFO: 655360 events read in total (13420ms).
[09:27:41.826] <TB1> INFO: Expecting 655360 events.
[09:27:55.620] <TB1> INFO: 655360 events read in total (13393ms).
[09:27:55.670] <TB1> INFO: Expecting 655360 events.
[09:28:09.459] <TB1> INFO: 655360 events read in total (13388ms).
[09:28:09.499] <TB1> INFO: Expecting 655360 events.
[09:28:23.735] <TB1> INFO: 655360 events read in total (13834ms).
[09:28:23.805] <TB1> INFO: Expecting 655360 events.
[09:28:37.699] <TB1> INFO: 655360 events read in total (13492ms).
[09:28:37.747] <TB1> INFO: Expecting 655360 events.
[09:28:51.654] <TB1> INFO: 655360 events read in total (13505ms).
[09:28:51.709] <TB1> INFO: Expecting 655360 events.
[09:29:05.628] <TB1> INFO: 655360 events read in total (13518ms).
[09:29:05.686] <TB1> INFO: Expecting 655360 events.
[09:29:19.710] <TB1> INFO: 655360 events read in total (13622ms).
[09:29:19.773] <TB1> INFO: Expecting 655360 events.
[09:29:33.749] <TB1> INFO: 655360 events read in total (13574ms).
[09:29:33.815] <TB1> INFO: Expecting 655360 events.
[09:29:47.773] <TB1> INFO: 655360 events read in total (13556ms).
[09:29:47.858] <TB1> INFO: Expecting 655360 events.
[09:30:01.756] <TB1> INFO: 655360 events read in total (13497ms).
[09:30:01.830] <TB1> INFO: Test took 223605ms.
[09:30:02.034] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:02.041] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:02.049] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:02.056] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:02.063] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:02.070] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:02.077] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:02.084] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:02.091] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:02.098] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:02.105] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:02.112] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:02.119] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:02.126] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:02.134] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:02.141] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:02.181] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:30:02.181] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:30:02.181] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:30:02.181] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:30:02.181] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:30:02.181] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:30:02.181] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:30:02.182] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:30:02.194] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:30:02.194] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:30:02.194] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:30:02.194] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:30:02.214] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:30:02.215] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:30:02.215] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:30:02.215] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:30:02.480] <TB1> INFO: Expecting 41600 events.
[09:30:05.650] <TB1> INFO: 41600 events read in total (2614ms).
[09:30:05.651] <TB1> INFO: Test took 3433ms.
[09:30:06.109] <TB1> INFO: Expecting 41600 events.
[09:30:09.207] <TB1> INFO: 41600 events read in total (2541ms).
[09:30:09.208] <TB1> INFO: Test took 3378ms.
[09:30:09.659] <TB1> INFO: Expecting 41600 events.
[09:30:12.874] <TB1> INFO: 41600 events read in total (2658ms).
[09:30:12.875] <TB1> INFO: Test took 3487ms.
[09:30:13.067] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:13.156] <TB1> INFO: Expecting 2560 events.
[09:30:14.013] <TB1> INFO: 2560 events read in total (301ms).
[09:30:14.013] <TB1> INFO: Test took 946ms.
[09:30:14.015] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:14.318] <TB1> INFO: Expecting 2560 events.
[09:30:15.174] <TB1> INFO: 2560 events read in total (300ms).
[09:30:15.174] <TB1> INFO: Test took 1159ms.
[09:30:15.176] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:15.480] <TB1> INFO: Expecting 2560 events.
[09:30:16.334] <TB1> INFO: 2560 events read in total (298ms).
[09:30:16.334] <TB1> INFO: Test took 1158ms.
[09:30:16.336] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:16.639] <TB1> INFO: Expecting 2560 events.
[09:30:17.492] <TB1> INFO: 2560 events read in total (296ms).
[09:30:17.492] <TB1> INFO: Test took 1156ms.
[09:30:17.494] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:17.798] <TB1> INFO: Expecting 2560 events.
[09:30:18.650] <TB1> INFO: 2560 events read in total (296ms).
[09:30:18.650] <TB1> INFO: Test took 1156ms.
[09:30:18.653] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:18.956] <TB1> INFO: Expecting 2560 events.
[09:30:19.808] <TB1> INFO: 2560 events read in total (296ms).
[09:30:19.808] <TB1> INFO: Test took 1155ms.
[09:30:19.809] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:20.114] <TB1> INFO: Expecting 2560 events.
[09:30:20.970] <TB1> INFO: 2560 events read in total (300ms).
[09:30:20.970] <TB1> INFO: Test took 1161ms.
[09:30:20.972] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:21.275] <TB1> INFO: Expecting 2560 events.
[09:30:22.128] <TB1> INFO: 2560 events read in total (296ms).
[09:30:22.128] <TB1> INFO: Test took 1156ms.
[09:30:22.130] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:22.433] <TB1> INFO: Expecting 2560 events.
[09:30:23.286] <TB1> INFO: 2560 events read in total (297ms).
[09:30:23.287] <TB1> INFO: Test took 1157ms.
[09:30:23.289] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:23.592] <TB1> INFO: Expecting 2560 events.
[09:30:24.445] <TB1> INFO: 2560 events read in total (297ms).
[09:30:24.446] <TB1> INFO: Test took 1158ms.
[09:30:24.452] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:24.750] <TB1> INFO: Expecting 2560 events.
[09:30:25.607] <TB1> INFO: 2560 events read in total (301ms).
[09:30:25.607] <TB1> INFO: Test took 1155ms.
[09:30:25.610] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:25.916] <TB1> INFO: Expecting 2560 events.
[09:30:26.769] <TB1> INFO: 2560 events read in total (297ms).
[09:30:26.769] <TB1> INFO: Test took 1160ms.
[09:30:26.772] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:27.074] <TB1> INFO: Expecting 2560 events.
[09:30:27.930] <TB1> INFO: 2560 events read in total (300ms).
[09:30:27.931] <TB1> INFO: Test took 1159ms.
[09:30:27.934] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:28.236] <TB1> INFO: Expecting 2560 events.
[09:30:29.090] <TB1> INFO: 2560 events read in total (298ms).
[09:30:29.090] <TB1> INFO: Test took 1157ms.
[09:30:29.092] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:29.395] <TB1> INFO: Expecting 2560 events.
[09:30:30.252] <TB1> INFO: 2560 events read in total (301ms).
[09:30:30.252] <TB1> INFO: Test took 1160ms.
[09:30:30.254] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:30.557] <TB1> INFO: Expecting 2560 events.
[09:30:31.414] <TB1> INFO: 2560 events read in total (301ms).
[09:30:31.414] <TB1> INFO: Test took 1160ms.
[09:30:31.416] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:31.719] <TB1> INFO: Expecting 2560 events.
[09:30:32.573] <TB1> INFO: 2560 events read in total (298ms).
[09:30:32.573] <TB1> INFO: Test took 1157ms.
[09:30:32.575] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:32.878] <TB1> INFO: Expecting 2560 events.
[09:30:33.731] <TB1> INFO: 2560 events read in total (297ms).
[09:30:33.731] <TB1> INFO: Test took 1156ms.
[09:30:33.734] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:34.036] <TB1> INFO: Expecting 2560 events.
[09:30:34.888] <TB1> INFO: 2560 events read in total (296ms).
[09:30:34.889] <TB1> INFO: Test took 1155ms.
[09:30:34.890] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:35.194] <TB1> INFO: Expecting 2560 events.
[09:30:36.046] <TB1> INFO: 2560 events read in total (296ms).
[09:30:36.046] <TB1> INFO: Test took 1156ms.
[09:30:36.049] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:36.352] <TB1> INFO: Expecting 2560 events.
[09:30:37.205] <TB1> INFO: 2560 events read in total (297ms).
[09:30:37.205] <TB1> INFO: Test took 1156ms.
[09:30:37.207] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:37.511] <TB1> INFO: Expecting 2560 events.
[09:30:38.364] <TB1> INFO: 2560 events read in total (297ms).
[09:30:38.364] <TB1> INFO: Test took 1157ms.
[09:30:38.365] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:38.669] <TB1> INFO: Expecting 2560 events.
[09:30:39.526] <TB1> INFO: 2560 events read in total (300ms).
[09:30:39.526] <TB1> INFO: Test took 1161ms.
[09:30:39.529] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:39.831] <TB1> INFO: Expecting 2560 events.
[09:30:40.685] <TB1> INFO: 2560 events read in total (297ms).
[09:30:40.685] <TB1> INFO: Test took 1156ms.
[09:30:40.688] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:40.990] <TB1> INFO: Expecting 2560 events.
[09:30:41.843] <TB1> INFO: 2560 events read in total (296ms).
[09:30:41.844] <TB1> INFO: Test took 1156ms.
[09:30:41.846] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:42.149] <TB1> INFO: Expecting 2560 events.
[09:30:43.002] <TB1> INFO: 2560 events read in total (297ms).
[09:30:43.002] <TB1> INFO: Test took 1157ms.
[09:30:43.005] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:43.307] <TB1> INFO: Expecting 2560 events.
[09:30:44.159] <TB1> INFO: 2560 events read in total (296ms).
[09:30:44.159] <TB1> INFO: Test took 1154ms.
[09:30:44.161] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:44.465] <TB1> INFO: Expecting 2560 events.
[09:30:45.316] <TB1> INFO: 2560 events read in total (295ms).
[09:30:45.316] <TB1> INFO: Test took 1155ms.
[09:30:45.318] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:45.622] <TB1> INFO: Expecting 2560 events.
[09:30:46.474] <TB1> INFO: 2560 events read in total (296ms).
[09:30:46.475] <TB1> INFO: Test took 1157ms.
[09:30:46.477] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:46.780] <TB1> INFO: Expecting 2560 events.
[09:30:47.631] <TB1> INFO: 2560 events read in total (295ms).
[09:30:47.632] <TB1> INFO: Test took 1156ms.
[09:30:47.633] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:47.937] <TB1> INFO: Expecting 2560 events.
[09:30:48.789] <TB1> INFO: 2560 events read in total (296ms).
[09:30:48.790] <TB1> INFO: Test took 1157ms.
[09:30:48.791] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:30:49.095] <TB1> INFO: Expecting 2560 events.
[09:30:49.952] <TB1> INFO: 2560 events read in total (301ms).
[09:30:49.952] <TB1> INFO: Test took 1161ms.
[09:30:50.433] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 529 seconds
[09:30:50.433] <TB1> INFO: PH scale (per ROC): 94 97 87 82 78 81 87 77 94 94 103 94 85 82 94 86
[09:30:50.433] <TB1> INFO: PH offset (per ROC): 148 137 158 158 163 154 151 166 147 144 137 162 150 154 143 150
[09:30:50.439] <TB1> INFO: Decoding statistics:
[09:30:50.439] <TB1> INFO: General information:
[09:30:50.439] <TB1> INFO: 16bit words read: 86922
[09:30:50.439] <TB1> INFO: valid events total: 10240
[09:30:50.439] <TB1> INFO: empty events: 7739
[09:30:50.439] <TB1> INFO: valid events with pixels: 2501
[09:30:50.439] <TB1> INFO: valid pixel hits: 2501
[09:30:50.439] <TB1> INFO: Event errors: 0
[09:30:50.439] <TB1> INFO: start marker: 0
[09:30:50.439] <TB1> INFO: stop marker: 0
[09:30:50.439] <TB1> INFO: overflow: 0
[09:30:50.439] <TB1> INFO: invalid 5bit words: 0
[09:30:50.439] <TB1> INFO: invalid XOR eye diagram: 0
[09:30:50.439] <TB1> INFO: frame (failed synchr.): 0
[09:30:50.439] <TB1> INFO: idle data (no TBM trl): 0
[09:30:50.439] <TB1> INFO: no data (only TBM hdr): 0
[09:30:50.439] <TB1> INFO: TBM errors: 0
[09:30:50.439] <TB1> INFO: flawed TBM headers: 0
[09:30:50.439] <TB1> INFO: flawed TBM trailers: 0
[09:30:50.439] <TB1> INFO: event ID mismatches: 0
[09:30:50.439] <TB1> INFO: ROC errors: 0
[09:30:50.439] <TB1> INFO: missing ROC header(s): 0
[09:30:50.439] <TB1> INFO: misplaced readback start: 0
[09:30:50.439] <TB1> INFO: Pixel decoding errors: 0
[09:30:50.439] <TB1> INFO: pixel data incomplete: 0
[09:30:50.439] <TB1> INFO: pixel address: 0
[09:30:50.439] <TB1> INFO: pulse height fill bit: 0
[09:30:50.439] <TB1> INFO: buffer corruption: 0
[09:30:50.713] <TB1> INFO: ######################################################################
[09:30:50.713] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[09:30:50.713] <TB1> INFO: ######################################################################
[09:30:50.725] <TB1> INFO: scanning low vcal = 10
[09:30:51.056] <TB1> INFO: Expecting 41600 events.
[09:30:54.460] <TB1> INFO: 41600 events read in total (2848ms).
[09:30:54.461] <TB1> INFO: Test took 3736ms.
[09:30:54.463] <TB1> INFO: scanning low vcal = 20
[09:30:54.753] <TB1> INFO: Expecting 41600 events.
[09:30:58.140] <TB1> INFO: 41600 events read in total (2831ms).
[09:30:58.141] <TB1> INFO: Test took 3678ms.
[09:30:58.142] <TB1> INFO: scanning low vcal = 30
[09:30:58.438] <TB1> INFO: Expecting 41600 events.
[09:31:01.828] <TB1> INFO: 41600 events read in total (2835ms).
[09:31:01.829] <TB1> INFO: Test took 3686ms.
[09:31:01.831] <TB1> INFO: scanning low vcal = 40
[09:31:02.123] <TB1> INFO: Expecting 41600 events.
[09:31:06.053] <TB1> INFO: 41600 events read in total (3374ms).
[09:31:06.054] <TB1> INFO: Test took 4223ms.
[09:31:06.059] <TB1> INFO: scanning low vcal = 50
[09:31:06.325] <TB1> INFO: Expecting 41600 events.
[09:31:10.305] <TB1> INFO: 41600 events read in total (3423ms).
[09:31:10.306] <TB1> INFO: Test took 4247ms.
[09:31:10.309] <TB1> INFO: scanning low vcal = 60
[09:31:10.605] <TB1> INFO: Expecting 41600 events.
[09:31:14.544] <TB1> INFO: 41600 events read in total (3383ms).
[09:31:14.546] <TB1> INFO: Test took 4237ms.
[09:31:14.549] <TB1> INFO: scanning low vcal = 70
[09:31:14.816] <TB1> INFO: Expecting 41600 events.
[09:31:18.758] <TB1> INFO: 41600 events read in total (3386ms).
[09:31:18.760] <TB1> INFO: Test took 4211ms.
[09:31:18.763] <TB1> INFO: scanning low vcal = 80
[09:31:19.036] <TB1> INFO: Expecting 41600 events.
[09:31:22.973] <TB1> INFO: 41600 events read in total (3381ms).
[09:31:22.974] <TB1> INFO: Test took 4211ms.
[09:31:22.977] <TB1> INFO: scanning low vcal = 90
[09:31:23.244] <TB1> INFO: Expecting 41600 events.
[09:31:27.174] <TB1> INFO: 41600 events read in total (3373ms).
[09:31:27.175] <TB1> INFO: Test took 4198ms.
[09:31:27.179] <TB1> INFO: scanning low vcal = 100
[09:31:27.444] <TB1> INFO: Expecting 41600 events.
[09:31:31.413] <TB1> INFO: 41600 events read in total (3413ms).
[09:31:31.415] <TB1> INFO: Test took 4236ms.
[09:31:31.418] <TB1> INFO: scanning low vcal = 110
[09:31:31.702] <TB1> INFO: Expecting 41600 events.
[09:31:35.650] <TB1> INFO: 41600 events read in total (3392ms).
[09:31:35.652] <TB1> INFO: Test took 4234ms.
[09:31:35.655] <TB1> INFO: scanning low vcal = 120
[09:31:35.920] <TB1> INFO: Expecting 41600 events.
[09:31:39.854] <TB1> INFO: 41600 events read in total (3378ms).
[09:31:39.856] <TB1> INFO: Test took 4201ms.
[09:31:39.858] <TB1> INFO: scanning low vcal = 130
[09:31:40.123] <TB1> INFO: Expecting 41600 events.
[09:31:44.057] <TB1> INFO: 41600 events read in total (3378ms).
[09:31:44.058] <TB1> INFO: Test took 4200ms.
[09:31:44.061] <TB1> INFO: scanning low vcal = 140
[09:31:44.341] <TB1> INFO: Expecting 41600 events.
[09:31:48.361] <TB1> INFO: 41600 events read in total (3464ms).
[09:31:48.363] <TB1> INFO: Test took 4302ms.
[09:31:48.366] <TB1> INFO: scanning low vcal = 150
[09:31:48.628] <TB1> INFO: Expecting 41600 events.
[09:31:52.581] <TB1> INFO: 41600 events read in total (3397ms).
[09:31:52.583] <TB1> INFO: Test took 4218ms.
[09:31:52.587] <TB1> INFO: scanning low vcal = 160
[09:31:52.847] <TB1> INFO: Expecting 41600 events.
[09:31:56.770] <TB1> INFO: 41600 events read in total (3367ms).
[09:31:56.773] <TB1> INFO: Test took 4185ms.
[09:31:56.778] <TB1> INFO: scanning low vcal = 170
[09:31:57.047] <TB1> INFO: Expecting 41600 events.
[09:32:00.980] <TB1> INFO: 41600 events read in total (3377ms).
[09:32:00.982] <TB1> INFO: Test took 4204ms.
[09:32:00.988] <TB1> INFO: scanning low vcal = 180
[09:32:01.248] <TB1> INFO: Expecting 41600 events.
[09:32:05.165] <TB1> INFO: 41600 events read in total (3360ms).
[09:32:05.166] <TB1> INFO: Test took 4178ms.
[09:32:05.169] <TB1> INFO: scanning low vcal = 190
[09:32:05.451] <TB1> INFO: Expecting 41600 events.
[09:32:09.428] <TB1> INFO: 41600 events read in total (3421ms).
[09:32:09.430] <TB1> INFO: Test took 4261ms.
[09:32:09.432] <TB1> INFO: scanning low vcal = 200
[09:32:09.694] <TB1> INFO: Expecting 41600 events.
[09:32:13.653] <TB1> INFO: 41600 events read in total (3403ms).
[09:32:13.655] <TB1> INFO: Test took 4223ms.
[09:32:13.657] <TB1> INFO: scanning low vcal = 210
[09:32:13.922] <TB1> INFO: Expecting 41600 events.
[09:32:17.858] <TB1> INFO: 41600 events read in total (3380ms).
[09:32:17.860] <TB1> INFO: Test took 4203ms.
[09:32:17.863] <TB1> INFO: scanning low vcal = 220
[09:32:18.130] <TB1> INFO: Expecting 41600 events.
[09:32:22.120] <TB1> INFO: 41600 events read in total (3434ms).
[09:32:22.122] <TB1> INFO: Test took 4259ms.
[09:32:22.125] <TB1> INFO: scanning low vcal = 230
[09:32:22.409] <TB1> INFO: Expecting 41600 events.
[09:32:26.414] <TB1> INFO: 41600 events read in total (3449ms).
[09:32:26.416] <TB1> INFO: Test took 4291ms.
[09:32:26.419] <TB1> INFO: scanning low vcal = 240
[09:32:26.712] <TB1> INFO: Expecting 41600 events.
[09:32:30.698] <TB1> INFO: 41600 events read in total (3430ms).
[09:32:30.700] <TB1> INFO: Test took 4281ms.
[09:32:30.703] <TB1> INFO: scanning low vcal = 250
[09:32:31.061] <TB1> INFO: Expecting 41600 events.
[09:32:35.037] <TB1> INFO: 41600 events read in total (3420ms).
[09:32:35.039] <TB1> INFO: Test took 4336ms.
[09:32:35.042] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[09:32:35.307] <TB1> INFO: Expecting 41600 events.
[09:32:39.319] <TB1> INFO: 41600 events read in total (3456ms).
[09:32:39.321] <TB1> INFO: Test took 4278ms.
[09:32:39.323] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[09:32:39.588] <TB1> INFO: Expecting 41600 events.
[09:32:43.544] <TB1> INFO: 41600 events read in total (3400ms).
[09:32:43.546] <TB1> INFO: Test took 4222ms.
[09:32:43.549] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[09:32:43.939] <TB1> INFO: Expecting 41600 events.
[09:32:47.864] <TB1> INFO: 41600 events read in total (3369ms).
[09:32:47.866] <TB1> INFO: Test took 4317ms.
[09:32:47.869] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[09:32:48.146] <TB1> INFO: Expecting 41600 events.
[09:32:52.089] <TB1> INFO: 41600 events read in total (3387ms).
[09:32:52.091] <TB1> INFO: Test took 4222ms.
[09:32:52.094] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[09:32:52.363] <TB1> INFO: Expecting 41600 events.
[09:32:56.298] <TB1> INFO: 41600 events read in total (3379ms).
[09:32:56.299] <TB1> INFO: Test took 4205ms.
[09:32:56.917] <TB1> INFO: PixTestGainPedestal::measure() done
[09:33:30.686] <TB1> INFO: PixTestGainPedestal::fit() done
[09:33:30.686] <TB1> INFO: non-linearity mean: 0.956 0.947 0.955 0.953 0.954 0.957 0.955 0.952 0.951 0.960 0.948 0.959 0.956 0.955 0.954 0.955
[09:33:30.686] <TB1> INFO: non-linearity RMS: 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.006 0.005 0.005 0.006 0.006 0.006 0.006
[09:33:30.686] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[09:33:30.706] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[09:33:30.726] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[09:33:30.746] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[09:33:30.767] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[09:33:30.787] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[09:33:30.807] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[09:33:30.827] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[09:33:30.847] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[09:33:30.867] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[09:33:30.887] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[09:33:30.907] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[09:33:30.928] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[09:33:30.948] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[09:33:30.969] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[09:33:30.989] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[09:33:31.010] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 160 seconds
[09:33:31.010] <TB1> INFO: Decoding statistics:
[09:33:31.010] <TB1> INFO: General information:
[09:33:31.010] <TB1> INFO: 16bit words read: 2662320
[09:33:31.010] <TB1> INFO: valid events total: 166400
[09:33:31.010] <TB1> INFO: empty events: 0
[09:33:31.010] <TB1> INFO: valid events with pixels: 166400
[09:33:31.010] <TB1> INFO: valid pixel hits: 665560
[09:33:31.010] <TB1> INFO: Event errors: 0
[09:33:31.010] <TB1> INFO: start marker: 0
[09:33:31.010] <TB1> INFO: stop marker: 0
[09:33:31.010] <TB1> INFO: overflow: 0
[09:33:31.010] <TB1> INFO: invalid 5bit words: 0
[09:33:31.010] <TB1> INFO: invalid XOR eye diagram: 0
[09:33:31.010] <TB1> INFO: frame (failed synchr.): 0
[09:33:31.010] <TB1> INFO: idle data (no TBM trl): 0
[09:33:31.010] <TB1> INFO: no data (only TBM hdr): 0
[09:33:31.010] <TB1> INFO: TBM errors: 0
[09:33:31.010] <TB1> INFO: flawed TBM headers: 0
[09:33:31.010] <TB1> INFO: flawed TBM trailers: 0
[09:33:31.010] <TB1> INFO: event ID mismatches: 0
[09:33:31.010] <TB1> INFO: ROC errors: 0
[09:33:31.010] <TB1> INFO: missing ROC header(s): 0
[09:33:31.010] <TB1> INFO: misplaced readback start: 0
[09:33:31.010] <TB1> INFO: Pixel decoding errors: 0
[09:33:31.010] <TB1> INFO: pixel data incomplete: 0
[09:33:31.010] <TB1> INFO: pixel address: 0
[09:33:31.010] <TB1> INFO: pulse height fill bit: 0
[09:33:31.010] <TB1> INFO: buffer corruption: 0
[09:33:31.019] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C15.dat
[09:33:31.045] <TB1> INFO: ######################################################################
[09:33:31.045] <TB1> INFO: PixTestReadback::doTest()
[09:33:31.045] <TB1> INFO: ######################################################################
[09:33:31.045] <TB1> INFO: ----------------------------------------------------------------------
[09:33:31.045] <TB1> INFO: PixTestReadback::CalibrateVd()
[09:33:31.045] <TB1> INFO: ----------------------------------------------------------------------
[09:33:40.581] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C0.dat
[09:33:40.581] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C1.dat
[09:33:40.581] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C2.dat
[09:33:40.581] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C3.dat
[09:33:40.581] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C4.dat
[09:33:40.581] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C5.dat
[09:33:40.581] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C6.dat
[09:33:40.581] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C7.dat
[09:33:40.581] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C8.dat
[09:33:40.581] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C9.dat
[09:33:40.581] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C10.dat
[09:33:40.581] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C11.dat
[09:33:40.581] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C12.dat
[09:33:40.581] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C13.dat
[09:33:40.581] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C14.dat
[09:33:40.581] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C15.dat
[09:33:40.612] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[09:33:40.612] <TB1> INFO: ----------------------------------------------------------------------
[09:33:40.612] <TB1> INFO: PixTestReadback::CalibrateVa()
[09:33:40.612] <TB1> INFO: ----------------------------------------------------------------------
[09:33:50.666] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C0.dat
[09:33:50.666] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C1.dat
[09:33:50.666] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C2.dat
[09:33:50.666] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C3.dat
[09:33:50.666] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C4.dat
[09:33:50.666] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C5.dat
[09:33:50.666] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C6.dat
[09:33:50.666] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C7.dat
[09:33:50.667] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C8.dat
[09:33:50.667] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C9.dat
[09:33:50.667] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C10.dat
[09:33:50.667] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C11.dat
[09:33:50.667] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C12.dat
[09:33:50.667] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C13.dat
[09:33:50.667] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C14.dat
[09:33:50.667] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C15.dat
[09:33:50.699] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[09:33:50.699] <TB1> INFO: ----------------------------------------------------------------------
[09:33:50.699] <TB1> INFO: PixTestReadback::readbackVbg()
[09:33:50.699] <TB1> INFO: ----------------------------------------------------------------------
[09:33:58.005] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[09:33:58.005] <TB1> INFO: ----------------------------------------------------------------------
[09:33:58.005] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[09:33:58.005] <TB1> INFO: ----------------------------------------------------------------------
[09:33:58.006] <TB1> INFO: Vbg will be calibrated using Vd calibration
[09:33:58.006] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 153.3calibrated Vbg = 1.18699 :::*/*/*/*/
[09:33:58.006] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 152.5calibrated Vbg = 1.1894 :::*/*/*/*/
[09:33:58.006] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 160calibrated Vbg = 1.19258 :::*/*/*/*/
[09:33:58.006] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 153.9calibrated Vbg = 1.19299 :::*/*/*/*/
[09:33:58.006] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 147.8calibrated Vbg = 1.19853 :::*/*/*/*/
[09:33:58.006] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 151.8calibrated Vbg = 1.19981 :::*/*/*/*/
[09:33:58.006] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 150.1calibrated Vbg = 1.20066 :::*/*/*/*/
[09:33:58.006] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 161.8calibrated Vbg = 1.20383 :::*/*/*/*/
[09:33:58.006] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 148.1calibrated Vbg = 1.20058 :::*/*/*/*/
[09:33:58.006] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 137calibrated Vbg = 1.20266 :::*/*/*/*/
[09:33:58.006] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 160.1calibrated Vbg = 1.20669 :::*/*/*/*/
[09:33:58.006] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 153.8calibrated Vbg = 1.20117 :::*/*/*/*/
[09:33:58.006] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 159.1calibrated Vbg = 1.18875 :::*/*/*/*/
[09:33:58.006] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 160.3calibrated Vbg = 1.1876 :::*/*/*/*/
[09:33:58.006] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 146calibrated Vbg = 1.18912 :::*/*/*/*/
[09:33:58.006] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 161.8calibrated Vbg = 1.18364 :::*/*/*/*/
[09:33:58.009] <TB1> INFO: ----------------------------------------------------------------------
[09:33:58.009] <TB1> INFO: PixTestReadback::CalibrateIa()
[09:33:58.009] <TB1> INFO: ----------------------------------------------------------------------
[09:36:33.226] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C0.dat
[09:36:33.226] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C1.dat
[09:36:33.226] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C2.dat
[09:36:33.226] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C3.dat
[09:36:33.226] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C4.dat
[09:36:33.226] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C5.dat
[09:36:33.226] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C6.dat
[09:36:33.226] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C7.dat
[09:36:33.226] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C8.dat
[09:36:33.226] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C9.dat
[09:36:33.226] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C10.dat
[09:36:33.226] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C11.dat
[09:36:33.227] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C12.dat
[09:36:33.227] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C13.dat
[09:36:33.227] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C14.dat
[09:36:33.227] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2349_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C15.dat
[09:36:33.251] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[09:36:33.252] <TB1> INFO: PixTestReadback::doTest() done
[09:36:33.252] <TB1> INFO: Decoding statistics:
[09:36:33.252] <TB1> INFO: General information:
[09:36:33.252] <TB1> INFO: 16bit words read: 1024
[09:36:33.252] <TB1> INFO: valid events total: 128
[09:36:33.252] <TB1> INFO: empty events: 128
[09:36:33.252] <TB1> INFO: valid events with pixels: 0
[09:36:33.252] <TB1> INFO: valid pixel hits: 0
[09:36:33.252] <TB1> INFO: Event errors: 0
[09:36:33.252] <TB1> INFO: start marker: 0
[09:36:33.252] <TB1> INFO: stop marker: 0
[09:36:33.252] <TB1> INFO: overflow: 0
[09:36:33.252] <TB1> INFO: invalid 5bit words: 0
[09:36:33.252] <TB1> INFO: invalid XOR eye diagram: 0
[09:36:33.252] <TB1> INFO: frame (failed synchr.): 0
[09:36:33.252] <TB1> INFO: idle data (no TBM trl): 0
[09:36:33.253] <TB1> INFO: no data (only TBM hdr): 0
[09:36:33.253] <TB1> INFO: TBM errors: 0
[09:36:33.253] <TB1> INFO: flawed TBM headers: 0
[09:36:33.253] <TB1> INFO: flawed TBM trailers: 0
[09:36:33.253] <TB1> INFO: event ID mismatches: 0
[09:36:33.253] <TB1> INFO: ROC errors: 0
[09:36:33.253] <TB1> INFO: missing ROC header(s): 0
[09:36:33.253] <TB1> INFO: misplaced readback start: 0
[09:36:33.253] <TB1> INFO: Pixel decoding errors: 0
[09:36:33.253] <TB1> INFO: pixel data incomplete: 0
[09:36:33.253] <TB1> INFO: pixel address: 0
[09:36:33.253] <TB1> INFO: pulse height fill bit: 0
[09:36:33.253] <TB1> INFO: buffer corruption: 0
[09:36:33.312] <TB1> INFO: Decoding statistics:
[09:36:33.312] <TB1> INFO: General information:
[09:36:33.312] <TB1> INFO: 16bit words read: 2750266
[09:36:33.312] <TB1> INFO: valid events total: 176768
[09:36:33.312] <TB1> INFO: empty events: 7867
[09:36:33.312] <TB1> INFO: valid events with pixels: 168901
[09:36:33.312] <TB1> INFO: valid pixel hits: 668061
[09:36:33.312] <TB1> INFO: Event errors: 0
[09:36:33.312] <TB1> INFO: start marker: 0
[09:36:33.312] <TB1> INFO: stop marker: 0
[09:36:33.312] <TB1> INFO: overflow: 0
[09:36:33.312] <TB1> INFO: invalid 5bit words: 0
[09:36:33.312] <TB1> INFO: invalid XOR eye diagram: 0
[09:36:33.312] <TB1> INFO: frame (failed synchr.): 0
[09:36:33.312] <TB1> INFO: idle data (no TBM trl): 0
[09:36:33.312] <TB1> INFO: no data (only TBM hdr): 0
[09:36:33.312] <TB1> INFO: TBM errors: 0
[09:36:33.312] <TB1> INFO: flawed TBM headers: 0
[09:36:33.312] <TB1> INFO: flawed TBM trailers: 0
[09:36:33.312] <TB1> INFO: event ID mismatches: 0
[09:36:33.312] <TB1> INFO: ROC errors: 0
[09:36:33.312] <TB1> INFO: missing ROC header(s): 0
[09:36:33.312] <TB1> INFO: misplaced readback start: 0
[09:36:33.312] <TB1> INFO: Pixel decoding errors: 0
[09:36:33.312] <TB1> INFO: pixel data incomplete: 0
[09:36:33.312] <TB1> INFO: pixel address: 0
[09:36:33.312] <TB1> INFO: pulse height fill bit: 0
[09:36:33.312] <TB1> INFO: buffer corruption: 0
[09:36:33.312] <TB1> INFO: enter test to run
[09:36:33.312] <TB1> INFO: test: exit no parameter change
[09:36:33.493] <TB1> QUIET: Connection to board 153 closed.
[09:36:33.572] <TB1> INFO: pXar: this is the end, my friend