[07:59:37.537] <TB0> INFO: *** Welcome to pxar ***
[07:59:37.537] <TB0> INFO: *** Today: 2016/07/19
[07:59:38.608] <TB0> INFO: *** Version: 9751-dirty
[07:59:38.608] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters_C15.dat
[07:59:38.608] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//tbmParameters_C0b.dat
[07:59:38.608] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//defaultMaskFile.dat
[07:59:38.608] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//trimParameters_C15.dat
[07:59:38.675] <TB0> INFO: clk: 4
[07:59:38.675] <TB0> INFO: ctr: 4
[07:59:38.675] <TB0> INFO: sda: 19
[07:59:38.675] <TB0> INFO: tin: 9
[07:59:38.675] <TB0> INFO: level: 15
[07:59:38.675] <TB0> INFO: triggerdelay: 0
[07:59:38.675] <TB0> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[07:59:38.675] <TB0> INFO: Log level: INFO
[07:59:38.682] <TB0> INFO: Found DTB DTB_WS6AYH
[07:59:38.694] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[07:59:38.696] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[07:59:38.697] <TB0> INFO: RPC call hashes of host and DTB match: 484264910
[07:59:40.226] <TB0> INFO: DUT info:
[07:59:40.226] <TB0> INFO: The DUT currently contains the following objects:
[07:59:40.226] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[07:59:40.226] <TB0> INFO: TBM Core alpha (0): 7 registers set
[07:59:40.226] <TB0> INFO: TBM Core beta (1): 7 registers set
[07:59:40.226] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:59:40.226] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:59:40.226] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:59:40.226] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:59:40.226] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:59:40.226] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:59:40.226] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:59:40.226] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:59:40.226] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:59:40.226] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:59:40.226] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:59:40.226] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:59:40.226] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:59:40.226] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:59:40.226] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:59:40.226] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:59:40.226] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:59:40.626] <TB0> INFO: enter 'restricted' command line mode
[07:59:40.627] <TB0> INFO: enter test to run
[07:59:40.627] <TB0> INFO: test: pretest no parameter change
[07:59:40.627] <TB0> INFO: running: pretest
[07:59:40.631] <TB0> INFO: ----------------------------------------------------------------------
[07:59:40.631] <TB0> INFO: PixTestPretest::programROC()
[07:59:40.631] <TB0> INFO: ----------------------------------------------------------------------
[07:59:58.644] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[07:59:58.645] <TB0> INFO: IA differences per ROC: 18.5 17.7 19.3 20.1 19.3 19.3 20.9 18.5 20.1 19.3 21.7 19.3 20.1 20.1 20.1 21.7
[07:59:58.748] <TB0> INFO: enter test to run
[07:59:58.748] <TB0> INFO: test: pretest no parameter change
[07:59:58.748] <TB0> INFO: running: pretest
[07:59:58.750] <TB0> INFO: ----------------------------------------------------------------------
[07:59:58.750] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[07:59:58.750] <TB0> INFO: ----------------------------------------------------------------------
[08:00:04.434] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 387.5 mA = 24.2188 mA/ROC
[08:00:04.434] <TB0> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 19.3 20.1 20.1 19.3 19.3 19.3 19.3 19.3 19.3 19.3 18.5 19.3 19.3
[08:00:04.468] <TB0> INFO: enter test to run
[08:00:04.468] <TB0> INFO: test: pretest no parameter change
[08:00:04.468] <TB0> INFO: running: pretest
[08:00:04.469] <TB0> INFO: ----------------------------------------------------------------------
[08:00:04.469] <TB0> INFO: PixTestPretest::findTiming()
[08:00:04.469] <TB0> INFO: ----------------------------------------------------------------------
[08:00:04.469] <TB0> INFO: PixTestCmd::init()
[08:00:05.429] <TB0> WARNING: Not unmasking DUT, not setting Calibrate bits!
[08:01:33.456] <TB0> INFO: TBM phases: 160MHz: 0, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[08:01:33.456] <TB0> INFO: (success/tries = 100/100), width = 5
[08:01:33.457] <TB0> INFO: enter test to run
[08:01:33.457] <TB0> INFO: test: pretest no parameter change
[08:01:33.457] <TB0> INFO: running: pretest
[08:01:33.459] <TB0> INFO: ----------------------------------------------------------------------
[08:01:33.459] <TB0> INFO: PixTestPretest::findWorkingPixel()
[08:01:33.459] <TB0> INFO: ----------------------------------------------------------------------
[08:01:33.553] <TB0> INFO: Expecting 231680 events.
[08:01:39.332] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L497> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[08:01:39.417] <TB0> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[08:01:43.771] <TB0> INFO: 231680 events read in total (9661ms).
[08:01:43.774] <TB0> INFO: Test took 10310ms.
[08:01:44.011] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[08:01:44.082] <TB0> INFO: enter test to run
[08:01:44.082] <TB0> INFO: test: pretest no parameter change
[08:01:44.082] <TB0> INFO: running: pretest
[08:01:44.084] <TB0> INFO: ----------------------------------------------------------------------
[08:01:44.084] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[08:01:44.084] <TB0> INFO: ----------------------------------------------------------------------
[08:01:44.176] <TB0> INFO: Expecting 231680 events.
[08:01:54.418] <TB0> INFO: 231680 events read in total (9686ms).
[08:01:54.422] <TB0> INFO: Test took 10335ms.
[08:01:54.679] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[08:01:54.679] <TB0> INFO: CalDel: 150 144 127 131 145 128 146 126 149 132 163 138 154 137 157 158
[08:01:54.679] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[08:01:54.715] <TB0> INFO: enter test to run
[08:01:54.715] <TB0> INFO: test: pretest no parameter change
[08:01:54.715] <TB0> INFO: running: pretest
[08:01:54.715] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters_C0.dat
[08:01:54.715] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters_C1.dat
[08:01:54.715] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters_C2.dat
[08:01:54.715] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters_C3.dat
[08:01:54.715] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters_C4.dat
[08:01:54.716] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters_C5.dat
[08:01:54.716] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters_C6.dat
[08:01:54.716] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters_C7.dat
[08:01:54.716] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters_C8.dat
[08:01:54.716] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters_C9.dat
[08:01:54.716] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters_C10.dat
[08:01:54.716] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters_C11.dat
[08:01:54.716] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters_C12.dat
[08:01:54.716] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters_C13.dat
[08:01:54.716] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters_C14.dat
[08:01:54.717] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters_C15.dat
[08:01:54.717] <TB0> INFO: enter test to run
[08:01:54.717] <TB0> INFO: test: fulltest no parameter change
[08:01:54.717] <TB0> INFO: running: fulltest
[08:01:54.717] <TB0> INFO: ######################################################################
[08:01:54.717] <TB0> INFO: PixTestFullTest::doTest()
[08:01:54.717] <TB0> INFO: ######################################################################
[08:01:54.718] <TB0> INFO: ######################################################################
[08:01:54.718] <TB0> INFO: PixTestAlive::doTest()
[08:01:54.718] <TB0> INFO: ######################################################################
[08:01:54.719] <TB0> INFO: ----------------------------------------------------------------------
[08:01:54.719] <TB0> 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:01:54.719] <TB0> INFO: ----------------------------------------------------------------------
[08:01:54.985] <TB0> INFO: Expecting 41600 events.
[08:01:58.728] <TB0> INFO: 41600 events read in total (3187ms).
[08:01:58.729] <TB0> INFO: Test took 4009ms.
[08:01:58.959] <TB0> INFO: PixTestAlive::aliveTest() done
[08:01:58.959] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[08:01:58.961] <TB0> INFO: ----------------------------------------------------------------------
[08:01:58.961] <TB0> 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:01:58.961] <TB0> INFO: ----------------------------------------------------------------------
[08:01:59.239] <TB0> INFO: Expecting 41600 events.
[08:02:02.214] <TB0> INFO: 41600 events read in total (2419ms).
[08:02:02.214] <TB0> INFO: Test took 3252ms.
[08:02:02.214] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:02:02.463] <TB0> INFO: PixTestAlive::maskTest() done
[08:02:02.464] <TB0> 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:02:02.467] <TB0> INFO: ----------------------------------------------------------------------
[08:02:02.467] <TB0> 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:02:02.467] <TB0> INFO: ----------------------------------------------------------------------
[08:02:02.733] <TB0> INFO: Expecting 41600 events.
[08:02:06.482] <TB0> INFO: 41600 events read in total (3192ms).
[08:02:06.483] <TB0> INFO: Test took 4014ms.
[08:02:06.703] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[08:02:06.703] <TB0> 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:02:06.703] <TB0> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[08:02:06.703] <TB0> INFO: Decoding statistics:
[08:02:06.703] <TB0> INFO: General information:
[08:02:06.703] <TB0> INFO: 16bit words read: 0
[08:02:06.703] <TB0> INFO: valid events total: 0
[08:02:06.703] <TB0> INFO: empty events: 0
[08:02:06.703] <TB0> INFO: valid events with pixels: 0
[08:02:06.703] <TB0> INFO: valid pixel hits: 0
[08:02:06.703] <TB0> INFO: Event errors: 0
[08:02:06.703] <TB0> INFO: start marker: 0
[08:02:06.703] <TB0> INFO: stop marker: 0
[08:02:06.703] <TB0> INFO: overflow: 0
[08:02:06.703] <TB0> INFO: invalid 5bit words: 0
[08:02:06.703] <TB0> INFO: invalid XOR eye diagram: 0
[08:02:06.703] <TB0> INFO: frame (failed synchr.): 0
[08:02:06.703] <TB0> INFO: idle data (no TBM trl): 0
[08:02:06.703] <TB0> INFO: no data (only TBM hdr): 0
[08:02:06.703] <TB0> INFO: TBM errors: 0
[08:02:06.703] <TB0> INFO: flawed TBM headers: 0
[08:02:06.703] <TB0> INFO: flawed TBM trailers: 0
[08:02:06.703] <TB0> INFO: event ID mismatches: 0
[08:02:06.703] <TB0> INFO: ROC errors: 0
[08:02:06.703] <TB0> INFO: missing ROC header(s): 0
[08:02:06.703] <TB0> INFO: misplaced readback start: 0
[08:02:06.703] <TB0> INFO: Pixel decoding errors: 0
[08:02:06.703] <TB0> INFO: pixel data incomplete: 0
[08:02:06.703] <TB0> INFO: pixel address: 0
[08:02:06.703] <TB0> INFO: pulse height fill bit: 0
[08:02:06.703] <TB0> INFO: buffer corruption: 0
[08:02:06.714] <TB0> INFO: ######################################################################
[08:02:06.714] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[08:02:06.714] <TB0> INFO: ######################################################################
[08:02:06.717] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[08:02:06.728] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[08:02:06.728] <TB0> INFO: run 1 of 1
[08:02:06.991] <TB0> INFO: Expecting 3120000 events.
[08:02:48.152] <TB0> INFO: 845635 events read in total (40605ms).
[08:03:27.939] <TB0> INFO: 1677555 events read in total (80393ms).
[08:04:07.970] <TB0> INFO: 2514285 events read in total (120423ms).
[08:04:36.942] <TB0> INFO: 3120000 events read in total (149395ms).
[08:04:36.989] <TB0> INFO: Test took 150261ms.
[08:04:59.545] <TB0> INFO: PixTestBBMap::doTest() done, duration: 172 seconds
[08:04:59.545] <TB0> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0 0
[08:04:59.545] <TB0> INFO: separation cut (per ROC): 68 71 85 89 81 84 86 96 75 78 71 71 74 80 69 74
[08:04:59.545] <TB0> INFO: Decoding statistics:
[08:04:59.545] <TB0> INFO: General information:
[08:04:59.545] <TB0> INFO: 16bit words read: 0
[08:04:59.545] <TB0> INFO: valid events total: 0
[08:04:59.545] <TB0> INFO: empty events: 0
[08:04:59.545] <TB0> INFO: valid events with pixels: 0
[08:04:59.545] <TB0> INFO: valid pixel hits: 0
[08:04:59.545] <TB0> INFO: Event errors: 0
[08:04:59.545] <TB0> INFO: start marker: 0
[08:04:59.545] <TB0> INFO: stop marker: 0
[08:04:59.545] <TB0> INFO: overflow: 0
[08:04:59.545] <TB0> INFO: invalid 5bit words: 0
[08:04:59.545] <TB0> INFO: invalid XOR eye diagram: 0
[08:04:59.545] <TB0> INFO: frame (failed synchr.): 0
[08:04:59.545] <TB0> INFO: idle data (no TBM trl): 0
[08:04:59.545] <TB0> INFO: no data (only TBM hdr): 0
[08:04:59.545] <TB0> INFO: TBM errors: 0
[08:04:59.545] <TB0> INFO: flawed TBM headers: 0
[08:04:59.545] <TB0> INFO: flawed TBM trailers: 0
[08:04:59.545] <TB0> INFO: event ID mismatches: 0
[08:04:59.545] <TB0> INFO: ROC errors: 0
[08:04:59.545] <TB0> INFO: missing ROC header(s): 0
[08:04:59.545] <TB0> INFO: misplaced readback start: 0
[08:04:59.545] <TB0> INFO: Pixel decoding errors: 0
[08:04:59.545] <TB0> INFO: pixel data incomplete: 0
[08:04:59.545] <TB0> INFO: pixel address: 0
[08:04:59.545] <TB0> INFO: pulse height fill bit: 0
[08:04:59.545] <TB0> INFO: buffer corruption: 0
[08:04:59.631] <TB0> INFO: ######################################################################
[08:04:59.631] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:04:59.631] <TB0> INFO: ######################################################################
[08:04:59.631] <TB0> INFO: ----------------------------------------------------------------------
[08:04:59.631] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:04:59.631] <TB0> INFO: ----------------------------------------------------------------------
[08:04:59.631] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[08:04:59.641] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[08:04:59.641] <TB0> INFO: run 1 of 1
[08:04:59.926] <TB0> INFO: Expecting 26208000 events.
[08:05:34.633] <TB0> INFO: 870050 events read in total (34149ms).
[08:06:06.474] <TB0> INFO: 1729400 events read in total (65990ms).
[08:06:38.732] <TB0> INFO: 2586000 events read in total (98248ms).
[08:07:11.019] <TB0> INFO: 3440250 events read in total (130535ms).
[08:07:43.417] <TB0> INFO: 4294100 events read in total (162933ms).
[08:08:15.383] <TB0> INFO: 5147750 events read in total (194899ms).
[08:08:47.951] <TB0> INFO: 6000900 events read in total (227467ms).
[08:09:20.626] <TB0> INFO: 6853200 events read in total (260142ms).
[08:09:52.653] <TB0> INFO: 7704500 events read in total (292169ms).
[08:10:24.917] <TB0> INFO: 8556650 events read in total (324433ms).
[08:10:57.169] <TB0> INFO: 9409500 events read in total (356685ms).
[08:11:30.225] <TB0> INFO: 10259800 events read in total (389741ms).
[08:12:02.143] <TB0> INFO: 11110950 events read in total (421659ms).
[08:12:34.589] <TB0> INFO: 11958800 events read in total (454105ms).
[08:13:06.958] <TB0> INFO: 12807100 events read in total (486475ms).
[08:13:39.155] <TB0> INFO: 13651000 events read in total (518671ms).
[08:14:11.361] <TB0> INFO: 14491900 events read in total (550877ms).
[08:14:43.488] <TB0> INFO: 15332150 events read in total (583005ms).
[08:15:15.477] <TB0> INFO: 16170650 events read in total (614993ms).
[08:15:47.859] <TB0> INFO: 17007000 events read in total (647375ms).
[08:16:20.739] <TB0> INFO: 17845200 events read in total (680255ms).
[08:16:53.156] <TB0> INFO: 18682850 events read in total (712672ms).
[08:17:26.382] <TB0> INFO: 19516500 events read in total (745898ms).
[08:17:59.105] <TB0> INFO: 20352900 events read in total (778621ms).
[08:18:31.457] <TB0> INFO: 21187300 events read in total (810973ms).
[08:19:03.986] <TB0> INFO: 22021850 events read in total (843502ms).
[08:19:36.211] <TB0> INFO: 22855450 events read in total (875727ms).
[08:20:08.566] <TB0> INFO: 23690650 events read in total (908082ms).
[08:20:40.505] <TB0> INFO: 24523850 events read in total (940021ms).
[08:21:12.605] <TB0> INFO: 25359800 events read in total (972121ms).
[08:21:44.928] <TB0> INFO: 26206850 events read in total (1004444ms).
[08:21:45.360] <TB0> INFO: 26208000 events read in total (1004876ms).
[08:21:45.387] <TB0> INFO: Test took 1005746ms.
[08:21:45.643] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:21:47.293] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:21:48.779] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:21:50.177] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:21:51.682] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:21:53.364] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:21:54.934] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:21:56.491] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:21:57.991] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:21:59.392] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:22:00.817] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:22:02.228] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:22:03.638] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:22:05.067] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:22:06.673] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:22:08.122] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:22:09.744] <TB0> INFO: PixTestScurves::scurves() done
[08:22:09.744] <TB0> INFO: Vcal mean: 80.80 83.86 84.19 89.49 93.17 90.89 84.11 93.76 87.85 81.84 83.53 78.89 79.01 83.16 74.64 76.90
[08:22:09.744] <TB0> INFO: Vcal RMS: 4.32 4.86 5.64 5.55 5.16 4.94 4.33 5.29 5.38 4.44 4.53 3.93 4.78 4.47 4.86 4.07
[08:22:09.744] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1030 seconds
[08:22:09.744] <TB0> INFO: Decoding statistics:
[08:22:09.744] <TB0> INFO: General information:
[08:22:09.744] <TB0> INFO: 16bit words read: 0
[08:22:09.744] <TB0> INFO: valid events total: 0
[08:22:09.744] <TB0> INFO: empty events: 0
[08:22:09.744] <TB0> INFO: valid events with pixels: 0
[08:22:09.744] <TB0> INFO: valid pixel hits: 0
[08:22:09.744] <TB0> INFO: Event errors: 0
[08:22:09.744] <TB0> INFO: start marker: 0
[08:22:09.744] <TB0> INFO: stop marker: 0
[08:22:09.744] <TB0> INFO: overflow: 0
[08:22:09.744] <TB0> INFO: invalid 5bit words: 0
[08:22:09.744] <TB0> INFO: invalid XOR eye diagram: 0
[08:22:09.744] <TB0> INFO: frame (failed synchr.): 0
[08:22:09.744] <TB0> INFO: idle data (no TBM trl): 0
[08:22:09.744] <TB0> INFO: no data (only TBM hdr): 0
[08:22:09.744] <TB0> INFO: TBM errors: 0
[08:22:09.744] <TB0> INFO: flawed TBM headers: 0
[08:22:09.744] <TB0> INFO: flawed TBM trailers: 0
[08:22:09.744] <TB0> INFO: event ID mismatches: 0
[08:22:09.744] <TB0> INFO: ROC errors: 0
[08:22:09.744] <TB0> INFO: missing ROC header(s): 0
[08:22:09.744] <TB0> INFO: misplaced readback start: 0
[08:22:09.745] <TB0> INFO: Pixel decoding errors: 0
[08:22:09.745] <TB0> INFO: pixel data incomplete: 0
[08:22:09.745] <TB0> INFO: pixel address: 0
[08:22:09.745] <TB0> INFO: pulse height fill bit: 0
[08:22:09.745] <TB0> INFO: buffer corruption: 0
[08:22:09.814] <TB0> INFO: ######################################################################
[08:22:09.814] <TB0> INFO: PixTestTrim::doTest()
[08:22:09.814] <TB0> INFO: ######################################################################
[08:22:09.815] <TB0> INFO: ----------------------------------------------------------------------
[08:22:09.815] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[08:22:09.815] <TB0> INFO: ----------------------------------------------------------------------
[08:22:09.893] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[08:22:09.893] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[08:22:09.904] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:22:09.904] <TB0> INFO: run 1 of 1
[08:22:10.171] <TB0> INFO: Expecting 6281600 events.
[08:22:49.217] <TB0> INFO: 1068650 events read in total (38490ms).
[08:23:27.984] <TB0> INFO: 2130170 events read in total (77257ms).
[08:24:06.935] <TB0> INFO: 3183150 events read in total (116208ms).
[08:24:46.153] <TB0> INFO: 4238410 events read in total (155426ms).
[08:25:25.214] <TB0> INFO: 5290710 events read in total (194487ms).
[08:26:01.932] <TB0> INFO: 6281600 events read in total (231205ms).
[08:26:01.964] <TB0> INFO: Test took 232059ms.
[08:26:23.085] <TB0> INFO: ROC 0 VthrComp = 85
[08:26:23.085] <TB0> INFO: ROC 1 VthrComp = 87
[08:26:23.085] <TB0> INFO: ROC 2 VthrComp = 89
[08:26:23.085] <TB0> INFO: ROC 3 VthrComp = 96
[08:26:23.085] <TB0> INFO: ROC 4 VthrComp = 98
[08:26:23.085] <TB0> INFO: ROC 5 VthrComp = 97
[08:26:23.085] <TB0> INFO: ROC 6 VthrComp = 92
[08:26:23.086] <TB0> INFO: ROC 7 VthrComp = 101
[08:26:23.086] <TB0> INFO: ROC 8 VthrComp = 90
[08:26:23.086] <TB0> INFO: ROC 9 VthrComp = 88
[08:26:23.086] <TB0> INFO: ROC 10 VthrComp = 91
[08:26:23.086] <TB0> INFO: ROC 11 VthrComp = 86
[08:26:23.086] <TB0> INFO: ROC 12 VthrComp = 83
[08:26:23.086] <TB0> INFO: ROC 13 VthrComp = 89
[08:26:23.086] <TB0> INFO: ROC 14 VthrComp = 79
[08:26:23.086] <TB0> INFO: ROC 15 VthrComp = 86
[08:26:23.086] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[08:26:23.086] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[08:26:23.095] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:26:23.095] <TB0> INFO: run 1 of 1
[08:26:23.371] <TB0> INFO: Expecting 6281600 events.
[08:26:58.872] <TB0> INFO: 753310 events read in total (34945ms).
[08:27:34.148] <TB0> INFO: 1505980 events read in total (70221ms).
[08:28:08.876] <TB0> INFO: 2258830 events read in total (104949ms).
[08:28:43.820] <TB0> INFO: 3012580 events read in total (139893ms).
[08:29:18.722] <TB0> INFO: 3761030 events read in total (174795ms).
[08:29:53.676] <TB0> INFO: 4505900 events read in total (209749ms).
[08:30:28.076] <TB0> INFO: 5249490 events read in total (244149ms).
[08:31:02.077] <TB0> INFO: 5992240 events read in total (278150ms).
[08:31:15.797] <TB0> INFO: 6281600 events read in total (291870ms).
[08:31:15.841] <TB0> INFO: Test took 292746ms.
[08:31:42.050] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 57.4069 for pixel 12/5 mean/min/max = 44.8176/31.9096/57.7257
[08:31:42.051] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 57.6922 for pixel 1/10 mean/min/max = 45.1042/32.4095/57.799
[08:31:42.051] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 60.8437 for pixel 0/79 mean/min/max = 46.6347/32.4195/60.8499
[08:31:42.051] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 58.5505 for pixel 10/4 mean/min/max = 44.7216/30.8794/58.5637
[08:31:42.052] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 57.4028 for pixel 0/15 mean/min/max = 44.7787/31.9696/57.5878
[08:31:42.052] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 57.4377 for pixel 51/4 mean/min/max = 44.5785/31.6996/57.4575
[08:31:42.052] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 55.6535 for pixel 51/2 mean/min/max = 44.1987/32.6576/55.7399
[08:31:42.053] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 57.0931 for pixel 25/1 mean/min/max = 44.9831/32.3946/57.5716
[08:31:42.053] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 58.6323 for pixel 0/6 mean/min/max = 45.7548/32.6248/58.8849
[08:31:42.053] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 57.2471 for pixel 18/2 mean/min/max = 45.2825/33.3173/57.2477
[08:31:42.054] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 56.5263 for pixel 22/7 mean/min/max = 44.6648/32.7714/56.5583
[08:31:42.054] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 55.7027 for pixel 14/12 mean/min/max = 43.8939/32.0182/55.7695
[08:31:42.054] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 57.9624 for pixel 3/15 mean/min/max = 44.6634/31.3452/57.9817
[08:31:42.055] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 57.2392 for pixel 38/3 mean/min/max = 45.2087/33.0675/57.3499
[08:31:42.055] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 59.7125 for pixel 10/69 mean/min/max = 46.968/34.1935/59.7425
[08:31:42.055] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 55.9149 for pixel 51/55 mean/min/max = 44.1675/32.3596/55.9755
[08:31:42.056] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:31:42.144] <TB0> INFO: Expecting 514560 events.
[08:31:55.007] <TB0> INFO: 514560 events read in total (12307ms).
[08:31:55.013] <TB0> INFO: Expecting 514560 events.
[08:32:07.783] <TB0> INFO: 514560 events read in total (12348ms).
[08:32:07.791] <TB0> INFO: Expecting 514560 events.
[08:32:20.592] <TB0> INFO: 514560 events read in total (12386ms).
[08:32:20.601] <TB0> INFO: Expecting 514560 events.
[08:32:33.458] <TB0> INFO: 514560 events read in total (12434ms).
[08:32:33.469] <TB0> INFO: Expecting 514560 events.
[08:32:46.468] <TB0> INFO: 514560 events read in total (12584ms).
[08:32:46.481] <TB0> INFO: Expecting 514560 events.
[08:32:59.395] <TB0> INFO: 514560 events read in total (12503ms).
[08:32:59.409] <TB0> INFO: Expecting 514560 events.
[08:33:12.303] <TB0> INFO: 514560 events read in total (12486ms).
[08:33:12.321] <TB0> INFO: Expecting 514560 events.
[08:33:25.249] <TB0> INFO: 514560 events read in total (12517ms).
[08:33:25.267] <TB0> INFO: Expecting 514560 events.
[08:33:38.110] <TB0> INFO: 514560 events read in total (12437ms).
[08:33:38.131] <TB0> INFO: Expecting 514560 events.
[08:33:51.067] <TB0> INFO: 514560 events read in total (12533ms).
[08:33:51.091] <TB0> INFO: Expecting 514560 events.
[08:34:03.999] <TB0> INFO: 514560 events read in total (12506ms).
[08:34:04.026] <TB0> INFO: Expecting 514560 events.
[08:34:17.013] <TB0> INFO: 514560 events read in total (12585ms).
[08:34:17.042] <TB0> INFO: Expecting 514560 events.
[08:34:29.902] <TB0> INFO: 514560 events read in total (12459ms).
[08:34:29.931] <TB0> INFO: Expecting 514560 events.
[08:34:42.934] <TB0> INFO: 514560 events read in total (12601ms).
[08:34:42.966] <TB0> INFO: Expecting 514560 events.
[08:34:55.922] <TB0> INFO: 514560 events read in total (12554ms).
[08:34:55.957] <TB0> INFO: Expecting 514560 events.
[08:35:08.882] <TB0> INFO: 514560 events read in total (12524ms).
[08:35:08.920] <TB0> INFO: Test took 206864ms.
[08:35:09.991] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[08:35:10.000] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:35:10.000] <TB0> INFO: run 1 of 1
[08:35:10.267] <TB0> INFO: Expecting 6281600 events.
[08:35:45.622] <TB0> INFO: 740850 events read in total (34799ms).
[08:36:19.983] <TB0> INFO: 1481220 events read in total (69160ms).
[08:36:54.878] <TB0> INFO: 2222340 events read in total (104055ms).
[08:37:29.589] <TB0> INFO: 2964100 events read in total (138766ms).
[08:38:04.236] <TB0> INFO: 3700320 events read in total (173413ms).
[08:38:38.290] <TB0> INFO: 4432410 events read in total (207467ms).
[08:39:12.451] <TB0> INFO: 5163390 events read in total (241628ms).
[08:39:46.687] <TB0> INFO: 5893820 events read in total (275864ms).
[08:40:04.764] <TB0> INFO: 6281600 events read in total (293941ms).
[08:40:04.831] <TB0> INFO: Test took 294831ms.
[08:40:29.553] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.952181 .. 66.031266
[08:40:29.628] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 76 (-1/-1) hits flags = 528 (plus default)
[08:40:29.638] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:40:29.638] <TB0> INFO: run 1 of 1
[08:40:29.898] <TB0> INFO: Expecting 3203200 events.
[08:41:07.082] <TB0> INFO: 856880 events read in total (36628ms).
[08:41:43.751] <TB0> INFO: 1715050 events read in total (73297ms).
[08:42:19.750] <TB0> INFO: 2567750 events read in total (109296ms).
[08:42:46.812] <TB0> INFO: 3203200 events read in total (136358ms).
[08:42:46.840] <TB0> INFO: Test took 137202ms.
[08:43:03.571] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 17.619435 .. 66.031266
[08:43:03.651] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 7 .. 76 (-1/-1) hits flags = 528 (plus default)
[08:43:03.660] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:43:03.660] <TB0> INFO: run 1 of 1
[08:43:03.925] <TB0> INFO: Expecting 2912000 events.
[08:43:40.849] <TB0> INFO: 828540 events read in total (36368ms).
[08:44:16.692] <TB0> INFO: 1657180 events read in total (72211ms).
[08:44:52.558] <TB0> INFO: 2483870 events read in total (108078ms).
[08:45:11.478] <TB0> INFO: 2912000 events read in total (126997ms).
[08:45:11.504] <TB0> INFO: Test took 127844ms.
[08:45:28.096] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 21.934473 .. 66.031266
[08:45:28.182] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 11 .. 76 (-1/-1) hits flags = 528 (plus default)
[08:45:28.194] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:45:28.194] <TB0> INFO: run 1 of 1
[08:45:28.489] <TB0> INFO: Expecting 2745600 events.
[08:46:05.012] <TB0> INFO: 811400 events read in total (35967ms).
[08:46:40.985] <TB0> INFO: 1622940 events read in total (71941ms).
[08:47:16.636] <TB0> INFO: 2433590 events read in total (107592ms).
[08:47:30.776] <TB0> INFO: 2745600 events read in total (121731ms).
[08:47:30.804] <TB0> INFO: Test took 122610ms.
[08:47:47.289] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 23.369451 .. 66.031266
[08:47:47.366] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 13 .. 76 (-1/-1) hits flags = 528 (plus default)
[08:47:47.377] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:47:47.377] <TB0> INFO: run 1 of 1
[08:47:47.640] <TB0> INFO: Expecting 2662400 events.
[08:48:24.479] <TB0> INFO: 801740 events read in total (36283ms).
[08:49:00.551] <TB0> INFO: 1603720 events read in total (72355ms).
[08:49:37.151] <TB0> INFO: 2405120 events read in total (108956ms).
[08:49:49.956] <TB0> INFO: 2662400 events read in total (121760ms).
[08:49:49.982] <TB0> INFO: Test took 122605ms.
[08:50:06.631] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[08:50:06.631] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[08:50:06.639] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:50:06.639] <TB0> INFO: run 1 of 1
[08:50:06.909] <TB0> INFO: Expecting 1705600 events.
[08:50:44.569] <TB0> INFO: 879450 events read in total (37104ms).
[08:51:19.918] <TB0> INFO: 1705600 events read in total (72453ms).
[08:51:19.932] <TB0> INFO: Test took 73294ms.
[08:51:33.674] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:51:33.674] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:51:33.674] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:51:33.674] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:51:33.675] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:51:33.675] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:51:33.675] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:51:33.675] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:51:33.675] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:51:33.675] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:51:33.675] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:51:33.675] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:51:33.675] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:51:33.676] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:51:33.676] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:51:33.676] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:51:33.676] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//trimParameters35_C0.dat
[08:51:33.683] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//trimParameters35_C1.dat
[08:51:33.690] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//trimParameters35_C2.dat
[08:51:33.696] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//trimParameters35_C3.dat
[08:51:33.702] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//trimParameters35_C4.dat
[08:51:33.708] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//trimParameters35_C5.dat
[08:51:33.715] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//trimParameters35_C6.dat
[08:51:33.721] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//trimParameters35_C7.dat
[08:51:33.727] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//trimParameters35_C8.dat
[08:51:33.733] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//trimParameters35_C9.dat
[08:51:33.739] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//trimParameters35_C10.dat
[08:51:33.746] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//trimParameters35_C11.dat
[08:51:33.753] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//trimParameters35_C12.dat
[08:51:33.760] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//trimParameters35_C13.dat
[08:51:33.767] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//trimParameters35_C14.dat
[08:51:33.773] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//trimParameters35_C15.dat
[08:51:33.780] <TB0> INFO: PixTestTrim::trimTest() done
[08:51:33.780] <TB0> INFO: vtrim: 98 103 88 104 122 93 93 101 92 107 99 98 94 101 110 100
[08:51:33.780] <TB0> INFO: vthrcomp: 85 87 89 96 98 97 92 101 90 88 91 86 83 89 79 86
[08:51:33.780] <TB0> INFO: vcal mean: 34.97 35.00 35.04 34.97 34.97 34.98 34.98 34.99 34.95 35.00 34.96 34.96 34.94 35.02 35.03 34.98
[08:51:33.780] <TB0> INFO: vcal RMS: 0.87 0.82 0.84 0.88 0.85 0.82 0.77 0.83 0.98 0.78 0.82 0.79 0.86 0.81 0.96 0.77
[08:51:33.780] <TB0> INFO: bits mean: 9.72 9.40 8.31 9.86 9.81 9.70 9.54 9.56 9.09 9.20 9.60 9.79 9.81 9.56 8.44 9.82
[08:51:33.780] <TB0> INFO: bits RMS: 2.65 2.69 3.10 2.72 2.63 2.73 2.69 2.68 2.76 2.69 2.62 2.62 2.69 2.56 2.83 2.55
[08:51:33.786] <TB0> INFO: ----------------------------------------------------------------------
[08:51:33.786] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[08:51:33.786] <TB0> INFO: ----------------------------------------------------------------------
[08:51:33.788] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[08:51:33.797] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[08:51:33.797] <TB0> INFO: run 1 of 1
[08:51:34.058] <TB0> INFO: Expecting 4160000 events.
[08:52:15.237] <TB0> INFO: 904555 events read in total (40623ms).
[08:52:55.696] <TB0> INFO: 1802290 events read in total (81083ms).
[08:53:36.331] <TB0> INFO: 2691920 events read in total (121717ms).
[08:54:16.983] <TB0> INFO: 3577550 events read in total (162369ms).
[08:54:43.759] <TB0> INFO: 4160000 events read in total (189145ms).
[08:54:43.797] <TB0> INFO: Test took 190000ms.
[08:55:11.922] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 153 (-1/-1) hits flags = 528 (plus default)
[08:55:11.931] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[08:55:11.931] <TB0> INFO: run 1 of 1
[08:55:12.192] <TB0> INFO: Expecting 3203200 events.
[08:55:55.154] <TB0> INFO: 968565 events read in total (42406ms).
[08:56:36.999] <TB0> INFO: 1924290 events read in total (84251ms).
[08:57:18.243] <TB0> INFO: 2867505 events read in total (125495ms).
[08:57:32.987] <TB0> INFO: 3203200 events read in total (140239ms).
[08:57:33.021] <TB0> INFO: Test took 141090ms.
[08:57:58.264] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 143 (-1/-1) hits flags = 528 (plus default)
[08:57:58.273] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[08:57:58.273] <TB0> INFO: run 1 of 1
[08:57:58.537] <TB0> INFO: Expecting 2995200 events.
[08:58:41.875] <TB0> INFO: 999675 events read in total (42782ms).
[08:59:24.226] <TB0> INFO: 1981745 events read in total (85133ms).
[09:00:06.833] <TB0> INFO: 2953065 events read in total (127740ms).
[09:00:08.972] <TB0> INFO: 2995200 events read in total (129879ms).
[09:00:08.997] <TB0> INFO: Test took 130724ms.
[09:00:33.654] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 143 (-1/-1) hits flags = 528 (plus default)
[09:00:33.662] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:00:33.667] <TB0> INFO: run 1 of 1
[09:00:33.927] <TB0> INFO: Expecting 2995200 events.
[09:01:16.631] <TB0> INFO: 998330 events read in total (42148ms).
[09:01:58.370] <TB0> INFO: 1979145 events read in total (83887ms).
[09:02:40.211] <TB0> INFO: 2949140 events read in total (125728ms).
[09:02:42.516] <TB0> INFO: 2995200 events read in total (128033ms).
[09:02:42.541] <TB0> INFO: Test took 128875ms.
[09:03:06.377] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 142 (-1/-1) hits flags = 528 (plus default)
[09:03:06.385] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:03:06.385] <TB0> INFO: run 1 of 1
[09:03:06.645] <TB0> INFO: Expecting 2974400 events.
[09:03:49.636] <TB0> INFO: 1000905 events read in total (42435ms).
[09:04:32.721] <TB0> INFO: 1983890 events read in total (85520ms).
[09:05:15.007] <TB0> INFO: 2957060 events read in total (127806ms).
[09:05:16.115] <TB0> INFO: 2974400 events read in total (128914ms).
[09:05:16.140] <TB0> INFO: Test took 129756ms.
[09:05:40.493] <TB0> INFO: PixTestTrim::trimBitTest() done
[09:05:40.494] <TB0> INFO: PixTestTrim::doTest() done, duration: 2610 seconds
[09:05:40.494] <TB0> INFO: Decoding statistics:
[09:05:40.494] <TB0> INFO: General information:
[09:05:40.494] <TB0> INFO: 16bit words read: 0
[09:05:40.494] <TB0> INFO: valid events total: 0
[09:05:40.494] <TB0> INFO: empty events: 0
[09:05:40.494] <TB0> INFO: valid events with pixels: 0
[09:05:40.494] <TB0> INFO: valid pixel hits: 0
[09:05:40.494] <TB0> INFO: Event errors: 0
[09:05:40.494] <TB0> INFO: start marker: 0
[09:05:40.494] <TB0> INFO: stop marker: 0
[09:05:40.494] <TB0> INFO: overflow: 0
[09:05:40.494] <TB0> INFO: invalid 5bit words: 0
[09:05:40.494] <TB0> INFO: invalid XOR eye diagram: 0
[09:05:40.494] <TB0> INFO: frame (failed synchr.): 0
[09:05:40.494] <TB0> INFO: idle data (no TBM trl): 0
[09:05:40.494] <TB0> INFO: no data (only TBM hdr): 0
[09:05:40.494] <TB0> INFO: TBM errors: 0
[09:05:40.494] <TB0> INFO: flawed TBM headers: 0
[09:05:40.494] <TB0> INFO: flawed TBM trailers: 0
[09:05:40.494] <TB0> INFO: event ID mismatches: 0
[09:05:40.494] <TB0> INFO: ROC errors: 0
[09:05:40.494] <TB0> INFO: missing ROC header(s): 0
[09:05:40.494] <TB0> INFO: misplaced readback start: 0
[09:05:40.494] <TB0> INFO: Pixel decoding errors: 0
[09:05:40.494] <TB0> INFO: pixel data incomplete: 0
[09:05:40.494] <TB0> INFO: pixel address: 0
[09:05:40.494] <TB0> INFO: pulse height fill bit: 0
[09:05:40.494] <TB0> INFO: buffer corruption: 0
[09:05:41.252] <TB0> INFO: ######################################################################
[09:05:41.253] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[09:05:41.253] <TB0> INFO: ######################################################################
[09:05:41.548] <TB0> INFO: Expecting 41600 events.
[09:05:45.329] <TB0> INFO: 41600 events read in total (3224ms).
[09:05:45.330] <TB0> INFO: Test took 4076ms.
[09:05:45.823] <TB0> INFO: Expecting 41600 events.
[09:05:49.686] <TB0> INFO: 41600 events read in total (3307ms).
[09:05:49.686] <TB0> INFO: Test took 4151ms.
[09:05:50.006] <TB0> INFO: Expecting 41600 events.
[09:05:53.829] <TB0> INFO: 41600 events read in total (3267ms).
[09:05:53.829] <TB0> INFO: Test took 4121ms.
[09:05:54.113] <TB0> INFO: Expecting 41600 events.
[09:05:57.896] <TB0> INFO: 41600 events read in total (3226ms).
[09:05:57.896] <TB0> INFO: Test took 4045ms.
[09:05:58.179] <TB0> INFO: Expecting 41600 events.
[09:06:02.013] <TB0> INFO: 41600 events read in total (3278ms).
[09:06:02.013] <TB0> INFO: Test took 4097ms.
[09:06:02.307] <TB0> INFO: Expecting 41600 events.
[09:06:06.055] <TB0> INFO: 41600 events read in total (3192ms).
[09:06:06.056] <TB0> INFO: Test took 4021ms.
[09:06:06.334] <TB0> INFO: Expecting 41600 events.
[09:06:10.134] <TB0> INFO: 41600 events read in total (3244ms).
[09:06:10.135] <TB0> INFO: Test took 4060ms.
[09:06:10.434] <TB0> INFO: Expecting 41600 events.
[09:06:14.343] <TB0> INFO: 41600 events read in total (3353ms).
[09:06:14.344] <TB0> INFO: Test took 4190ms.
[09:06:14.655] <TB0> INFO: Expecting 41600 events.
[09:06:18.442] <TB0> INFO: 41600 events read in total (3231ms).
[09:06:18.443] <TB0> INFO: Test took 4079ms.
[09:06:18.753] <TB0> INFO: Expecting 41600 events.
[09:06:22.601] <TB0> INFO: 41600 events read in total (3292ms).
[09:06:22.601] <TB0> INFO: Test took 4136ms.
[09:06:22.881] <TB0> INFO: Expecting 41600 events.
[09:06:26.646] <TB0> INFO: 41600 events read in total (3210ms).
[09:06:26.647] <TB0> INFO: Test took 4026ms.
[09:06:26.944] <TB0> INFO: Expecting 41600 events.
[09:06:30.716] <TB0> INFO: 41600 events read in total (3216ms).
[09:06:30.716] <TB0> INFO: Test took 4049ms.
[09:06:31.032] <TB0> INFO: Expecting 41600 events.
[09:06:34.837] <TB0> INFO: 41600 events read in total (3248ms).
[09:06:34.839] <TB0> INFO: Test took 4096ms.
[09:06:35.125] <TB0> INFO: Expecting 41600 events.
[09:06:39.006] <TB0> INFO: 41600 events read in total (3325ms).
[09:06:39.006] <TB0> INFO: Test took 4146ms.
[09:06:39.307] <TB0> INFO: Expecting 41600 events.
[09:06:43.157] <TB0> INFO: 41600 events read in total (3294ms).
[09:06:43.158] <TB0> INFO: Test took 4131ms.
[09:06:43.441] <TB0> INFO: Expecting 41600 events.
[09:06:47.274] <TB0> INFO: 41600 events read in total (3277ms).
[09:06:47.274] <TB0> INFO: Test took 4096ms.
[09:06:47.570] <TB0> INFO: Expecting 41600 events.
[09:06:51.323] <TB0> INFO: 41600 events read in total (3197ms).
[09:06:51.324] <TB0> INFO: Test took 4030ms.
[09:06:51.607] <TB0> INFO: Expecting 41600 events.
[09:06:55.473] <TB0> INFO: 41600 events read in total (3310ms).
[09:06:55.473] <TB0> INFO: Test took 4129ms.
[09:06:55.752] <TB0> INFO: Expecting 41600 events.
[09:06:59.534] <TB0> INFO: 41600 events read in total (3226ms).
[09:06:59.535] <TB0> INFO: Test took 4042ms.
[09:06:59.833] <TB0> INFO: Expecting 41600 events.
[09:07:03.683] <TB0> INFO: 41600 events read in total (3294ms).
[09:07:03.683] <TB0> INFO: Test took 4128ms.
[09:07:03.963] <TB0> INFO: Expecting 41600 events.
[09:07:07.759] <TB0> INFO: 41600 events read in total (3240ms).
[09:07:07.760] <TB0> INFO: Test took 4057ms.
[09:07:08.075] <TB0> INFO: Expecting 41600 events.
[09:07:11.826] <TB0> INFO: 41600 events read in total (3195ms).
[09:07:11.826] <TB0> INFO: Test took 4046ms.
[09:07:12.105] <TB0> INFO: Expecting 41600 events.
[09:07:15.935] <TB0> INFO: 41600 events read in total (3273ms).
[09:07:15.936] <TB0> INFO: Test took 4090ms.
[09:07:16.215] <TB0> INFO: Expecting 41600 events.
[09:07:19.986] <TB0> INFO: 41600 events read in total (3215ms).
[09:07:19.988] <TB0> INFO: Test took 4033ms.
[09:07:20.274] <TB0> INFO: Expecting 41600 events.
[09:07:24.092] <TB0> INFO: 41600 events read in total (3262ms).
[09:07:24.092] <TB0> INFO: Test took 4084ms.
[09:07:24.376] <TB0> INFO: Expecting 41600 events.
[09:07:28.190] <TB0> INFO: 41600 events read in total (3257ms).
[09:07:28.191] <TB0> INFO: Test took 4078ms.
[09:07:28.489] <TB0> INFO: Expecting 41600 events.
[09:07:32.289] <TB0> INFO: 41600 events read in total (3243ms).
[09:07:32.290] <TB0> INFO: Test took 4079ms.
[09:07:32.605] <TB0> INFO: Expecting 41600 events.
[09:07:36.508] <TB0> INFO: 41600 events read in total (3347ms).
[09:07:36.509] <TB0> INFO: Test took 4194ms.
[09:07:36.791] <TB0> INFO: Expecting 41600 events.
[09:07:40.895] <TB0> INFO: 41600 events read in total (3548ms).
[09:07:40.895] <TB0> INFO: Test took 4367ms.
[09:07:41.264] <TB0> INFO: Expecting 41600 events.
[09:07:45.262] <TB0> INFO: 41600 events read in total (3442ms).
[09:07:45.263] <TB0> INFO: Test took 4342ms.
[09:07:45.549] <TB0> INFO: Expecting 41600 events.
[09:07:49.361] <TB0> INFO: 41600 events read in total (3256ms).
[09:07:49.362] <TB0> INFO: Test took 4079ms.
[09:07:49.649] <TB0> INFO: Expecting 41600 events.
[09:07:53.452] <TB0> INFO: 41600 events read in total (3247ms).
[09:07:53.452] <TB0> INFO: Test took 4065ms.
[09:07:53.770] <TB0> INFO: Expecting 41600 events.
[09:07:57.682] <TB0> INFO: 41600 events read in total (3356ms).
[09:07:57.683] <TB0> INFO: Test took 4209ms.
[09:07:57.976] <TB0> INFO: Expecting 41600 events.
[09:08:01.946] <TB0> INFO: 41600 events read in total (3413ms).
[09:08:01.947] <TB0> INFO: Test took 4244ms.
[09:08:02.258] <TB0> INFO: Expecting 41600 events.
[09:08:06.034] <TB0> INFO: 41600 events read in total (3220ms).
[09:08:06.035] <TB0> INFO: Test took 4068ms.
[09:08:06.315] <TB0> INFO: Expecting 41600 events.
[09:08:10.132] <TB0> INFO: 41600 events read in total (3261ms).
[09:08:10.132] <TB0> INFO: Test took 4077ms.
[09:08:10.415] <TB0> INFO: Expecting 41600 events.
[09:08:14.298] <TB0> INFO: 41600 events read in total (3327ms).
[09:08:14.298] <TB0> INFO: Test took 4146ms.
[09:08:14.604] <TB0> INFO: Expecting 41600 events.
[09:08:18.467] <TB0> INFO: 41600 events read in total (3307ms).
[09:08:18.467] <TB0> INFO: Test took 4149ms.
[09:08:18.778] <TB0> INFO: Expecting 41600 events.
[09:08:22.663] <TB0> INFO: 41600 events read in total (3329ms).
[09:08:22.664] <TB0> INFO: Test took 4172ms.
[09:08:22.952] <TB0> INFO: Expecting 41600 events.
[09:08:26.794] <TB0> INFO: 41600 events read in total (3285ms).
[09:08:26.794] <TB0> INFO: Test took 4105ms.
[09:08:27.102] <TB0> INFO: Expecting 41600 events.
[09:08:30.910] <TB0> INFO: 41600 events read in total (3252ms).
[09:08:30.910] <TB0> INFO: Test took 4094ms.
[09:08:31.194] <TB0> INFO: Expecting 41600 events.
[09:08:34.976] <TB0> INFO: 41600 events read in total (3225ms).
[09:08:34.976] <TB0> INFO: Test took 4046ms.
[09:08:35.275] <TB0> INFO: Expecting 41600 events.
[09:08:39.114] <TB0> INFO: 41600 events read in total (3283ms).
[09:08:39.115] <TB0> INFO: Test took 4118ms.
[09:08:39.424] <TB0> INFO: Expecting 41600 events.
[09:08:43.321] <TB0> INFO: 41600 events read in total (3341ms).
[09:08:43.321] <TB0> INFO: Test took 4185ms.
[09:08:43.614] <TB0> INFO: Expecting 41600 events.
[09:08:47.531] <TB0> INFO: 41600 events read in total (3361ms).
[09:08:47.532] <TB0> INFO: Test took 4190ms.
[09:08:47.836] <TB0> INFO: Expecting 41600 events.
[09:08:51.662] <TB0> INFO: 41600 events read in total (3270ms).
[09:08:51.663] <TB0> INFO: Test took 4111ms.
[09:08:51.942] <TB0> INFO: Expecting 41600 events.
[09:08:55.888] <TB0> INFO: 41600 events read in total (3390ms).
[09:08:55.889] <TB0> INFO: Test took 4207ms.
[09:08:56.190] <TB0> INFO: Expecting 41600 events.
[09:08:59.998] <TB0> INFO: 41600 events read in total (3252ms).
[09:08:59.999] <TB0> INFO: Test took 4085ms.
[09:09:00.288] <TB0> INFO: Expecting 41600 events.
[09:09:04.120] <TB0> INFO: 41600 events read in total (3276ms).
[09:09:04.121] <TB0> INFO: Test took 4101ms.
[09:09:04.405] <TB0> INFO: Expecting 41600 events.
[09:09:08.208] <TB0> INFO: 41600 events read in total (3247ms).
[09:09:08.209] <TB0> INFO: Test took 4068ms.
[09:09:08.521] <TB0> INFO: Expecting 41600 events.
[09:09:12.362] <TB0> INFO: 41600 events read in total (3285ms).
[09:09:12.363] <TB0> INFO: Test took 4129ms.
[09:09:12.644] <TB0> INFO: Expecting 41600 events.
[09:09:16.463] <TB0> INFO: 41600 events read in total (3263ms).
[09:09:16.464] <TB0> INFO: Test took 4081ms.
[09:09:16.754] <TB0> INFO: Expecting 41600 events.
[09:09:20.583] <TB0> INFO: 41600 events read in total (3273ms).
[09:09:20.583] <TB0> INFO: Test took 4099ms.
[09:09:20.871] <TB0> INFO: Expecting 41600 events.
[09:09:24.712] <TB0> INFO: 41600 events read in total (3285ms).
[09:09:24.713] <TB0> INFO: Test took 4109ms.
[09:09:24.970] <TB0> INFO: Expecting 2560 events.
[09:09:25.822] <TB0> INFO: 2560 events read in total (296ms).
[09:09:25.822] <TB0> INFO: Test took 1094ms.
[09:09:26.127] <TB0> INFO: Expecting 2560 events.
[09:09:26.982] <TB0> INFO: 2560 events read in total (299ms).
[09:09:26.982] <TB0> INFO: Test took 1160ms.
[09:09:27.288] <TB0> INFO: Expecting 2560 events.
[09:09:28.141] <TB0> INFO: 2560 events read in total (297ms).
[09:09:28.141] <TB0> INFO: Test took 1158ms.
[09:09:28.446] <TB0> INFO: Expecting 2560 events.
[09:09:29.301] <TB0> INFO: 2560 events read in total (299ms).
[09:09:29.301] <TB0> INFO: Test took 1160ms.
[09:09:29.606] <TB0> INFO: Expecting 2560 events.
[09:09:30.461] <TB0> INFO: 2560 events read in total (299ms).
[09:09:30.461] <TB0> INFO: Test took 1160ms.
[09:09:30.766] <TB0> INFO: Expecting 2560 events.
[09:09:31.621] <TB0> INFO: 2560 events read in total (299ms).
[09:09:31.621] <TB0> INFO: Test took 1160ms.
[09:09:31.926] <TB0> INFO: Expecting 2560 events.
[09:09:32.779] <TB0> INFO: 2560 events read in total (297ms).
[09:09:32.779] <TB0> INFO: Test took 1158ms.
[09:09:33.084] <TB0> INFO: Expecting 2560 events.
[09:09:33.936] <TB0> INFO: 2560 events read in total (296ms).
[09:09:33.936] <TB0> INFO: Test took 1157ms.
[09:09:34.242] <TB0> INFO: Expecting 2560 events.
[09:09:35.094] <TB0> INFO: 2560 events read in total (296ms).
[09:09:35.095] <TB0> INFO: Test took 1158ms.
[09:09:35.400] <TB0> INFO: Expecting 2560 events.
[09:09:36.252] <TB0> INFO: 2560 events read in total (296ms).
[09:09:36.253] <TB0> INFO: Test took 1158ms.
[09:09:36.558] <TB0> INFO: Expecting 2560 events.
[09:09:37.410] <TB0> INFO: 2560 events read in total (296ms).
[09:09:37.411] <TB0> INFO: Test took 1158ms.
[09:09:37.715] <TB0> INFO: Expecting 2560 events.
[09:09:38.568] <TB0> INFO: 2560 events read in total (296ms).
[09:09:38.568] <TB0> INFO: Test took 1157ms.
[09:09:38.873] <TB0> INFO: Expecting 2560 events.
[09:09:39.728] <TB0> INFO: 2560 events read in total (299ms).
[09:09:39.728] <TB0> INFO: Test took 1159ms.
[09:09:40.033] <TB0> INFO: Expecting 2560 events.
[09:09:40.888] <TB0> INFO: 2560 events read in total (298ms).
[09:09:40.888] <TB0> INFO: Test took 1159ms.
[09:09:41.193] <TB0> INFO: Expecting 2560 events.
[09:09:42.048] <TB0> INFO: 2560 events read in total (299ms).
[09:09:42.048] <TB0> INFO: Test took 1159ms.
[09:09:42.353] <TB0> INFO: Expecting 2560 events.
[09:09:43.207] <TB0> INFO: 2560 events read in total (298ms).
[09:09:43.207] <TB0> INFO: Test took 1158ms.
[09:09:43.210] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:09:43.513] <TB0> INFO: Expecting 655360 events.
[09:10:00.146] <TB0> INFO: 655360 events read in total (16077ms).
[09:10:00.155] <TB0> INFO: Expecting 655360 events.
[09:10:16.699] <TB0> INFO: 655360 events read in total (16143ms).
[09:10:16.711] <TB0> INFO: Expecting 655360 events.
[09:10:33.349] <TB0> INFO: 655360 events read in total (16237ms).
[09:10:33.365] <TB0> INFO: Expecting 655360 events.
[09:10:49.929] <TB0> INFO: 655360 events read in total (16163ms).
[09:10:49.949] <TB0> INFO: Expecting 655360 events.
[09:11:06.674] <TB0> INFO: 655360 events read in total (16323ms).
[09:11:06.697] <TB0> INFO: Expecting 655360 events.
[09:11:23.459] <TB0> INFO: 655360 events read in total (16361ms).
[09:11:23.488] <TB0> INFO: Expecting 655360 events.
[09:11:40.057] <TB0> INFO: 655360 events read in total (16167ms).
[09:11:40.085] <TB0> INFO: Expecting 655360 events.
[09:11:56.907] <TB0> INFO: 655360 events read in total (16420ms).
[09:11:56.950] <TB0> INFO: Expecting 655360 events.
[09:12:13.577] <TB0> INFO: 655360 events read in total (16227ms).
[09:12:13.625] <TB0> INFO: Expecting 655360 events.
[09:12:30.239] <TB0> INFO: 655360 events read in total (16212ms).
[09:12:30.287] <TB0> INFO: Expecting 655360 events.
[09:12:46.958] <TB0> INFO: 655360 events read in total (16269ms).
[09:12:47.003] <TB0> INFO: Expecting 655360 events.
[09:13:03.630] <TB0> INFO: 655360 events read in total (16225ms).
[09:13:03.683] <TB0> INFO: Expecting 655360 events.
[09:13:20.659] <TB0> INFO: 655360 events read in total (16574ms).
[09:13:20.713] <TB0> INFO: Expecting 655360 events.
[09:13:37.271] <TB0> INFO: 655360 events read in total (16156ms).
[09:13:37.330] <TB0> INFO: Expecting 655360 events.
[09:13:54.103] <TB0> INFO: 655360 events read in total (16371ms).
[09:13:54.172] <TB0> INFO: Expecting 655360 events.
[09:14:10.758] <TB0> INFO: 655360 events read in total (16184ms).
[09:14:10.825] <TB0> INFO: Test took 267615ms.
[09:14:10.906] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:14:11.135] <TB0> INFO: Expecting 655360 events.
[09:14:27.939] <TB0> INFO: 655360 events read in total (16248ms).
[09:14:27.949] <TB0> INFO: Expecting 655360 events.
[09:14:44.728] <TB0> INFO: 655360 events read in total (16377ms).
[09:14:44.742] <TB0> INFO: Expecting 655360 events.
[09:15:01.344] <TB0> INFO: 655360 events read in total (16201ms).
[09:15:01.362] <TB0> INFO: Expecting 655360 events.
[09:15:18.030] <TB0> INFO: 655360 events read in total (16266ms).
[09:15:18.052] <TB0> INFO: Expecting 655360 events.
[09:15:34.745] <TB0> INFO: 655360 events read in total (16291ms).
[09:15:34.778] <TB0> INFO: Expecting 655360 events.
[09:15:51.560] <TB0> INFO: 655360 events read in total (16380ms).
[09:15:51.593] <TB0> INFO: Expecting 655360 events.
[09:16:08.277] <TB0> INFO: 655360 events read in total (16282ms).
[09:16:08.308] <TB0> INFO: Expecting 655360 events.
[09:16:24.869] <TB0> INFO: 655360 events read in total (16159ms).
[09:16:24.905] <TB0> INFO: Expecting 655360 events.
[09:16:41.518] <TB0> INFO: 655360 events read in total (16212ms).
[09:16:41.560] <TB0> INFO: Expecting 655360 events.
[09:16:58.352] <TB0> INFO: 655360 events read in total (16390ms).
[09:16:58.395] <TB0> INFO: Expecting 655360 events.
[09:17:15.136] <TB0> INFO: 655360 events read in total (16339ms).
[09:17:15.193] <TB0> INFO: Expecting 655360 events.
[09:17:31.818] <TB0> INFO: 655360 events read in total (16223ms).
[09:17:31.871] <TB0> INFO: Expecting 655360 events.
[09:17:48.507] <TB0> INFO: 655360 events read in total (16234ms).
[09:17:48.562] <TB0> INFO: Expecting 655360 events.
[09:18:05.111] <TB0> INFO: 655360 events read in total (16147ms).
[09:18:05.176] <TB0> INFO: Expecting 655360 events.
[09:18:21.709] <TB0> INFO: 655360 events read in total (16131ms).
[09:18:21.781] <TB0> INFO: Expecting 655360 events.
[09:18:38.313] <TB0> INFO: 655360 events read in total (16130ms).
[09:18:38.379] <TB0> INFO: Test took 267473ms.
[09:18:38.577] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:18:38.584] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:18:38.591] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:18:38.599] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:18:38.607] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[09:18:38.614] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[09:18:38.622] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:18:38.629] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:18:38.637] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:18:38.644] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:18:38.652] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:18:38.659] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:18:38.667] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:18:38.674] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:18:38.682] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:18:38.690] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:18:38.697] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:18:38.705] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:18:38.742] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:18:38.742] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:18:38.742] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:18:38.742] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:18:38.742] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:18:38.742] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:18:38.742] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:18:38.742] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:18:38.743] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:18:38.743] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:18:38.745] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:18:38.745] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:18:38.745] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:18:38.745] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:18:38.745] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:18:38.745] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:18:39.017] <TB0> INFO: Expecting 41600 events.
[09:18:42.495] <TB0> INFO: 41600 events read in total (2922ms).
[09:18:42.496] <TB0> INFO: Test took 3749ms.
[09:18:42.937] <TB0> INFO: Expecting 41600 events.
[09:18:46.424] <TB0> INFO: 41600 events read in total (2932ms).
[09:18:46.425] <TB0> INFO: Test took 3753ms.
[09:18:46.863] <TB0> INFO: Expecting 41600 events.
[09:18:50.340] <TB0> INFO: 41600 events read in total (2921ms).
[09:18:50.341] <TB0> INFO: Test took 3737ms.
[09:18:50.525] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:18:50.613] <TB0> INFO: Expecting 2560 events.
[09:18:51.466] <TB0> INFO: 2560 events read in total (297ms).
[09:18:51.466] <TB0> INFO: Test took 941ms.
[09:18:51.468] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:18:51.772] <TB0> INFO: Expecting 2560 events.
[09:18:52.627] <TB0> INFO: 2560 events read in total (298ms).
[09:18:52.627] <TB0> INFO: Test took 1159ms.
[09:18:52.629] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:18:52.933] <TB0> INFO: Expecting 2560 events.
[09:18:53.786] <TB0> INFO: 2560 events read in total (298ms).
[09:18:53.787] <TB0> INFO: Test took 1158ms.
[09:18:53.790] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:18:54.091] <TB0> INFO: Expecting 2560 events.
[09:18:54.946] <TB0> INFO: 2560 events read in total (298ms).
[09:18:54.947] <TB0> INFO: Test took 1157ms.
[09:18:54.949] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:18:55.252] <TB0> INFO: Expecting 2560 events.
[09:18:56.105] <TB0> INFO: 2560 events read in total (297ms).
[09:18:56.106] <TB0> INFO: Test took 1157ms.
[09:18:56.108] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:18:56.411] <TB0> INFO: Expecting 2560 events.
[09:18:57.264] <TB0> INFO: 2560 events read in total (297ms).
[09:18:57.264] <TB0> INFO: Test took 1156ms.
[09:18:57.266] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:18:57.570] <TB0> INFO: Expecting 2560 events.
[09:18:58.426] <TB0> INFO: 2560 events read in total (299ms).
[09:18:58.426] <TB0> INFO: Test took 1160ms.
[09:18:58.431] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:18:58.731] <TB0> INFO: Expecting 2560 events.
[09:18:59.587] <TB0> INFO: 2560 events read in total (300ms).
[09:18:59.587] <TB0> INFO: Test took 1156ms.
[09:18:59.590] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:18:59.892] <TB0> INFO: Expecting 2560 events.
[09:19:00.745] <TB0> INFO: 2560 events read in total (297ms).
[09:19:00.746] <TB0> INFO: Test took 1156ms.
[09:19:00.747] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:01.051] <TB0> INFO: Expecting 2560 events.
[09:19:01.910] <TB0> INFO: 2560 events read in total (302ms).
[09:19:01.910] <TB0> INFO: Test took 1163ms.
[09:19:01.912] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:02.215] <TB0> INFO: Expecting 2560 events.
[09:19:03.069] <TB0> INFO: 2560 events read in total (297ms).
[09:19:03.071] <TB0> INFO: Test took 1159ms.
[09:19:03.074] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:03.375] <TB0> INFO: Expecting 2560 events.
[09:19:04.227] <TB0> INFO: 2560 events read in total (296ms).
[09:19:04.228] <TB0> INFO: Test took 1154ms.
[09:19:04.229] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:04.533] <TB0> INFO: Expecting 2560 events.
[09:19:05.386] <TB0> INFO: 2560 events read in total (297ms).
[09:19:05.386] <TB0> INFO: Test took 1157ms.
[09:19:05.388] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:05.692] <TB0> INFO: Expecting 2560 events.
[09:19:06.545] <TB0> INFO: 2560 events read in total (297ms).
[09:19:06.545] <TB0> INFO: Test took 1157ms.
[09:19:06.547] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:06.851] <TB0> INFO: Expecting 2560 events.
[09:19:07.703] <TB0> INFO: 2560 events read in total (296ms).
[09:19:07.703] <TB0> INFO: Test took 1156ms.
[09:19:07.706] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:08.009] <TB0> INFO: Expecting 2560 events.
[09:19:08.861] <TB0> INFO: 2560 events read in total (296ms).
[09:19:08.861] <TB0> INFO: Test took 1155ms.
[09:19:08.863] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:09.167] <TB0> INFO: Expecting 2560 events.
[09:19:10.020] <TB0> INFO: 2560 events read in total (297ms).
[09:19:10.020] <TB0> INFO: Test took 1157ms.
[09:19:10.022] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:10.327] <TB0> INFO: Expecting 2560 events.
[09:19:11.180] <TB0> INFO: 2560 events read in total (297ms).
[09:19:11.180] <TB0> INFO: Test took 1158ms.
[09:19:11.182] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:11.485] <TB0> INFO: Expecting 2560 events.
[09:19:12.340] <TB0> INFO: 2560 events read in total (299ms).
[09:19:12.340] <TB0> INFO: Test took 1158ms.
[09:19:12.342] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:12.646] <TB0> INFO: Expecting 2560 events.
[09:19:13.502] <TB0> INFO: 2560 events read in total (300ms).
[09:19:13.503] <TB0> INFO: Test took 1161ms.
[09:19:13.505] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:13.808] <TB0> INFO: Expecting 2560 events.
[09:19:14.662] <TB0> INFO: 2560 events read in total (298ms).
[09:19:14.662] <TB0> INFO: Test took 1157ms.
[09:19:14.665] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:14.968] <TB0> INFO: Expecting 2560 events.
[09:19:15.826] <TB0> INFO: 2560 events read in total (302ms).
[09:19:15.827] <TB0> INFO: Test took 1163ms.
[09:19:15.830] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:16.132] <TB0> INFO: Expecting 2560 events.
[09:19:16.990] <TB0> INFO: 2560 events read in total (301ms).
[09:19:16.991] <TB0> INFO: Test took 1161ms.
[09:19:16.994] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:17.296] <TB0> INFO: Expecting 2560 events.
[09:19:18.154] <TB0> INFO: 2560 events read in total (301ms).
[09:19:18.155] <TB0> INFO: Test took 1161ms.
[09:19:18.157] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:18.459] <TB0> INFO: Expecting 2560 events.
[09:19:19.313] <TB0> INFO: 2560 events read in total (297ms).
[09:19:19.313] <TB0> INFO: Test took 1156ms.
[09:19:19.315] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:19.618] <TB0> INFO: Expecting 2560 events.
[09:19:20.472] <TB0> INFO: 2560 events read in total (298ms).
[09:19:20.472] <TB0> INFO: Test took 1157ms.
[09:19:20.475] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:20.778] <TB0> INFO: Expecting 2560 events.
[09:19:21.630] <TB0> INFO: 2560 events read in total (296ms).
[09:19:21.630] <TB0> INFO: Test took 1155ms.
[09:19:21.632] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:21.937] <TB0> INFO: Expecting 2560 events.
[09:19:22.792] <TB0> INFO: 2560 events read in total (299ms).
[09:19:22.792] <TB0> INFO: Test took 1160ms.
[09:19:22.795] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:23.097] <TB0> INFO: Expecting 2560 events.
[09:19:23.955] <TB0> INFO: 2560 events read in total (301ms).
[09:19:23.956] <TB0> INFO: Test took 1161ms.
[09:19:23.957] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:24.261] <TB0> INFO: Expecting 2560 events.
[09:19:25.120] <TB0> INFO: 2560 events read in total (303ms).
[09:19:25.121] <TB0> INFO: Test took 1164ms.
[09:19:25.123] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:25.429] <TB0> INFO: Expecting 2560 events.
[09:19:26.281] <TB0> INFO: 2560 events read in total (296ms).
[09:19:26.281] <TB0> INFO: Test took 1158ms.
[09:19:26.283] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:19:26.587] <TB0> INFO: Expecting 2560 events.
[09:19:27.441] <TB0> INFO: 2560 events read in total (298ms).
[09:19:27.441] <TB0> INFO: Test took 1158ms.
[09:19:27.904] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 826 seconds
[09:19:27.904] <TB0> INFO: PH scale (per ROC): 78 81 91 80 76 80 86 80 80 87 94 80 80 87 84 81
[09:19:27.904] <TB0> INFO: PH offset (per ROC): 151 150 149 161 172 148 138 138 144 150 148 156 150 145 145 151
[09:19:27.908] <TB0> INFO: Decoding statistics:
[09:19:27.908] <TB0> INFO: General information:
[09:19:27.908] <TB0> INFO: 16bit words read: 91924
[09:19:27.908] <TB0> INFO: valid events total: 10240
[09:19:27.908] <TB0> INFO: empty events: 7739
[09:19:27.908] <TB0> INFO: valid events with pixels: 2501
[09:19:27.908] <TB0> INFO: valid pixel hits: 2501
[09:19:27.908] <TB0> INFO: Event errors: 0
[09:19:27.908] <TB0> INFO: start marker: 0
[09:19:27.908] <TB0> INFO: stop marker: 0
[09:19:27.908] <TB0> INFO: overflow: 0
[09:19:27.908] <TB0> INFO: invalid 5bit words: 0
[09:19:27.908] <TB0> INFO: invalid XOR eye diagram: 0
[09:19:27.908] <TB0> INFO: frame (failed synchr.): 0
[09:19:27.908] <TB0> INFO: idle data (no TBM trl): 0
[09:19:27.908] <TB0> INFO: no data (only TBM hdr): 0
[09:19:27.908] <TB0> INFO: TBM errors: 0
[09:19:27.908] <TB0> INFO: flawed TBM headers: 0
[09:19:27.908] <TB0> INFO: flawed TBM trailers: 0
[09:19:27.908] <TB0> INFO: event ID mismatches: 0
[09:19:27.908] <TB0> INFO: ROC errors: 0
[09:19:27.908] <TB0> INFO: missing ROC header(s): 0
[09:19:27.908] <TB0> INFO: misplaced readback start: 0
[09:19:27.908] <TB0> INFO: Pixel decoding errors: 0
[09:19:27.908] <TB0> INFO: pixel data incomplete: 0
[09:19:27.908] <TB0> INFO: pixel address: 0
[09:19:27.908] <TB0> INFO: pulse height fill bit: 0
[09:19:27.908] <TB0> INFO: buffer corruption: 0
[09:19:28.097] <TB0> INFO: ######################################################################
[09:19:28.097] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[09:19:28.097] <TB0> INFO: ######################################################################
[09:19:28.107] <TB0> INFO: scanning low vcal = 10
[09:19:28.436] <TB0> INFO: Expecting 41600 events.
[09:19:31.964] <TB0> INFO: 41600 events read in total (2972ms).
[09:19:31.964] <TB0> INFO: Test took 3857ms.
[09:19:31.965] <TB0> INFO: scanning low vcal = 20
[09:19:32.269] <TB0> INFO: Expecting 41600 events.
[09:19:35.820] <TB0> INFO: 41600 events read in total (2995ms).
[09:19:35.820] <TB0> INFO: Test took 3855ms.
[09:19:35.822] <TB0> INFO: scanning low vcal = 30
[09:19:36.125] <TB0> INFO: Expecting 41600 events.
[09:19:39.672] <TB0> INFO: 41600 events read in total (2991ms).
[09:19:39.673] <TB0> INFO: Test took 3851ms.
[09:19:39.675] <TB0> INFO: scanning low vcal = 40
[09:19:39.970] <TB0> INFO: Expecting 41600 events.
[09:19:44.092] <TB0> INFO: 41600 events read in total (3566ms).
[09:19:44.093] <TB0> INFO: Test took 4418ms.
[09:19:44.096] <TB0> INFO: scanning low vcal = 50
[09:19:44.369] <TB0> INFO: Expecting 41600 events.
[09:19:48.571] <TB0> INFO: 41600 events read in total (3646ms).
[09:19:48.571] <TB0> INFO: Test took 4475ms.
[09:19:48.574] <TB0> INFO: scanning low vcal = 60
[09:19:48.837] <TB0> INFO: Expecting 41600 events.
[09:19:53.051] <TB0> INFO: 41600 events read in total (3657ms).
[09:19:53.051] <TB0> INFO: Test took 4477ms.
[09:19:53.054] <TB0> INFO: scanning low vcal = 70
[09:19:53.329] <TB0> INFO: Expecting 41600 events.
[09:19:57.550] <TB0> INFO: 41600 events read in total (3665ms).
[09:19:57.551] <TB0> INFO: Test took 4497ms.
[09:19:57.553] <TB0> INFO: scanning low vcal = 80
[09:19:57.840] <TB0> INFO: Expecting 41600 events.
[09:20:02.025] <TB0> INFO: 41600 events read in total (3628ms).
[09:20:02.026] <TB0> INFO: Test took 4472ms.
[09:20:02.028] <TB0> INFO: scanning low vcal = 90
[09:20:02.320] <TB0> INFO: Expecting 41600 events.
[09:20:06.505] <TB0> INFO: 41600 events read in total (3629ms).
[09:20:06.505] <TB0> INFO: Test took 4476ms.
[09:20:06.508] <TB0> INFO: scanning low vcal = 100
[09:20:06.770] <TB0> INFO: Expecting 41600 events.
[09:20:10.949] <TB0> INFO: 41600 events read in total (3622ms).
[09:20:10.950] <TB0> INFO: Test took 4442ms.
[09:20:10.952] <TB0> INFO: scanning low vcal = 110
[09:20:11.215] <TB0> INFO: Expecting 41600 events.
[09:20:15.393] <TB0> INFO: 41600 events read in total (3622ms).
[09:20:15.394] <TB0> INFO: Test took 4442ms.
[09:20:15.396] <TB0> INFO: scanning low vcal = 120
[09:20:15.667] <TB0> INFO: Expecting 41600 events.
[09:20:19.862] <TB0> INFO: 41600 events read in total (3639ms).
[09:20:19.862] <TB0> INFO: Test took 4466ms.
[09:20:19.864] <TB0> INFO: scanning low vcal = 130
[09:20:20.127] <TB0> INFO: Expecting 41600 events.
[09:20:24.354] <TB0> INFO: 41600 events read in total (3671ms).
[09:20:24.354] <TB0> INFO: Test took 4490ms.
[09:20:24.357] <TB0> INFO: scanning low vcal = 140
[09:20:24.630] <TB0> INFO: Expecting 41600 events.
[09:20:28.852] <TB0> INFO: 41600 events read in total (3666ms).
[09:20:28.852] <TB0> INFO: Test took 4495ms.
[09:20:28.855] <TB0> INFO: scanning low vcal = 150
[09:20:29.117] <TB0> INFO: Expecting 41600 events.
[09:20:33.355] <TB0> INFO: 41600 events read in total (3682ms).
[09:20:33.356] <TB0> INFO: Test took 4501ms.
[09:20:33.358] <TB0> INFO: scanning low vcal = 160
[09:20:33.632] <TB0> INFO: Expecting 41600 events.
[09:20:37.859] <TB0> INFO: 41600 events read in total (3671ms).
[09:20:37.859] <TB0> INFO: Test took 4501ms.
[09:20:37.862] <TB0> INFO: scanning low vcal = 170
[09:20:38.126] <TB0> INFO: Expecting 41600 events.
[09:20:42.323] <TB0> INFO: 41600 events read in total (3641ms).
[09:20:42.324] <TB0> INFO: Test took 4462ms.
[09:20:42.328] <TB0> INFO: scanning low vcal = 180
[09:20:42.589] <TB0> INFO: Expecting 41600 events.
[09:20:46.778] <TB0> INFO: 41600 events read in total (3633ms).
[09:20:46.778] <TB0> INFO: Test took 4450ms.
[09:20:46.781] <TB0> INFO: scanning low vcal = 190
[09:20:47.046] <TB0> INFO: Expecting 41600 events.
[09:20:51.256] <TB0> INFO: 41600 events read in total (3654ms).
[09:20:51.256] <TB0> INFO: Test took 4475ms.
[09:20:51.259] <TB0> INFO: scanning low vcal = 200
[09:20:51.543] <TB0> INFO: Expecting 41600 events.
[09:20:55.800] <TB0> INFO: 41600 events read in total (3701ms).
[09:20:55.801] <TB0> INFO: Test took 4542ms.
[09:20:55.803] <TB0> INFO: scanning low vcal = 210
[09:20:56.067] <TB0> INFO: Expecting 41600 events.
[09:21:00.248] <TB0> INFO: 41600 events read in total (3625ms).
[09:21:00.250] <TB0> INFO: Test took 4447ms.
[09:21:00.252] <TB0> INFO: scanning low vcal = 220
[09:21:00.534] <TB0> INFO: Expecting 41600 events.
[09:21:04.731] <TB0> INFO: 41600 events read in total (3640ms).
[09:21:04.732] <TB0> INFO: Test took 4479ms.
[09:21:04.735] <TB0> INFO: scanning low vcal = 230
[09:21:04.998] <TB0> INFO: Expecting 41600 events.
[09:21:09.199] <TB0> INFO: 41600 events read in total (3645ms).
[09:21:09.199] <TB0> INFO: Test took 4464ms.
[09:21:09.202] <TB0> INFO: scanning low vcal = 240
[09:21:09.473] <TB0> INFO: Expecting 41600 events.
[09:21:13.667] <TB0> INFO: 41600 events read in total (3638ms).
[09:21:13.668] <TB0> INFO: Test took 4466ms.
[09:21:13.670] <TB0> INFO: scanning low vcal = 250
[09:21:13.936] <TB0> INFO: Expecting 41600 events.
[09:21:18.134] <TB0> INFO: 41600 events read in total (3642ms).
[09:21:18.135] <TB0> INFO: Test took 4464ms.
[09:21:18.139] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[09:21:18.427] <TB0> INFO: Expecting 41600 events.
[09:21:22.640] <TB0> INFO: 41600 events read in total (3657ms).
[09:21:22.640] <TB0> INFO: Test took 4501ms.
[09:21:22.643] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[09:21:22.915] <TB0> INFO: Expecting 41600 events.
[09:21:27.110] <TB0> INFO: 41600 events read in total (3638ms).
[09:21:27.110] <TB0> INFO: Test took 4467ms.
[09:21:27.113] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[09:21:27.396] <TB0> INFO: Expecting 41600 events.
[09:21:31.609] <TB0> INFO: 41600 events read in total (3657ms).
[09:21:31.610] <TB0> INFO: Test took 4497ms.
[09:21:31.612] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[09:21:31.877] <TB0> INFO: Expecting 41600 events.
[09:21:36.062] <TB0> INFO: 41600 events read in total (3629ms).
[09:21:36.062] <TB0> INFO: Test took 4450ms.
[09:21:36.065] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[09:21:36.326] <TB0> INFO: Expecting 41600 events.
[09:21:40.548] <TB0> INFO: 41600 events read in total (3666ms).
[09:21:40.549] <TB0> INFO: Test took 4484ms.
[09:21:41.066] <TB0> INFO: PixTestGainPedestal::measure() done
[09:22:13.438] <TB0> INFO: PixTestGainPedestal::fit() done
[09:22:13.438] <TB0> INFO: non-linearity mean: 0.950 0.958 0.955 0.955 0.955 0.956 0.949 0.955 0.957 0.953 0.957 0.953 0.948 0.953 0.953 0.945
[09:22:13.438] <TB0> INFO: non-linearity RMS: 0.005 0.006 0.005 0.004 0.006 0.006 0.006 0.005 0.004 0.005 0.005 0.005 0.006 0.005 0.007 0.006
[09:22:13.439] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[09:22:13.459] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[09:22:13.478] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[09:22:13.504] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[09:22:13.524] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[09:22:13.544] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[09:22:13.565] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[09:22:13.585] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[09:22:13.605] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[09:22:13.630] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[09:22:13.650] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[09:22:13.670] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[09:22:13.692] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[09:22:13.712] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[09:22:13.732] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[09:22:13.755] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[09:22:13.776] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 165 seconds
[09:22:13.776] <TB0> INFO: Decoding statistics:
[09:22:13.776] <TB0> INFO: General information:
[09:22:13.776] <TB0> INFO: 16bit words read: 2662400
[09:22:13.776] <TB0> INFO: valid events total: 166400
[09:22:13.776] <TB0> INFO: empty events: 0
[09:22:13.776] <TB0> INFO: valid events with pixels: 166400
[09:22:13.776] <TB0> INFO: valid pixel hits: 665600
[09:22:13.776] <TB0> INFO: Event errors: 0
[09:22:13.776] <TB0> INFO: start marker: 0
[09:22:13.776] <TB0> INFO: stop marker: 0
[09:22:13.776] <TB0> INFO: overflow: 0
[09:22:13.776] <TB0> INFO: invalid 5bit words: 0
[09:22:13.776] <TB0> INFO: invalid XOR eye diagram: 0
[09:22:13.776] <TB0> INFO: frame (failed synchr.): 0
[09:22:13.776] <TB0> INFO: idle data (no TBM trl): 0
[09:22:13.776] <TB0> INFO: no data (only TBM hdr): 0
[09:22:13.776] <TB0> INFO: TBM errors: 0
[09:22:13.776] <TB0> INFO: flawed TBM headers: 0
[09:22:13.776] <TB0> INFO: flawed TBM trailers: 0
[09:22:13.776] <TB0> INFO: event ID mismatches: 0
[09:22:13.776] <TB0> INFO: ROC errors: 0
[09:22:13.776] <TB0> INFO: missing ROC header(s): 0
[09:22:13.776] <TB0> INFO: misplaced readback start: 0
[09:22:13.776] <TB0> INFO: Pixel decoding errors: 0
[09:22:13.776] <TB0> INFO: pixel data incomplete: 0
[09:22:13.776] <TB0> INFO: pixel address: 0
[09:22:13.776] <TB0> INFO: pulse height fill bit: 0
[09:22:13.776] <TB0> INFO: buffer corruption: 0
[09:22:13.789] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C15.dat
[09:22:13.790] <TB0> INFO: ######################################################################
[09:22:13.790] <TB0> INFO: PixTestReadback::doTest()
[09:22:13.790] <TB0> INFO: ######################################################################
[09:22:13.790] <TB0> INFO: ----------------------------------------------------------------------
[09:22:13.790] <TB0> INFO: PixTestReadback::CalibrateVd()
[09:22:13.790] <TB0> INFO: ----------------------------------------------------------------------
[09:22:23.253] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C0.dat
[09:22:23.253] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C1.dat
[09:22:23.253] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C2.dat
[09:22:23.253] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C3.dat
[09:22:23.253] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C4.dat
[09:22:23.253] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C5.dat
[09:22:23.253] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C6.dat
[09:22:23.253] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C7.dat
[09:22:23.253] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C8.dat
[09:22:23.253] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C9.dat
[09:22:23.253] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C10.dat
[09:22:23.254] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C11.dat
[09:22:23.254] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C12.dat
[09:22:23.254] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C13.dat
[09:22:23.254] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C14.dat
[09:22:23.254] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C15.dat
[09:22:23.276] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[09:22:23.276] <TB0> INFO: ----------------------------------------------------------------------
[09:22:23.276] <TB0> INFO: PixTestReadback::CalibrateVa()
[09:22:23.276] <TB0> INFO: ----------------------------------------------------------------------
[09:22:33.809] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C0.dat
[09:22:33.810] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C1.dat
[09:22:33.810] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C2.dat
[09:22:33.810] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C3.dat
[09:22:33.810] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C4.dat
[09:22:33.810] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C5.dat
[09:22:33.810] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C6.dat
[09:22:33.810] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C7.dat
[09:22:33.810] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C8.dat
[09:22:33.810] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C9.dat
[09:22:33.811] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C10.dat
[09:22:33.811] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C11.dat
[09:22:33.811] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C12.dat
[09:22:33.811] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C13.dat
[09:22:33.811] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C14.dat
[09:22:33.811] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C15.dat
[09:22:33.837] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[09:22:33.837] <TB0> INFO: ----------------------------------------------------------------------
[09:22:33.837] <TB0> INFO: PixTestReadback::readbackVbg()
[09:22:33.837] <TB0> INFO: ----------------------------------------------------------------------
[09:22:41.138] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[09:22:41.138] <TB0> INFO: ----------------------------------------------------------------------
[09:22:41.138] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[09:22:41.138] <TB0> INFO: ----------------------------------------------------------------------
[09:22:41.138] <TB0> INFO: Vbg will be calibrated using Vd calibration
[09:22:41.138] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 146.9calibrated Vbg = 1.22607 :::*/*/*/*/
[09:22:41.138] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 148.1calibrated Vbg = 1.22113 :::*/*/*/*/
[09:22:41.138] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 157.4calibrated Vbg = 1.2273 :::*/*/*/*/
[09:22:41.138] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 154.3calibrated Vbg = 1.23124 :::*/*/*/*/
[09:22:41.138] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 156.7calibrated Vbg = 1.23483 :::*/*/*/*/
[09:22:41.138] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 150.1calibrated Vbg = 1.23459 :::*/*/*/*/
[09:22:41.138] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 144.6calibrated Vbg = 1.24286 :::*/*/*/*/
[09:22:41.138] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 156.9calibrated Vbg = 1.23365 :::*/*/*/*/
[09:22:41.138] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 140.3calibrated Vbg = 1.24621 :::*/*/*/*/
[09:22:41.138] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 143.9calibrated Vbg = 1.24306 :::*/*/*/*/
[09:22:41.138] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 163.2calibrated Vbg = 1.24566 :::*/*/*/*/
[09:22:41.138] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 145.2calibrated Vbg = 1.24326 :::*/*/*/*/
[09:22:41.138] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 155.2calibrated Vbg = 1.22833 :::*/*/*/*/
[09:22:41.138] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 155.5calibrated Vbg = 1.22512 :::*/*/*/*/
[09:22:41.138] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 154.6calibrated Vbg = 1.22688 :::*/*/*/*/
[09:22:41.138] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 160.8calibrated Vbg = 1.22166 :::*/*/*/*/
[09:22:41.140] <TB0> INFO: ----------------------------------------------------------------------
[09:22:41.140] <TB0> INFO: PixTestReadback::CalibrateIa()
[09:22:41.140] <TB0> INFO: ----------------------------------------------------------------------
[09:25:16.368] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C0.dat
[09:25:16.370] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C1.dat
[09:25:16.370] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C2.dat
[09:25:16.370] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C3.dat
[09:25:16.370] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C4.dat
[09:25:16.370] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C5.dat
[09:25:16.370] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C6.dat
[09:25:16.370] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C7.dat
[09:25:16.371] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C8.dat
[09:25:16.371] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C9.dat
[09:25:16.371] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C10.dat
[09:25:16.371] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C11.dat
[09:25:16.371] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C12.dat
[09:25:16.371] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C13.dat
[09:25:16.371] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C14.dat
[09:25:16.371] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//000_FulltestPxar_m20//readbackCal_C15.dat
[09:25:16.391] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[09:25:16.392] <TB0> INFO: PixTestReadback::doTest() done
[09:25:16.392] <TB0> INFO: Decoding statistics:
[09:25:16.392] <TB0> INFO: General information:
[09:25:16.392] <TB0> INFO: 16bit words read: 1024
[09:25:16.392] <TB0> INFO: valid events total: 128
[09:25:16.392] <TB0> INFO: empty events: 128
[09:25:16.392] <TB0> INFO: valid events with pixels: 0
[09:25:16.392] <TB0> INFO: valid pixel hits: 0
[09:25:16.392] <TB0> INFO: Event errors: 0
[09:25:16.392] <TB0> INFO: start marker: 0
[09:25:16.392] <TB0> INFO: stop marker: 0
[09:25:16.392] <TB0> INFO: overflow: 0
[09:25:16.392] <TB0> INFO: invalid 5bit words: 0
[09:25:16.392] <TB0> INFO: invalid XOR eye diagram: 0
[09:25:16.392] <TB0> INFO: frame (failed synchr.): 0
[09:25:16.392] <TB0> INFO: idle data (no TBM trl): 0
[09:25:16.392] <TB0> INFO: no data (only TBM hdr): 0
[09:25:16.392] <TB0> INFO: TBM errors: 0
[09:25:16.392] <TB0> INFO: flawed TBM headers: 0
[09:25:16.392] <TB0> INFO: flawed TBM trailers: 0
[09:25:16.392] <TB0> INFO: event ID mismatches: 0
[09:25:16.393] <TB0> INFO: ROC errors: 0
[09:25:16.393] <TB0> INFO: missing ROC header(s): 0
[09:25:16.393] <TB0> INFO: misplaced readback start: 0
[09:25:16.393] <TB0> INFO: Pixel decoding errors: 0
[09:25:16.393] <TB0> INFO: pixel data incomplete: 0
[09:25:16.393] <TB0> INFO: pixel address: 0
[09:25:16.393] <TB0> INFO: pulse height fill bit: 0
[09:25:16.393] <TB0> INFO: buffer corruption: 0
[09:25:16.419] <TB0> INFO: Decoding statistics:
[09:25:16.419] <TB0> INFO: General information:
[09:25:16.419] <TB0> INFO: 16bit words read: 2755348
[09:25:16.419] <TB0> INFO: valid events total: 176768
[09:25:16.419] <TB0> INFO: empty events: 7867
[09:25:16.419] <TB0> INFO: valid events with pixels: 168901
[09:25:16.419] <TB0> INFO: valid pixel hits: 668101
[09:25:16.419] <TB0> INFO: Event errors: 0
[09:25:16.419] <TB0> INFO: start marker: 0
[09:25:16.419] <TB0> INFO: stop marker: 0
[09:25:16.419] <TB0> INFO: overflow: 0
[09:25:16.419] <TB0> INFO: invalid 5bit words: 0
[09:25:16.419] <TB0> INFO: invalid XOR eye diagram: 0
[09:25:16.419] <TB0> INFO: frame (failed synchr.): 0
[09:25:16.419] <TB0> INFO: idle data (no TBM trl): 0
[09:25:16.419] <TB0> INFO: no data (only TBM hdr): 0
[09:25:16.419] <TB0> INFO: TBM errors: 0
[09:25:16.419] <TB0> INFO: flawed TBM headers: 0
[09:25:16.419] <TB0> INFO: flawed TBM trailers: 0
[09:25:16.419] <TB0> INFO: event ID mismatches: 0
[09:25:16.419] <TB0> INFO: ROC errors: 0
[09:25:16.419] <TB0> INFO: missing ROC header(s): 0
[09:25:16.419] <TB0> INFO: misplaced readback start: 0
[09:25:16.419] <TB0> INFO: Pixel decoding errors: 0
[09:25:16.419] <TB0> INFO: pixel data incomplete: 0
[09:25:16.419] <TB0> INFO: pixel address: 0
[09:25:16.419] <TB0> INFO: pulse height fill bit: 0
[09:25:16.419] <TB0> INFO: buffer corruption: 0
[09:25:16.419] <TB0> INFO: enter test to run
[09:25:16.419] <TB0> INFO: test: exit no parameter change
[09:25:16.588] <TB0> QUIET: Connection to board 73 closed.
[09:25:16.668] <TB0> INFO: pXar: this is the end, my friend