[13:01:17.813] <TB0> INFO: *** Welcome to pxar ***
[13:01:17.813] <TB0> INFO: *** Today: 2016/07/19
[13:01:19.191] <TB0> INFO: *** Version: 9751-dirty
[13:01:19.191] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters_C15.dat
[13:01:19.192] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//tbmParameters_C0b.dat
[13:01:19.192] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//defaultMaskFile.dat
[13:01:19.192] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//trimParameters_C15.dat
[13:01:19.259] <TB0> INFO: clk: 4
[13:01:19.259] <TB0> INFO: ctr: 4
[13:01:19.259] <TB0> INFO: sda: 19
[13:01:19.259] <TB0> INFO: tin: 9
[13:01:19.259] <TB0> INFO: level: 15
[13:01:19.259] <TB0> INFO: triggerdelay: 0
[13:01:19.259] <TB0> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[13:01:19.259] <TB0> INFO: Log level: INFO
[13:01:19.266] <TB0> INFO: Found DTB DTB_WS6AYH
[13:01:19.278] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[13:01:19.279] <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:
------------------------------------------------------
[13:01:19.281] <TB0> INFO: RPC call hashes of host and DTB match: 484264910
[13:01:20.808] <TB0> INFO: DUT info:
[13:01:20.808] <TB0> INFO: The DUT currently contains the following objects:
[13:01:20.808] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[13:01:20.808] <TB0> INFO: TBM Core alpha (0): 7 registers set
[13:01:20.808] <TB0> INFO: TBM Core beta (1): 7 registers set
[13:01:20.808] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:01:20.808] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:01:20.808] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:01:20.808] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:01:20.808] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:01:20.808] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:01:20.808] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:01:20.808] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:01:20.808] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:01:20.808] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:01:20.808] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:01:20.808] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:01:20.808] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:01:20.808] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:01:20.808] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:01:20.808] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:01:20.808] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:01:21.208] <TB0> INFO: enter 'restricted' command line mode
[13:01:21.208] <TB0> INFO: enter test to run
[13:01:21.209] <TB0> INFO: test: pretest no parameter change
[13:01:21.209] <TB0> INFO: running: pretest
[13:01:21.212] <TB0> INFO: ----------------------------------------------------------------------
[13:01:21.212] <TB0> INFO: PixTestPretest::programROC()
[13:01:21.212] <TB0> INFO: ----------------------------------------------------------------------
[13:01:39.227] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[13:01:39.227] <TB0> INFO: IA differences per ROC: 17.7 17.7 18.5 19.3 18.5 18.5 20.1 17.7 19.3 17.7 20.9 18.5 19.3 19.3 19.3 20.9
[13:01:39.327] <TB0> INFO: enter test to run
[13:01:39.327] <TB0> INFO: test: pretest no parameter change
[13:01:39.327] <TB0> INFO: running: pretest
[13:01:39.328] <TB0> INFO: ----------------------------------------------------------------------
[13:01:39.328] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[13:01:39.328] <TB0> INFO: ----------------------------------------------------------------------
[13:01:46.415] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 389.1 mA = 24.3188 mA/ROC
[13:01:46.415] <TB0> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 19.3 18.5 18.5 20.1 19.3 19.3 19.3 19.3 18.5 19.3 20.1 18.5 18.5
[13:01:46.443] <TB0> INFO: enter test to run
[13:01:46.443] <TB0> INFO: test: pretest no parameter change
[13:01:46.443] <TB0> INFO: running: pretest
[13:01:46.444] <TB0> INFO: ----------------------------------------------------------------------
[13:01:46.444] <TB0> INFO: PixTestPretest::findTiming()
[13:01:46.444] <TB0> INFO: ----------------------------------------------------------------------
[13:01:46.444] <TB0> INFO: PixTestCmd::init()
[13:01:47.401] <TB0> WARNING: Not unmasking DUT, not setting Calibrate bits!
[13:03:15.462] <TB0> INFO: TBM phases: 160MHz: 0, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[13:03:15.462] <TB0> INFO: (success/tries = 100/100), width = 5
[13:03:15.463] <TB0> INFO: enter test to run
[13:03:15.463] <TB0> INFO: test: pretest no parameter change
[13:03:15.463] <TB0> INFO: running: pretest
[13:03:15.465] <TB0> INFO: ----------------------------------------------------------------------
[13:03:15.465] <TB0> INFO: PixTestPretest::findWorkingPixel()
[13:03:15.465] <TB0> INFO: ----------------------------------------------------------------------
[13:03:15.558] <TB0> INFO: Expecting 231680 events.
[13:03:21.334] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L497> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[13:03:21.418] <TB0> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[13:03:25.757] <TB0> INFO: 231680 events read in total (9643ms).
[13:03:25.760] <TB0> INFO: Test took 10290ms.
[13:03:25.990] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[13:03:26.063] <TB0> INFO: enter test to run
[13:03:26.063] <TB0> INFO: test: pretest no parameter change
[13:03:26.063] <TB0> INFO: running: pretest
[13:03:26.064] <TB0> INFO: ----------------------------------------------------------------------
[13:03:26.064] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[13:03:26.064] <TB0> INFO: ----------------------------------------------------------------------
[13:03:26.155] <TB0> INFO: Expecting 231680 events.
[13:03:36.466] <TB0> INFO: 231680 events read in total (9754ms).
[13:03:36.470] <TB0> INFO: Test took 10402ms.
[13:03:36.724] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[13:03:36.724] <TB0> INFO: CalDel: 150 143 126 131 145 128 145 126 149 132 163 138 153 137 157 157
[13:03:36.724] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[13:03:36.767] <TB0> INFO: enter test to run
[13:03:36.767] <TB0> INFO: test: pretest no parameter change
[13:03:36.767] <TB0> INFO: running: pretest
[13:03:36.768] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters_C0.dat
[13:03:36.768] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters_C1.dat
[13:03:36.768] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters_C2.dat
[13:03:36.768] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters_C3.dat
[13:03:36.768] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters_C4.dat
[13:03:36.768] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters_C5.dat
[13:03:36.768] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters_C6.dat
[13:03:36.768] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters_C7.dat
[13:03:36.768] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters_C8.dat
[13:03:36.769] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters_C9.dat
[13:03:36.769] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters_C10.dat
[13:03:36.769] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters_C11.dat
[13:03:36.769] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters_C12.dat
[13:03:36.769] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters_C13.dat
[13:03:36.769] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters_C14.dat
[13:03:36.769] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters_C15.dat
[13:03:36.770] <TB0> INFO: enter test to run
[13:03:36.770] <TB0> INFO: test: fulltest no parameter change
[13:03:36.770] <TB0> INFO: running: fulltest
[13:03:36.770] <TB0> INFO: ######################################################################
[13:03:36.770] <TB0> INFO: PixTestFullTest::doTest()
[13:03:36.770] <TB0> INFO: ######################################################################
[13:03:36.771] <TB0> INFO: ######################################################################
[13:03:36.771] <TB0> INFO: PixTestAlive::doTest()
[13:03:36.771] <TB0> INFO: ######################################################################
[13:03:36.772] <TB0> INFO: ----------------------------------------------------------------------
[13:03:36.772] <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)
[13:03:36.772] <TB0> INFO: ----------------------------------------------------------------------
[13:03:37.042] <TB0> INFO: Expecting 41600 events.
[13:03:40.775] <TB0> INFO: 41600 events read in total (3177ms).
[13:03:40.776] <TB0> INFO: Test took 4002ms.
[13:03:41.006] <TB0> INFO: PixTestAlive::aliveTest() done
[13:03:41.006] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0
[13:03:41.007] <TB0> INFO: ----------------------------------------------------------------------
[13:03:41.007] <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)
[13:03:41.007] <TB0> INFO: ----------------------------------------------------------------------
[13:03:41.267] <TB0> INFO: Expecting 41600 events.
[13:03:44.264] <TB0> INFO: 41600 events read in total (2441ms).
[13:03:44.264] <TB0> INFO: Test took 3256ms.
[13:03:44.264] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[13:03:44.505] <TB0> INFO: PixTestAlive::maskTest() done
[13:03:44.505] <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
[13:03:44.507] <TB0> INFO: ----------------------------------------------------------------------
[13:03:44.507] <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)
[13:03:44.507] <TB0> INFO: ----------------------------------------------------------------------
[13:03:44.775] <TB0> INFO: Expecting 41600 events.
[13:03:48.564] <TB0> INFO: 41600 events read in total (3233ms).
[13:03:48.565] <TB0> INFO: Test took 4056ms.
[13:03:48.787] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[13:03:48.787] <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
[13:03:48.787] <TB0> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[13:03:48.787] <TB0> INFO: Decoding statistics:
[13:03:48.787] <TB0> INFO: General information:
[13:03:48.787] <TB0> INFO: 16bit words read: 0
[13:03:48.787] <TB0> INFO: valid events total: 0
[13:03:48.787] <TB0> INFO: empty events: 0
[13:03:48.787] <TB0> INFO: valid events with pixels: 0
[13:03:48.787] <TB0> INFO: valid pixel hits: 0
[13:03:48.787] <TB0> INFO: Event errors: 0
[13:03:48.787] <TB0> INFO: start marker: 0
[13:03:48.787] <TB0> INFO: stop marker: 0
[13:03:48.787] <TB0> INFO: overflow: 0
[13:03:48.787] <TB0> INFO: invalid 5bit words: 0
[13:03:48.787] <TB0> INFO: invalid XOR eye diagram: 0
[13:03:48.787] <TB0> INFO: frame (failed synchr.): 0
[13:03:48.787] <TB0> INFO: idle data (no TBM trl): 0
[13:03:48.787] <TB0> INFO: no data (only TBM hdr): 0
[13:03:48.787] <TB0> INFO: TBM errors: 0
[13:03:48.787] <TB0> INFO: flawed TBM headers: 0
[13:03:48.787] <TB0> INFO: flawed TBM trailers: 0
[13:03:48.787] <TB0> INFO: event ID mismatches: 0
[13:03:48.787] <TB0> INFO: ROC errors: 0
[13:03:48.787] <TB0> INFO: missing ROC header(s): 0
[13:03:48.787] <TB0> INFO: misplaced readback start: 0
[13:03:48.787] <TB0> INFO: Pixel decoding errors: 0
[13:03:48.787] <TB0> INFO: pixel data incomplete: 0
[13:03:48.787] <TB0> INFO: pixel address: 0
[13:03:48.787] <TB0> INFO: pulse height fill bit: 0
[13:03:48.788] <TB0> INFO: buffer corruption: 0
[13:03:48.802] <TB0> INFO: ######################################################################
[13:03:48.802] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[13:03:48.802] <TB0> INFO: ######################################################################
[13:03:48.804] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[13:03:48.816] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[13:03:48.816] <TB0> INFO: run 1 of 1
[13:03:49.080] <TB0> INFO: Expecting 3120000 events.
[13:04:30.471] <TB0> INFO: 846175 events read in total (40835ms).
[13:05:10.315] <TB0> INFO: 1679015 events read in total (80680ms).
[13:05:50.229] <TB0> INFO: 2517250 events read in total (120594ms).
[13:06:19.190] <TB0> INFO: 3120000 events read in total (149554ms).
[13:06:19.248] <TB0> INFO: Test took 150432ms.
[13:06:44.548] <TB0> INFO: PixTestBBMap::doTest() done, duration: 175 seconds
[13:06:44.548] <TB0> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0 0
[13:06:44.548] <TB0> INFO: separation cut (per ROC): 69 70 83 95 75 78 91 98 75 78 70 69 75 92 70 72
[13:06:44.549] <TB0> INFO: Decoding statistics:
[13:06:44.549] <TB0> INFO: General information:
[13:06:44.549] <TB0> INFO: 16bit words read: 0
[13:06:44.549] <TB0> INFO: valid events total: 0
[13:06:44.549] <TB0> INFO: empty events: 0
[13:06:44.549] <TB0> INFO: valid events with pixels: 0
[13:06:44.549] <TB0> INFO: valid pixel hits: 0
[13:06:44.549] <TB0> INFO: Event errors: 0
[13:06:44.549] <TB0> INFO: start marker: 0
[13:06:44.549] <TB0> INFO: stop marker: 0
[13:06:44.549] <TB0> INFO: overflow: 0
[13:06:44.549] <TB0> INFO: invalid 5bit words: 0
[13:06:44.549] <TB0> INFO: invalid XOR eye diagram: 0
[13:06:44.549] <TB0> INFO: frame (failed synchr.): 0
[13:06:44.549] <TB0> INFO: idle data (no TBM trl): 0
[13:06:44.549] <TB0> INFO: no data (only TBM hdr): 0
[13:06:44.549] <TB0> INFO: TBM errors: 0
[13:06:44.549] <TB0> INFO: flawed TBM headers: 0
[13:06:44.549] <TB0> INFO: flawed TBM trailers: 0
[13:06:44.549] <TB0> INFO: event ID mismatches: 0
[13:06:44.549] <TB0> INFO: ROC errors: 0
[13:06:44.549] <TB0> INFO: missing ROC header(s): 0
[13:06:44.549] <TB0> INFO: misplaced readback start: 0
[13:06:44.549] <TB0> INFO: Pixel decoding errors: 0
[13:06:44.549] <TB0> INFO: pixel data incomplete: 0
[13:06:44.549] <TB0> INFO: pixel address: 0
[13:06:44.549] <TB0> INFO: pulse height fill bit: 0
[13:06:44.549] <TB0> INFO: buffer corruption: 0
[13:06:44.622] <TB0> INFO: ######################################################################
[13:06:44.622] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[13:06:44.622] <TB0> INFO: ######################################################################
[13:06:44.622] <TB0> INFO: ----------------------------------------------------------------------
[13:06:44.622] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[13:06:44.622] <TB0> INFO: ----------------------------------------------------------------------
[13:06:44.622] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[13:06:44.631] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[13:06:44.631] <TB0> INFO: run 1 of 1
[13:06:44.899] <TB0> INFO: Expecting 26208000 events.
[13:07:18.981] <TB0> INFO: 875600 events read in total (33525ms).
[13:07:51.634] <TB0> INFO: 1739050 events read in total (66178ms).
[13:08:23.667] <TB0> INFO: 2601050 events read in total (98211ms).
[13:08:56.271] <TB0> INFO: 3462150 events read in total (130815ms).
[13:09:28.828] <TB0> INFO: 4320100 events read in total (163372ms).
[13:10:01.626] <TB0> INFO: 5178950 events read in total (196170ms).
[13:10:34.174] <TB0> INFO: 6037150 events read in total (228718ms).
[13:11:06.435] <TB0> INFO: 6895300 events read in total (260979ms).
[13:11:38.861] <TB0> INFO: 7752300 events read in total (293405ms).
[13:12:11.100] <TB0> INFO: 8609950 events read in total (325644ms).
[13:12:43.192] <TB0> INFO: 9466900 events read in total (357736ms).
[13:13:15.191] <TB0> INFO: 10322600 events read in total (389735ms).
[13:13:47.649] <TB0> INFO: 11179000 events read in total (422193ms).
[13:14:20.585] <TB0> INFO: 12031450 events read in total (455129ms).
[13:14:52.905] <TB0> INFO: 12885050 events read in total (487449ms).
[13:15:25.462] <TB0> INFO: 13733250 events read in total (520006ms).
[13:15:57.597] <TB0> INFO: 14578200 events read in total (552141ms).
[13:16:30.367] <TB0> INFO: 15424050 events read in total (584911ms).
[13:17:02.698] <TB0> INFO: 16266400 events read in total (617242ms).
[13:17:36.128] <TB0> INFO: 17108050 events read in total (650672ms).
[13:18:08.826] <TB0> INFO: 17949700 events read in total (683370ms).
[13:18:41.082] <TB0> INFO: 18792100 events read in total (715626ms).
[13:19:13.418] <TB0> INFO: 19630450 events read in total (747962ms).
[13:19:45.858] <TB0> INFO: 20471400 events read in total (780402ms).
[13:20:18.197] <TB0> INFO: 21311200 events read in total (812741ms).
[13:20:50.116] <TB0> INFO: 22148800 events read in total (844660ms).
[13:21:22.304] <TB0> INFO: 22987700 events read in total (876848ms).
[13:21:54.661] <TB0> INFO: 23827200 events read in total (909205ms).
[13:22:27.108] <TB0> INFO: 24666600 events read in total (941652ms).
[13:22:59.661] <TB0> INFO: 25506400 events read in total (974205ms).
[13:23:26.734] <TB0> INFO: 26208000 events read in total (1001278ms).
[13:23:26.760] <TB0> INFO: Test took 1002129ms.
[13:23:27.011] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:23:28.421] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:23:29.857] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:23:31.297] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:23:32.799] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:23:34.277] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:23:35.692] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:23:37.065] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:23:38.594] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:23:40.241] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:23:41.935] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:23:43.616] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:23:45.305] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:23:46.702] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:23:48.086] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:23:49.510] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[13:23:51.219] <TB0> INFO: PixTestScurves::scurves() done
[13:23:51.219] <TB0> INFO: Vcal mean: 80.80 82.89 83.81 92.58 90.09 87.89 87.19 94.90 87.85 81.79 83.51 76.55 79.25 90.72 75.67 76.65
[13:23:51.219] <TB0> INFO: Vcal RMS: 4.30 4.66 5.56 5.62 5.15 4.90 4.60 5.30 5.35 4.43 4.52 4.02 4.71 4.89 4.75 4.09
[13:23:51.219] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1026 seconds
[13:23:51.219] <TB0> INFO: Decoding statistics:
[13:23:51.219] <TB0> INFO: General information:
[13:23:51.219] <TB0> INFO: 16bit words read: 0
[13:23:51.219] <TB0> INFO: valid events total: 0
[13:23:51.219] <TB0> INFO: empty events: 0
[13:23:51.219] <TB0> INFO: valid events with pixels: 0
[13:23:51.219] <TB0> INFO: valid pixel hits: 0
[13:23:51.219] <TB0> INFO: Event errors: 0
[13:23:51.219] <TB0> INFO: start marker: 0
[13:23:51.219] <TB0> INFO: stop marker: 0
[13:23:51.219] <TB0> INFO: overflow: 0
[13:23:51.219] <TB0> INFO: invalid 5bit words: 0
[13:23:51.219] <TB0> INFO: invalid XOR eye diagram: 0
[13:23:51.219] <TB0> INFO: frame (failed synchr.): 0
[13:23:51.219] <TB0> INFO: idle data (no TBM trl): 0
[13:23:51.219] <TB0> INFO: no data (only TBM hdr): 0
[13:23:51.219] <TB0> INFO: TBM errors: 0
[13:23:51.219] <TB0> INFO: flawed TBM headers: 0
[13:23:51.219] <TB0> INFO: flawed TBM trailers: 0
[13:23:51.219] <TB0> INFO: event ID mismatches: 0
[13:23:51.219] <TB0> INFO: ROC errors: 0
[13:23:51.219] <TB0> INFO: missing ROC header(s): 0
[13:23:51.219] <TB0> INFO: misplaced readback start: 0
[13:23:51.219] <TB0> INFO: Pixel decoding errors: 0
[13:23:51.219] <TB0> INFO: pixel data incomplete: 0
[13:23:51.219] <TB0> INFO: pixel address: 0
[13:23:51.219] <TB0> INFO: pulse height fill bit: 0
[13:23:51.219] <TB0> INFO: buffer corruption: 0
[13:23:51.289] <TB0> INFO: ######################################################################
[13:23:51.289] <TB0> INFO: PixTestTrim::doTest()
[13:23:51.289] <TB0> INFO: ######################################################################
[13:23:51.290] <TB0> INFO: ----------------------------------------------------------------------
[13:23:51.290] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[13:23:51.290] <TB0> INFO: ----------------------------------------------------------------------
[13:23:51.370] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[13:23:51.370] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:23:51.379] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[13:23:51.379] <TB0> INFO: run 1 of 1
[13:23:51.638] <TB0> INFO: Expecting 6281600 events.
[13:24:31.043] <TB0> INFO: 1067980 events read in total (38848ms).
[13:25:10.322] <TB0> INFO: 2129900 events read in total (78127ms).
[13:25:49.825] <TB0> INFO: 3183330 events read in total (117630ms).
[13:26:28.981] <TB0> INFO: 4241430 events read in total (156786ms).
[13:27:07.917] <TB0> INFO: 5297750 events read in total (195722ms).
[13:27:44.255] <TB0> INFO: 6281600 events read in total (232060ms).
[13:27:44.284] <TB0> INFO: Test took 232905ms.
[13:28:04.381] <TB0> INFO: ROC 0 VthrComp = 85
[13:28:04.381] <TB0> INFO: ROC 1 VthrComp = 87
[13:28:04.382] <TB0> INFO: ROC 2 VthrComp = 89
[13:28:04.382] <TB0> INFO: ROC 3 VthrComp = 99
[13:28:04.382] <TB0> INFO: ROC 4 VthrComp = 95
[13:28:04.382] <TB0> INFO: ROC 5 VthrComp = 93
[13:28:04.382] <TB0> INFO: ROC 6 VthrComp = 97
[13:28:04.382] <TB0> INFO: ROC 7 VthrComp = 102
[13:28:04.382] <TB0> INFO: ROC 8 VthrComp = 90
[13:28:04.382] <TB0> INFO: ROC 9 VthrComp = 88
[13:28:04.383] <TB0> INFO: ROC 10 VthrComp = 91
[13:28:04.383] <TB0> INFO: ROC 11 VthrComp = 83
[13:28:04.383] <TB0> INFO: ROC 12 VthrComp = 83
[13:28:04.383] <TB0> INFO: ROC 13 VthrComp = 98
[13:28:04.383] <TB0> INFO: ROC 14 VthrComp = 80
[13:28:04.383] <TB0> INFO: ROC 15 VthrComp = 86
[13:28:04.383] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[13:28:04.383] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:28:04.394] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[13:28:04.395] <TB0> INFO: run 1 of 1
[13:28:04.734] <TB0> INFO: Expecting 6281600 events.
[13:28:39.498] <TB0> INFO: 753490 events read in total (34208ms).
[13:29:14.018] <TB0> INFO: 1506310 events read in total (68728ms).
[13:29:48.656] <TB0> INFO: 2259520 events read in total (103366ms).
[13:30:23.403] <TB0> INFO: 3013000 events read in total (138113ms).
[13:30:57.863] <TB0> INFO: 3761440 events read in total (172573ms).
[13:31:32.500] <TB0> INFO: 4506270 events read in total (207210ms).
[13:32:07.345] <TB0> INFO: 5248850 events read in total (242055ms).
[13:32:42.109] <TB0> INFO: 5990810 events read in total (276819ms).
[13:32:55.817] <TB0> INFO: 6281600 events read in total (290527ms).
[13:32:55.871] <TB0> INFO: Test took 291476ms.
[13:33:20.396] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 57.679 for pixel 12/5 mean/min/max = 44.8794/32.0038/57.755
[13:33:20.396] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 57.4384 for pixel 15/6 mean/min/max = 44.6901/31.91/57.4702
[13:33:20.396] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 60.7065 for pixel 16/29 mean/min/max = 46.4377/32.1377/60.7378
[13:33:20.396] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 58.8991 for pixel 10/74 mean/min/max = 45.125/31.2409/59.009
[13:33:20.397] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 57.1711 for pixel 24/74 mean/min/max = 44.2299/31.2755/57.1843
[13:33:20.397] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 57.7852 for pixel 51/25 mean/min/max = 45.6243/33.1259/58.1227
[13:33:20.397] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 55.3418 for pixel 51/2 mean/min/max = 43.9347/32.2342/55.6351
[13:33:20.398] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 57.4305 for pixel 51/7 mean/min/max = 45.1666/32.6402/57.693
[13:33:20.398] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 58.4721 for pixel 7/21 mean/min/max = 45.7932/32.674/58.9124
[13:33:20.398] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 57.0161 for pixel 3/46 mean/min/max = 45.2443/33.2616/57.2271
[13:33:20.398] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 56.3324 for pixel 22/7 mean/min/max = 44.5942/32.6768/56.5116
[13:33:20.399] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 55.5368 for pixel 23/15 mean/min/max = 43.6792/31.7293/55.6291
[13:33:20.399] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 58.3119 for pixel 1/68 mean/min/max = 45.1402/31.8708/58.4096
[13:33:20.399] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 56.9899 for pixel 16/1 mean/min/max = 44.621/32.0548/57.1872
[13:33:20.399] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 58.3768 for pixel 8/40 mean/min/max = 45.4666/32.4091/58.5241
[13:33:20.400] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 55.6998 for pixel 21/4 mean/min/max = 43.9565/32.0701/55.8429
[13:33:20.400] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:33:20.487] <TB0> INFO: Expecting 514560 events.
[13:33:33.429] <TB0> INFO: 514560 events read in total (12385ms).
[13:33:33.434] <TB0> INFO: Expecting 514560 events.
[13:33:46.228] <TB0> INFO: 514560 events read in total (12371ms).
[13:33:46.237] <TB0> INFO: Expecting 514560 events.
[13:33:59.097] <TB0> INFO: 514560 events read in total (12443ms).
[13:33:59.106] <TB0> INFO: Expecting 514560 events.
[13:34:12.028] <TB0> INFO: 514560 events read in total (12509ms).
[13:34:12.040] <TB0> INFO: Expecting 514560 events.
[13:34:24.932] <TB0> INFO: 514560 events read in total (12485ms).
[13:34:24.946] <TB0> INFO: Expecting 514560 events.
[13:34:37.756] <TB0> INFO: 514560 events read in total (12408ms).
[13:34:37.771] <TB0> INFO: Expecting 514560 events.
[13:34:50.649] <TB0> INFO: 514560 events read in total (12467ms).
[13:34:50.666] <TB0> INFO: Expecting 514560 events.
[13:35:03.617] <TB0> INFO: 514560 events read in total (12543ms).
[13:35:03.635] <TB0> INFO: Expecting 514560 events.
[13:35:16.455] <TB0> INFO: 514560 events read in total (12409ms).
[13:35:16.476] <TB0> INFO: Expecting 514560 events.
[13:35:29.260] <TB0> INFO: 514560 events read in total (12379ms).
[13:35:29.284] <TB0> INFO: Expecting 514560 events.
[13:35:42.238] <TB0> INFO: 514560 events read in total (12552ms).
[13:35:42.266] <TB0> INFO: Expecting 514560 events.
[13:35:55.162] <TB0> INFO: 514560 events read in total (12495ms).
[13:35:55.188] <TB0> INFO: Expecting 514560 events.
[13:36:07.996] <TB0> INFO: 514560 events read in total (12406ms).
[13:36:08.025] <TB0> INFO: Expecting 514560 events.
[13:36:20.982] <TB0> INFO: 514560 events read in total (12555ms).
[13:36:21.012] <TB0> INFO: Expecting 514560 events.
[13:36:34.029] <TB0> INFO: 514560 events read in total (12615ms).
[13:36:34.061] <TB0> INFO: Expecting 514560 events.
[13:36:46.961] <TB0> INFO: 514560 events read in total (12498ms).
[13:36:47.001] <TB0> INFO: Test took 206601ms.
[13:36:48.024] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[13:36:48.034] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[13:36:48.034] <TB0> INFO: run 1 of 1
[13:36:48.319] <TB0> INFO: Expecting 6281600 events.
[13:37:23.613] <TB0> INFO: 740780 events read in total (34738ms).
[13:37:57.960] <TB0> INFO: 1480740 events read in total (69085ms).
[13:38:32.690] <TB0> INFO: 2221250 events read in total (103815ms).
[13:39:07.521] <TB0> INFO: 2962260 events read in total (138646ms).
[13:39:41.690] <TB0> INFO: 3697600 events read in total (172815ms).
[13:40:15.917] <TB0> INFO: 4428540 events read in total (207042ms).
[13:40:50.635] <TB0> INFO: 5158420 events read in total (241760ms).
[13:41:25.148] <TB0> INFO: 5887430 events read in total (276273ms).
[13:41:44.277] <TB0> INFO: 6281600 events read in total (295402ms).
[13:41:44.327] <TB0> INFO: Test took 296293ms.
[13:42:08.385] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.174582 .. 255.000000
[13:42:08.507] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[13:42:08.519] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[13:42:08.519] <TB0> INFO: run 1 of 1
[13:42:08.861] <TB0> INFO: Expecting 10649600 events.
[13:42:43.574] <TB0> INFO: 712550 events read in total (34156ms).
[13:43:17.890] <TB0> INFO: 1425040 events read in total (68472ms).
[13:43:51.912] <TB0> INFO: 2137610 events read in total (102494ms).
[13:44:26.549] <TB0> INFO: 2850460 events read in total (137131ms).
[13:45:00.075] <TB0> INFO: 3563110 events read in total (170657ms).
[13:45:33.744] <TB0> INFO: 4276080 events read in total (204326ms).
[13:46:07.835] <TB0> INFO: 4988830 events read in total (238417ms).
[13:46:41.957] <TB0> INFO: 5701570 events read in total (272539ms).
[13:47:15.511] <TB0> INFO: 6413630 events read in total (306093ms).
[13:47:50.157] <TB0> INFO: 7124640 events read in total (340739ms).
[13:48:24.783] <TB0> INFO: 7835600 events read in total (375365ms).
[13:48:58.878] <TB0> INFO: 8546320 events read in total (409460ms).
[13:49:33.039] <TB0> INFO: 9256940 events read in total (443621ms).
[13:50:07.260] <TB0> INFO: 9967450 events read in total (477842ms).
[13:50:39.745] <TB0> INFO: 10649600 events read in total (510327ms).
[13:50:39.831] <TB0> INFO: Test took 511312ms.
[13:51:07.846] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 17.473957 .. 135.067973
[13:51:07.921] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 7 .. 145 (-1/-1) hits flags = 528 (plus default)
[13:51:07.929] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[13:51:07.929] <TB0> INFO: run 1 of 1
[13:51:08.194] <TB0> INFO: Expecting 5782400 events.
[13:51:42.721] <TB0> INFO: 737850 events read in total (33971ms).
[13:52:17.020] <TB0> INFO: 1475810 events read in total (68270ms).
[13:52:51.466] <TB0> INFO: 2213760 events read in total (102716ms).
[13:53:26.192] <TB0> INFO: 2951670 events read in total (137442ms).
[13:54:00.538] <TB0> INFO: 3689010 events read in total (171788ms).
[13:54:34.943] <TB0> INFO: 4426120 events read in total (206193ms).
[13:55:09.218] <TB0> INFO: 5163140 events read in total (240468ms).
[13:55:37.753] <TB0> INFO: 5782400 events read in total (269003ms).
[13:55:37.793] <TB0> INFO: Test took 269863ms.
[13:56:01.380] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 20.373977 .. 130.890357
[13:56:01.455] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 10 .. 140 (-1/-1) hits flags = 528 (plus default)
[13:56:01.463] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[13:56:01.463] <TB0> INFO: run 1 of 1
[13:56:01.723] <TB0> INFO: Expecting 5449600 events.
[13:56:37.016] <TB0> INFO: 734090 events read in total (34737ms).
[13:57:11.210] <TB0> INFO: 1468150 events read in total (68931ms).
[13:57:45.673] <TB0> INFO: 2202470 events read in total (103394ms).
[13:58:20.390] <TB0> INFO: 2936560 events read in total (138111ms).
[13:58:54.203] <TB0> INFO: 3670440 events read in total (171924ms).
[13:59:28.748] <TB0> INFO: 4404090 events read in total (206469ms).
[14:00:03.191] <TB0> INFO: 5137710 events read in total (240912ms).
[14:00:18.027] <TB0> INFO: 5449600 events read in total (255748ms).
[14:00:18.064] <TB0> INFO: Test took 256601ms.
[14:00:43.473] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 24.603184 .. 130.715643
[14:00:43.550] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 14 .. 140 (-1/-1) hits flags = 528 (plus default)
[14:00:43.559] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[14:00:43.559] <TB0> INFO: run 1 of 1
[14:00:43.834] <TB0> INFO: Expecting 5283200 events.
[14:01:18.785] <TB0> INFO: 723900 events read in total (34395ms).
[14:01:53.229] <TB0> INFO: 1447970 events read in total (68839ms).
[14:02:27.569] <TB0> INFO: 2171970 events read in total (103179ms).
[14:03:01.411] <TB0> INFO: 2895840 events read in total (137021ms).
[14:03:35.666] <TB0> INFO: 3619560 events read in total (171276ms).
[14:04:10.370] <TB0> INFO: 4343370 events read in total (205980ms).
[14:04:45.245] <TB0> INFO: 5067190 events read in total (240855ms).
[14:04:55.785] <TB0> INFO: 5283200 events read in total (251395ms).
[14:04:55.825] <TB0> INFO: Test took 252266ms.
[14:05:18.626] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[14:05:18.626] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[14:05:18.634] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[14:05:18.634] <TB0> INFO: run 1 of 1
[14:05:18.893] <TB0> INFO: Expecting 1705600 events.
[14:05:56.006] <TB0> INFO: 879670 events read in total (36556ms).
[14:06:30.779] <TB0> INFO: 1705600 events read in total (71329ms).
[14:06:30.803] <TB0> INFO: Test took 72169ms.
[14:06:44.538] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C0.dat
[14:06:44.538] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C1.dat
[14:06:44.538] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C2.dat
[14:06:44.538] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C3.dat
[14:06:44.538] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C4.dat
[14:06:44.538] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C5.dat
[14:06:44.538] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C6.dat
[14:06:44.539] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C7.dat
[14:06:44.539] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C8.dat
[14:06:44.539] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C9.dat
[14:06:44.539] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C10.dat
[14:06:44.539] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C11.dat
[14:06:44.539] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C12.dat
[14:06:44.539] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C13.dat
[14:06:44.540] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C14.dat
[14:06:44.540] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C15.dat
[14:06:44.540] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//trimParameters35_C0.dat
[14:06:44.548] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//trimParameters35_C1.dat
[14:06:44.556] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//trimParameters35_C2.dat
[14:06:44.564] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//trimParameters35_C3.dat
[14:06:44.572] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//trimParameters35_C4.dat
[14:06:44.580] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//trimParameters35_C5.dat
[14:06:44.588] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//trimParameters35_C6.dat
[14:06:44.596] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//trimParameters35_C7.dat
[14:06:44.604] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//trimParameters35_C8.dat
[14:06:44.612] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//trimParameters35_C9.dat
[14:06:44.619] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//trimParameters35_C10.dat
[14:06:44.627] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//trimParameters35_C11.dat
[14:06:44.635] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//trimParameters35_C12.dat
[14:06:44.644] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//trimParameters35_C13.dat
[14:06:44.650] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//trimParameters35_C14.dat
[14:06:44.656] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//trimParameters35_C15.dat
[14:06:44.662] <TB0> INFO: PixTestTrim::trimTest() done
[14:06:44.662] <TB0> INFO: vtrim: 95 105 110 112 115 92 96 94 95 101 99 99 93 107 120 101
[14:06:44.662] <TB0> INFO: vthrcomp: 85 87 89 99 95 93 97 102 90 88 91 83 83 98 80 86
[14:06:44.662] <TB0> INFO: vcal mean: 34.96 35.04 34.99 34.98 35.04 34.97 34.98 35.01 34.98 35.00 34.97 34.97 35.00 34.97 35.01 34.98
[14:06:44.662] <TB0> INFO: vcal RMS: 0.86 0.84 0.87 1.05 0.87 0.82 0.80 0.82 1.00 0.77 0.81 0.83 0.81 0.80 0.99 0.79
[14:06:44.662] <TB0> INFO: bits mean: 9.56 9.71 9.66 9.98 10.02 9.18 9.66 8.97 9.21 8.95 9.68 10.15 9.34 9.76 9.78 9.99
[14:06:44.662] <TB0> INFO: bits RMS: 2.73 2.67 2.54 2.57 2.66 2.69 2.72 2.91 2.67 2.82 2.62 2.51 2.75 2.64 2.58 2.54
[14:06:44.669] <TB0> INFO: ----------------------------------------------------------------------
[14:06:44.669] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[14:06:44.669] <TB0> INFO: ----------------------------------------------------------------------
[14:06:44.671] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[14:06:44.681] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[14:06:44.681] <TB0> INFO: run 1 of 1
[14:06:44.941] <TB0> INFO: Expecting 4160000 events.
[14:07:26.626] <TB0> INFO: 907655 events read in total (41129ms).
[14:08:07.613] <TB0> INFO: 1808640 events read in total (82116ms).
[14:08:47.941] <TB0> INFO: 2701510 events read in total (122444ms).
[14:09:28.872] <TB0> INFO: 3589890 events read in total (163375ms).
[14:09:55.005] <TB0> INFO: 4160000 events read in total (189508ms).
[14:09:55.046] <TB0> INFO: Test took 190365ms.
[14:10:23.265] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 154 (-1/-1) hits flags = 528 (plus default)
[14:10:23.274] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[14:10:23.274] <TB0> INFO: run 1 of 1
[14:10:23.536] <TB0> INFO: Expecting 3224000 events.
[14:11:06.633] <TB0> INFO: 971420 events read in total (42541ms).
[14:11:48.484] <TB0> INFO: 1929975 events read in total (84392ms).
[14:12:29.848] <TB0> INFO: 2875440 events read in total (125757ms).
[14:12:45.169] <TB0> INFO: 3224000 events read in total (141077ms).
[14:12:45.199] <TB0> INFO: Test took 141926ms.
[14:13:09.235] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 146 (-1/-1) hits flags = 528 (plus default)
[14:13:09.244] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[14:13:09.244] <TB0> INFO: run 1 of 1
[14:13:09.503] <TB0> INFO: Expecting 3057600 events.
[14:13:52.627] <TB0> INFO: 995745 events read in total (42568ms).
[14:14:34.357] <TB0> INFO: 1974925 events read in total (84299ms).
[14:15:17.030] <TB0> INFO: 2942525 events read in total (126971ms).
[14:15:22.385] <TB0> INFO: 3057600 events read in total (132326ms).
[14:15:22.414] <TB0> INFO: Test took 133170ms.
[14:15:46.694] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 144 (-1/-1) hits flags = 528 (plus default)
[14:15:46.703] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[14:15:46.703] <TB0> INFO: run 1 of 1
[14:15:46.968] <TB0> INFO: Expecting 3016000 events.
[14:16:30.460] <TB0> INFO: 1001685 events read in total (42936ms).
[14:17:12.601] <TB0> INFO: 1985575 events read in total (85078ms).
[14:17:55.454] <TB0> INFO: 2957870 events read in total (127930ms).
[14:17:58.286] <TB0> INFO: 3016000 events read in total (130762ms).
[14:17:58.316] <TB0> INFO: Test took 131613ms.
[14:18:20.862] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 145 (-1/-1) hits flags = 528 (plus default)
[14:18:20.870] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[14:18:20.870] <TB0> INFO: run 1 of 1
[14:18:21.130] <TB0> INFO: Expecting 3036800 events.
[14:19:03.920] <TB0> INFO: 997375 events read in total (42234ms).
[14:19:45.974] <TB0> INFO: 1977335 events read in total (84288ms).
[14:20:28.136] <TB0> INFO: 2945885 events read in total (126450ms).
[14:20:32.370] <TB0> INFO: 3036800 events read in total (130684ms).
[14:20:32.396] <TB0> INFO: Test took 131525ms.
[14:20:55.256] <TB0> INFO: PixTestTrim::trimBitTest() done
[14:20:55.257] <TB0> INFO: PixTestTrim::doTest() done, duration: 3423 seconds
[14:20:55.257] <TB0> INFO: Decoding statistics:
[14:20:55.257] <TB0> INFO: General information:
[14:20:55.257] <TB0> INFO: 16bit words read: 0
[14:20:55.257] <TB0> INFO: valid events total: 0
[14:20:55.257] <TB0> INFO: empty events: 0
[14:20:55.257] <TB0> INFO: valid events with pixels: 0
[14:20:55.257] <TB0> INFO: valid pixel hits: 0
[14:20:55.257] <TB0> INFO: Event errors: 0
[14:20:55.257] <TB0> INFO: start marker: 0
[14:20:55.257] <TB0> INFO: stop marker: 0
[14:20:55.257] <TB0> INFO: overflow: 0
[14:20:55.257] <TB0> INFO: invalid 5bit words: 0
[14:20:55.257] <TB0> INFO: invalid XOR eye diagram: 0
[14:20:55.257] <TB0> INFO: frame (failed synchr.): 0
[14:20:55.257] <TB0> INFO: idle data (no TBM trl): 0
[14:20:55.257] <TB0> INFO: no data (only TBM hdr): 0
[14:20:55.257] <TB0> INFO: TBM errors: 0
[14:20:55.257] <TB0> INFO: flawed TBM headers: 0
[14:20:55.257] <TB0> INFO: flawed TBM trailers: 0
[14:20:55.257] <TB0> INFO: event ID mismatches: 0
[14:20:55.257] <TB0> INFO: ROC errors: 0
[14:20:55.257] <TB0> INFO: missing ROC header(s): 0
[14:20:55.257] <TB0> INFO: misplaced readback start: 0
[14:20:55.257] <TB0> INFO: Pixel decoding errors: 0
[14:20:55.257] <TB0> INFO: pixel data incomplete: 0
[14:20:55.257] <TB0> INFO: pixel address: 0
[14:20:55.257] <TB0> INFO: pulse height fill bit: 0
[14:20:55.257] <TB0> INFO: buffer corruption: 0
[14:20:55.948] <TB0> INFO: ######################################################################
[14:20:55.948] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[14:20:55.948] <TB0> INFO: ######################################################################
[14:20:56.218] <TB0> INFO: Expecting 41600 events.
[14:21:00.049] <TB0> INFO: 41600 events read in total (3275ms).
[14:21:00.049] <TB0> INFO: Test took 4100ms.
[14:21:00.526] <TB0> INFO: Expecting 41600 events.
[14:21:04.356] <TB0> INFO: 41600 events read in total (3274ms).
[14:21:04.357] <TB0> INFO: Test took 4104ms.
[14:21:04.649] <TB0> INFO: Expecting 41600 events.
[14:21:08.526] <TB0> INFO: 41600 events read in total (3321ms).
[14:21:08.527] <TB0> INFO: Test took 4148ms.
[14:21:08.810] <TB0> INFO: Expecting 41600 events.
[14:21:12.682] <TB0> INFO: 41600 events read in total (3315ms).
[14:21:12.682] <TB0> INFO: Test took 4135ms.
[14:21:12.970] <TB0> INFO: Expecting 41600 events.
[14:21:16.815] <TB0> INFO: 41600 events read in total (3289ms).
[14:21:16.816] <TB0> INFO: Test took 4114ms.
[14:21:17.106] <TB0> INFO: Expecting 41600 events.
[14:21:20.986] <TB0> INFO: 41600 events read in total (3324ms).
[14:21:20.987] <TB0> INFO: Test took 4151ms.
[14:21:21.270] <TB0> INFO: Expecting 41600 events.
[14:21:25.103] <TB0> INFO: 41600 events read in total (3277ms).
[14:21:25.104] <TB0> INFO: Test took 4096ms.
[14:21:25.411] <TB0> INFO: Expecting 41600 events.
[14:21:29.287] <TB0> INFO: 41600 events read in total (3319ms).
[14:21:29.287] <TB0> INFO: Test took 4163ms.
[14:21:29.573] <TB0> INFO: Expecting 41600 events.
[14:21:33.466] <TB0> INFO: 41600 events read in total (3337ms).
[14:21:33.467] <TB0> INFO: Test took 4160ms.
[14:21:33.762] <TB0> INFO: Expecting 41600 events.
[14:21:37.565] <TB0> INFO: 41600 events read in total (3247ms).
[14:21:37.565] <TB0> INFO: Test took 4078ms.
[14:21:37.853] <TB0> INFO: Expecting 41600 events.
[14:21:41.691] <TB0> INFO: 41600 events read in total (3282ms).
[14:21:41.692] <TB0> INFO: Test took 4106ms.
[14:21:42.002] <TB0> INFO: Expecting 41600 events.
[14:21:45.853] <TB0> INFO: 41600 events read in total (3295ms).
[14:21:45.854] <TB0> INFO: Test took 4141ms.
[14:21:46.140] <TB0> INFO: Expecting 41600 events.
[14:21:49.925] <TB0> INFO: 41600 events read in total (3229ms).
[14:21:49.926] <TB0> INFO: Test took 4052ms.
[14:21:50.217] <TB0> INFO: Expecting 41600 events.
[14:21:54.022] <TB0> INFO: 41600 events read in total (3249ms).
[14:21:54.022] <TB0> INFO: Test took 4076ms.
[14:21:54.318] <TB0> INFO: Expecting 41600 events.
[14:21:58.098] <TB0> INFO: 41600 events read in total (3224ms).
[14:21:58.099] <TB0> INFO: Test took 4056ms.
[14:21:58.384] <TB0> INFO: Expecting 41600 events.
[14:22:02.197] <TB0> INFO: 41600 events read in total (3257ms).
[14:22:02.197] <TB0> INFO: Test took 4077ms.
[14:22:02.480] <TB0> INFO: Expecting 41600 events.
[14:22:06.306] <TB0> INFO: 41600 events read in total (3270ms).
[14:22:06.307] <TB0> INFO: Test took 4090ms.
[14:22:06.592] <TB0> INFO: Expecting 41600 events.
[14:22:10.390] <TB0> INFO: 41600 events read in total (3242ms).
[14:22:10.391] <TB0> INFO: Test took 4063ms.
[14:22:10.669] <TB0> INFO: Expecting 41600 events.
[14:22:14.437] <TB0> INFO: 41600 events read in total (3212ms).
[14:22:14.437] <TB0> INFO: Test took 4026ms.
[14:22:14.719] <TB0> INFO: Expecting 41600 events.
[14:22:18.556] <TB0> INFO: 41600 events read in total (3281ms).
[14:22:18.557] <TB0> INFO: Test took 4100ms.
[14:22:18.837] <TB0> INFO: Expecting 41600 events.
[14:22:22.687] <TB0> INFO: 41600 events read in total (3294ms).
[14:22:22.688] <TB0> INFO: Test took 4112ms.
[14:22:22.982] <TB0> INFO: Expecting 41600 events.
[14:22:26.795] <TB0> INFO: 41600 events read in total (3257ms).
[14:22:26.795] <TB0> INFO: Test took 4087ms.
[14:22:27.086] <TB0> INFO: Expecting 41600 events.
[14:22:30.941] <TB0> INFO: 41600 events read in total (3299ms).
[14:22:30.942] <TB0> INFO: Test took 4127ms.
[14:22:31.228] <TB0> INFO: Expecting 41600 events.
[14:22:35.068] <TB0> INFO: 41600 events read in total (3284ms).
[14:22:35.068] <TB0> INFO: Test took 4106ms.
[14:22:35.349] <TB0> INFO: Expecting 41600 events.
[14:22:39.206] <TB0> INFO: 41600 events read in total (3301ms).
[14:22:39.207] <TB0> INFO: Test took 4119ms.
[14:22:39.497] <TB0> INFO: Expecting 41600 events.
[14:22:43.333] <TB0> INFO: 41600 events read in total (3279ms).
[14:22:43.333] <TB0> INFO: Test took 4106ms.
[14:22:43.626] <TB0> INFO: Expecting 41600 events.
[14:22:47.454] <TB0> INFO: 41600 events read in total (3271ms).
[14:22:47.454] <TB0> INFO: Test took 4101ms.
[14:22:47.747] <TB0> INFO: Expecting 41600 events.
[14:22:51.567] <TB0> INFO: 41600 events read in total (3264ms).
[14:22:51.568] <TB0> INFO: Test took 4093ms.
[14:22:51.860] <TB0> INFO: Expecting 41600 events.
[14:22:55.696] <TB0> INFO: 41600 events read in total (3280ms).
[14:22:55.697] <TB0> INFO: Test took 4109ms.
[14:22:55.995] <TB0> INFO: Expecting 41600 events.
[14:22:59.844] <TB0> INFO: 41600 events read in total (3293ms).
[14:22:59.845] <TB0> INFO: Test took 4127ms.
[14:23:00.129] <TB0> INFO: Expecting 41600 events.
[14:23:03.969] <TB0> INFO: 41600 events read in total (3284ms).
[14:23:03.969] <TB0> INFO: Test took 4104ms.
[14:23:04.251] <TB0> INFO: Expecting 41600 events.
[14:23:08.137] <TB0> INFO: 41600 events read in total (3330ms).
[14:23:08.138] <TB0> INFO: Test took 4149ms.
[14:23:08.432] <TB0> INFO: Expecting 41600 events.
[14:23:12.302] <TB0> INFO: 41600 events read in total (3313ms).
[14:23:12.303] <TB0> INFO: Test took 4145ms.
[14:23:12.588] <TB0> INFO: Expecting 41600 events.
[14:23:16.440] <TB0> INFO: 41600 events read in total (3296ms).
[14:23:16.440] <TB0> INFO: Test took 4117ms.
[14:23:16.735] <TB0> INFO: Expecting 41600 events.
[14:23:20.510] <TB0> INFO: 41600 events read in total (3219ms).
[14:23:20.510] <TB0> INFO: Test took 4050ms.
[14:23:20.785] <TB0> INFO: Expecting 41600 events.
[14:23:24.601] <TB0> INFO: 41600 events read in total (3260ms).
[14:23:24.602] <TB0> INFO: Test took 4072ms.
[14:23:24.885] <TB0> INFO: Expecting 41600 events.
[14:23:28.745] <TB0> INFO: 41600 events read in total (3304ms).
[14:23:28.745] <TB0> INFO: Test took 4123ms.
[14:23:29.041] <TB0> INFO: Expecting 41600 events.
[14:23:32.883] <TB0> INFO: 41600 events read in total (3286ms).
[14:23:32.884] <TB0> INFO: Test took 4118ms.
[14:23:33.162] <TB0> INFO: Expecting 41600 events.
[14:23:37.002] <TB0> INFO: 41600 events read in total (3284ms).
[14:23:37.002] <TB0> INFO: Test took 4099ms.
[14:23:37.285] <TB0> INFO: Expecting 41600 events.
[14:23:41.096] <TB0> INFO: 41600 events read in total (3255ms).
[14:23:41.097] <TB0> INFO: Test took 4074ms.
[14:23:41.378] <TB0> INFO: Expecting 41600 events.
[14:23:45.169] <TB0> INFO: 41600 events read in total (3235ms).
[14:23:45.170] <TB0> INFO: Test took 4053ms.
[14:23:45.451] <TB0> INFO: Expecting 41600 events.
[14:23:49.248] <TB0> INFO: 41600 events read in total (3241ms).
[14:23:49.249] <TB0> INFO: Test took 4059ms.
[14:23:49.539] <TB0> INFO: Expecting 41600 events.
[14:23:53.329] <TB0> INFO: 41600 events read in total (3234ms).
[14:23:53.329] <TB0> INFO: Test took 4060ms.
[14:23:53.625] <TB0> INFO: Expecting 41600 events.
[14:23:57.506] <TB0> INFO: 41600 events read in total (3325ms).
[14:23:57.507] <TB0> INFO: Test took 4157ms.
[14:23:57.805] <TB0> INFO: Expecting 41600 events.
[14:24:01.655] <TB0> INFO: 41600 events read in total (3294ms).
[14:24:01.656] <TB0> INFO: Test took 4129ms.
[14:24:01.941] <TB0> INFO: Expecting 41600 events.
[14:24:05.764] <TB0> INFO: 41600 events read in total (3267ms).
[14:24:05.764] <TB0> INFO: Test took 4088ms.
[14:24:06.055] <TB0> INFO: Expecting 41600 events.
[14:24:09.878] <TB0> INFO: 41600 events read in total (3266ms).
[14:24:09.879] <TB0> INFO: Test took 4095ms.
[14:24:10.162] <TB0> INFO: Expecting 41600 events.
[14:24:13.982] <TB0> INFO: 41600 events read in total (3264ms).
[14:24:13.982] <TB0> INFO: Test took 4083ms.
[14:24:14.277] <TB0> INFO: Expecting 41600 events.
[14:24:18.134] <TB0> INFO: 41600 events read in total (3301ms).
[14:24:18.135] <TB0> INFO: Test took 4133ms.
[14:24:18.426] <TB0> INFO: Expecting 41600 events.
[14:24:22.264] <TB0> INFO: 41600 events read in total (3282ms).
[14:24:22.265] <TB0> INFO: Test took 4110ms.
[14:24:22.546] <TB0> INFO: Expecting 41600 events.
[14:24:26.338] <TB0> INFO: 41600 events read in total (3236ms).
[14:24:26.339] <TB0> INFO: Test took 4052ms.
[14:24:26.624] <TB0> INFO: Expecting 41600 events.
[14:24:30.383] <TB0> INFO: 41600 events read in total (3203ms).
[14:24:30.383] <TB0> INFO: Test took 4024ms.
[14:24:30.664] <TB0> INFO: Expecting 41600 events.
[14:24:34.556] <TB0> INFO: 41600 events read in total (3335ms).
[14:24:34.557] <TB0> INFO: Test took 4154ms.
[14:24:34.839] <TB0> INFO: Expecting 41600 events.
[14:24:38.692] <TB0> INFO: 41600 events read in total (3297ms).
[14:24:38.693] <TB0> INFO: Test took 4115ms.
[14:24:38.949] <TB0> INFO: Expecting 2560 events.
[14:24:39.804] <TB0> INFO: 2560 events read in total (299ms).
[14:24:39.804] <TB0> INFO: Test took 1097ms.
[14:24:40.109] <TB0> INFO: Expecting 2560 events.
[14:24:40.963] <TB0> INFO: 2560 events read in total (298ms).
[14:24:40.963] <TB0> INFO: Test took 1159ms.
[14:24:41.268] <TB0> INFO: Expecting 2560 events.
[14:24:42.126] <TB0> INFO: 2560 events read in total (302ms).
[14:24:42.127] <TB0> INFO: Test took 1164ms.
[14:24:42.431] <TB0> INFO: Expecting 2560 events.
[14:24:43.283] <TB0> INFO: 2560 events read in total (296ms).
[14:24:43.283] <TB0> INFO: Test took 1156ms.
[14:24:43.588] <TB0> INFO: Expecting 2560 events.
[14:24:44.444] <TB0> INFO: 2560 events read in total (299ms).
[14:24:44.444] <TB0> INFO: Test took 1160ms.
[14:24:44.749] <TB0> INFO: Expecting 2560 events.
[14:24:45.603] <TB0> INFO: 2560 events read in total (298ms).
[14:24:45.603] <TB0> INFO: Test took 1159ms.
[14:24:45.909] <TB0> INFO: Expecting 2560 events.
[14:24:46.763] <TB0> INFO: 2560 events read in total (298ms).
[14:24:46.763] <TB0> INFO: Test took 1160ms.
[14:24:47.069] <TB0> INFO: Expecting 2560 events.
[14:24:47.925] <TB0> INFO: 2560 events read in total (300ms).
[14:24:47.925] <TB0> INFO: Test took 1161ms.
[14:24:48.230] <TB0> INFO: Expecting 2560 events.
[14:24:49.085] <TB0> INFO: 2560 events read in total (299ms).
[14:24:49.086] <TB0> INFO: Test took 1160ms.
[14:24:49.391] <TB0> INFO: Expecting 2560 events.
[14:24:50.245] <TB0> INFO: 2560 events read in total (298ms).
[14:24:50.246] <TB0> INFO: Test took 1160ms.
[14:24:50.551] <TB0> INFO: Expecting 2560 events.
[14:24:51.405] <TB0> INFO: 2560 events read in total (298ms).
[14:24:51.405] <TB0> INFO: Test took 1159ms.
[14:24:51.710] <TB0> INFO: Expecting 2560 events.
[14:24:52.563] <TB0> INFO: 2560 events read in total (297ms).
[14:24:52.563] <TB0> INFO: Test took 1157ms.
[14:24:52.868] <TB0> INFO: Expecting 2560 events.
[14:24:53.724] <TB0> INFO: 2560 events read in total (300ms).
[14:24:53.724] <TB0> INFO: Test took 1161ms.
[14:24:54.029] <TB0> INFO: Expecting 2560 events.
[14:24:54.888] <TB0> INFO: 2560 events read in total (303ms).
[14:24:54.888] <TB0> INFO: Test took 1163ms.
[14:24:55.193] <TB0> INFO: Expecting 2560 events.
[14:24:56.046] <TB0> INFO: 2560 events read in total (296ms).
[14:24:56.047] <TB0> INFO: Test took 1158ms.
[14:24:56.352] <TB0> INFO: Expecting 2560 events.
[14:24:57.211] <TB0> INFO: 2560 events read in total (303ms).
[14:24:57.211] <TB0> INFO: Test took 1164ms.
[14:24:57.214] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:57.517] <TB0> INFO: Expecting 655360 events.
[14:25:14.551] <TB0> INFO: 655360 events read in total (16478ms).
[14:25:14.560] <TB0> INFO: Expecting 655360 events.
[14:25:31.393] <TB0> INFO: 655360 events read in total (16431ms).
[14:25:31.406] <TB0> INFO: Expecting 655360 events.
[14:25:48.248] <TB0> INFO: 655360 events read in total (16441ms).
[14:25:48.263] <TB0> INFO: Expecting 655360 events.
[14:26:04.775] <TB0> INFO: 655360 events read in total (16110ms).
[14:26:04.794] <TB0> INFO: Expecting 655360 events.
[14:26:21.241] <TB0> INFO: 655360 events read in total (16045ms).
[14:26:21.262] <TB0> INFO: Expecting 655360 events.
[14:26:38.210] <TB0> INFO: 655360 events read in total (16546ms).
[14:26:38.235] <TB0> INFO: Expecting 655360 events.
[14:26:55.289] <TB0> INFO: 655360 events read in total (16652ms).
[14:26:55.323] <TB0> INFO: Expecting 655360 events.
[14:27:12.104] <TB0> INFO: 655360 events read in total (16379ms).
[14:27:12.138] <TB0> INFO: Expecting 655360 events.
[14:27:29.064] <TB0> INFO: 655360 events read in total (16524ms).
[14:27:29.103] <TB0> INFO: Expecting 655360 events.
[14:27:46.112] <TB0> INFO: 655360 events read in total (16607ms).
[14:27:46.153] <TB0> INFO: Expecting 655360 events.
[14:28:03.233] <TB0> INFO: 655360 events read in total (16678ms).
[14:28:03.280] <TB0> INFO: Expecting 655360 events.
[14:28:19.884] <TB0> INFO: 655360 events read in total (16202ms).
[14:28:19.930] <TB0> INFO: Expecting 655360 events.
[14:28:36.503] <TB0> INFO: 655360 events read in total (16172ms).
[14:28:36.551] <TB0> INFO: Expecting 655360 events.
[14:28:53.261] <TB0> INFO: 655360 events read in total (16308ms).
[14:28:53.316] <TB0> INFO: Expecting 655360 events.
[14:29:10.232] <TB0> INFO: 655360 events read in total (16514ms).
[14:29:10.288] <TB0> INFO: Expecting 655360 events.
[14:29:27.249] <TB0> INFO: 655360 events read in total (16559ms).
[14:29:27.310] <TB0> INFO: Test took 270096ms.
[14:29:27.389] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:29:27.621] <TB0> INFO: Expecting 655360 events.
[14:29:44.515] <TB0> INFO: 655360 events read in total (16338ms).
[14:29:44.524] <TB0> INFO: Expecting 655360 events.
[14:30:01.260] <TB0> INFO: 655360 events read in total (16334ms).
[14:30:01.275] <TB0> INFO: Expecting 655360 events.
[14:30:17.863] <TB0> INFO: 655360 events read in total (16186ms).
[14:30:17.879] <TB0> INFO: Expecting 655360 events.
[14:30:34.588] <TB0> INFO: 655360 events read in total (16307ms).
[14:30:34.607] <TB0> INFO: Expecting 655360 events.
[14:30:51.051] <TB0> INFO: 655360 events read in total (16042ms).
[14:30:51.073] <TB0> INFO: Expecting 655360 events.
[14:31:07.424] <TB0> INFO: 655360 events read in total (15949ms).
[14:31:07.450] <TB0> INFO: Expecting 655360 events.
[14:31:24.008] <TB0> INFO: 655360 events read in total (16157ms).
[14:31:24.036] <TB0> INFO: Expecting 655360 events.
[14:31:40.781] <TB0> INFO: 655360 events read in total (16343ms).
[14:31:40.812] <TB0> INFO: Expecting 655360 events.
[14:31:57.442] <TB0> INFO: 655360 events read in total (16228ms).
[14:31:57.484] <TB0> INFO: Expecting 655360 events.
[14:32:14.264] <TB0> INFO: 655360 events read in total (16378ms).
[14:32:14.321] <TB0> INFO: Expecting 655360 events.
[14:32:30.895] <TB0> INFO: 655360 events read in total (16173ms).
[14:32:30.936] <TB0> INFO: Expecting 655360 events.
[14:32:47.528] <TB0> INFO: 655360 events read in total (16190ms).
[14:32:47.573] <TB0> INFO: Expecting 655360 events.
[14:33:04.550] <TB0> INFO: 655360 events read in total (16575ms).
[14:33:04.600] <TB0> INFO: Expecting 655360 events.
[14:33:21.517] <TB0> INFO: 655360 events read in total (16515ms).
[14:33:21.569] <TB0> INFO: Expecting 655360 events.
[14:33:38.432] <TB0> INFO: 655360 events read in total (16461ms).
[14:33:38.490] <TB0> INFO: Expecting 655360 events.
[14:33:55.161] <TB0> INFO: 655360 events read in total (16269ms).
[14:33:55.223] <TB0> INFO: Test took 267834ms.
[14:33:55.407] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:33:55.418] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:33:55.428] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:33:55.439] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:33:55.450] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[14:33:55.460] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[14:33:55.467] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:33:55.474] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:33:55.480] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:33:55.491] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:33:55.501] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:33:55.511] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:33:55.519] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:33:55.526] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:33:55.533] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:33:55.539] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:33:55.546] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:33:55.552] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[14:33:55.589] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C0.dat
[14:33:55.589] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C1.dat
[14:33:55.589] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C2.dat
[14:33:55.590] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C3.dat
[14:33:55.590] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C4.dat
[14:33:55.590] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C5.dat
[14:33:55.590] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C6.dat
[14:33:55.590] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C7.dat
[14:33:55.590] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C8.dat
[14:33:55.590] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C9.dat
[14:33:55.590] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C10.dat
[14:33:55.590] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C11.dat
[14:33:55.591] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C12.dat
[14:33:55.591] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C13.dat
[14:33:55.591] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C14.dat
[14:33:55.591] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//dacParameters35_C15.dat
[14:33:55.857] <TB0> INFO: Expecting 41600 events.
[14:33:59.334] <TB0> INFO: 41600 events read in total (2921ms).
[14:33:59.334] <TB0> INFO: Test took 3740ms.
[14:33:59.773] <TB0> INFO: Expecting 41600 events.
[14:34:03.239] <TB0> INFO: 41600 events read in total (2910ms).
[14:34:03.239] <TB0> INFO: Test took 3727ms.
[14:34:03.674] <TB0> INFO: Expecting 41600 events.
[14:34:07.136] <TB0> INFO: 41600 events read in total (2906ms).
[14:34:07.136] <TB0> INFO: Test took 3719ms.
[14:34:07.319] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:07.407] <TB0> INFO: Expecting 2560 events.
[14:34:08.263] <TB0> INFO: 2560 events read in total (300ms).
[14:34:08.263] <TB0> INFO: Test took 944ms.
[14:34:08.265] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:08.569] <TB0> INFO: Expecting 2560 events.
[14:34:09.424] <TB0> INFO: 2560 events read in total (299ms).
[14:34:09.424] <TB0> INFO: Test took 1159ms.
[14:34:09.426] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:09.730] <TB0> INFO: Expecting 2560 events.
[14:34:10.585] <TB0> INFO: 2560 events read in total (298ms).
[14:34:10.585] <TB0> INFO: Test took 1159ms.
[14:34:10.587] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:10.891] <TB0> INFO: Expecting 2560 events.
[14:34:11.745] <TB0> INFO: 2560 events read in total (298ms).
[14:34:11.745] <TB0> INFO: Test took 1158ms.
[14:34:11.747] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:12.051] <TB0> INFO: Expecting 2560 events.
[14:34:12.913] <TB0> INFO: 2560 events read in total (305ms).
[14:34:12.913] <TB0> INFO: Test took 1166ms.
[14:34:12.916] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:13.220] <TB0> INFO: Expecting 2560 events.
[14:34:14.074] <TB0> INFO: 2560 events read in total (298ms).
[14:34:14.074] <TB0> INFO: Test took 1158ms.
[14:34:14.076] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:14.381] <TB0> INFO: Expecting 2560 events.
[14:34:15.243] <TB0> INFO: 2560 events read in total (305ms).
[14:34:15.243] <TB0> INFO: Test took 1167ms.
[14:34:15.245] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:15.550] <TB0> INFO: Expecting 2560 events.
[14:34:16.406] <TB0> INFO: 2560 events read in total (299ms).
[14:34:16.406] <TB0> INFO: Test took 1161ms.
[14:34:16.409] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:16.713] <TB0> INFO: Expecting 2560 events.
[14:34:17.574] <TB0> INFO: 2560 events read in total (304ms).
[14:34:17.574] <TB0> INFO: Test took 1166ms.
[14:34:17.576] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:17.880] <TB0> INFO: Expecting 2560 events.
[14:34:18.732] <TB0> INFO: 2560 events read in total (296ms).
[14:34:18.732] <TB0> INFO: Test took 1156ms.
[14:34:18.734] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:19.038] <TB0> INFO: Expecting 2560 events.
[14:34:19.891] <TB0> INFO: 2560 events read in total (297ms).
[14:34:19.891] <TB0> INFO: Test took 1157ms.
[14:34:19.893] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:20.197] <TB0> INFO: Expecting 2560 events.
[14:34:21.050] <TB0> INFO: 2560 events read in total (296ms).
[14:34:21.050] <TB0> INFO: Test took 1157ms.
[14:34:21.052] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:21.356] <TB0> INFO: Expecting 2560 events.
[14:34:22.209] <TB0> INFO: 2560 events read in total (297ms).
[14:34:22.209] <TB0> INFO: Test took 1157ms.
[14:34:22.211] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:22.515] <TB0> INFO: Expecting 2560 events.
[14:34:23.368] <TB0> INFO: 2560 events read in total (297ms).
[14:34:23.369] <TB0> INFO: Test took 1158ms.
[14:34:23.371] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:23.674] <TB0> INFO: Expecting 2560 events.
[14:34:24.528] <TB0> INFO: 2560 events read in total (297ms).
[14:34:24.529] <TB0> INFO: Test took 1158ms.
[14:34:24.531] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:24.835] <TB0> INFO: Expecting 2560 events.
[14:34:25.687] <TB0> INFO: 2560 events read in total (296ms).
[14:34:25.687] <TB0> INFO: Test took 1157ms.
[14:34:25.689] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:25.993] <TB0> INFO: Expecting 2560 events.
[14:34:26.846] <TB0> INFO: 2560 events read in total (297ms).
[14:34:26.846] <TB0> INFO: Test took 1157ms.
[14:34:26.848] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:27.152] <TB0> INFO: Expecting 2560 events.
[14:34:28.005] <TB0> INFO: 2560 events read in total (297ms).
[14:34:28.005] <TB0> INFO: Test took 1157ms.
[14:34:28.007] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:28.311] <TB0> INFO: Expecting 2560 events.
[14:34:29.164] <TB0> INFO: 2560 events read in total (297ms).
[14:34:29.164] <TB0> INFO: Test took 1157ms.
[14:34:29.166] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:29.470] <TB0> INFO: Expecting 2560 events.
[14:34:30.323] <TB0> INFO: 2560 events read in total (297ms).
[14:34:30.323] <TB0> INFO: Test took 1157ms.
[14:34:30.325] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:30.629] <TB0> INFO: Expecting 2560 events.
[14:34:31.482] <TB0> INFO: 2560 events read in total (297ms).
[14:34:31.482] <TB0> INFO: Test took 1157ms.
[14:34:31.484] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:31.788] <TB0> INFO: Expecting 2560 events.
[14:34:32.641] <TB0> INFO: 2560 events read in total (297ms).
[14:34:32.641] <TB0> INFO: Test took 1157ms.
[14:34:32.643] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:32.947] <TB0> INFO: Expecting 2560 events.
[14:34:33.800] <TB0> INFO: 2560 events read in total (297ms).
[14:34:33.800] <TB0> INFO: Test took 1157ms.
[14:34:33.802] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:34.106] <TB0> INFO: Expecting 2560 events.
[14:34:34.958] <TB0> INFO: 2560 events read in total (296ms).
[14:34:34.959] <TB0> INFO: Test took 1157ms.
[14:34:34.961] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:35.264] <TB0> INFO: Expecting 2560 events.
[14:34:36.117] <TB0> INFO: 2560 events read in total (296ms).
[14:34:36.118] <TB0> INFO: Test took 1158ms.
[14:34:36.120] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:36.424] <TB0> INFO: Expecting 2560 events.
[14:34:37.276] <TB0> INFO: 2560 events read in total (296ms).
[14:34:37.276] <TB0> INFO: Test took 1157ms.
[14:34:37.278] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:37.582] <TB0> INFO: Expecting 2560 events.
[14:34:38.436] <TB0> INFO: 2560 events read in total (297ms).
[14:34:38.436] <TB0> INFO: Test took 1158ms.
[14:34:38.438] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:38.742] <TB0> INFO: Expecting 2560 events.
[14:34:39.594] <TB0> INFO: 2560 events read in total (296ms).
[14:34:39.594] <TB0> INFO: Test took 1156ms.
[14:34:39.596] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:39.900] <TB0> INFO: Expecting 2560 events.
[14:34:40.753] <TB0> INFO: 2560 events read in total (296ms).
[14:34:40.753] <TB0> INFO: Test took 1157ms.
[14:34:40.755] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:41.059] <TB0> INFO: Expecting 2560 events.
[14:34:41.912] <TB0> INFO: 2560 events read in total (296ms).
[14:34:41.912] <TB0> INFO: Test took 1157ms.
[14:34:41.914] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:42.218] <TB0> INFO: Expecting 2560 events.
[14:34:43.071] <TB0> INFO: 2560 events read in total (296ms).
[14:34:43.071] <TB0> INFO: Test took 1157ms.
[14:34:43.073] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:34:43.377] <TB0> INFO: Expecting 2560 events.
[14:34:44.230] <TB0> INFO: 2560 events read in total (297ms).
[14:34:44.230] <TB0> INFO: Test took 1157ms.
[14:34:44.689] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 828 seconds
[14:34:44.689] <TB0> INFO: PH scale (per ROC): 79 82 92 80 77 80 86 80 79 92 94 80 79 87 83 81
[14:34:44.689] <TB0> INFO: PH offset (per ROC): 150 149 148 161 172 148 139 137 144 148 148 155 152 144 147 151
[14:34:44.693] <TB0> INFO: Decoding statistics:
[14:34:44.693] <TB0> INFO: General information:
[14:34:44.693] <TB0> INFO: 16bit words read: 91928
[14:34:44.693] <TB0> INFO: valid events total: 10240
[14:34:44.693] <TB0> INFO: empty events: 7738
[14:34:44.693] <TB0> INFO: valid events with pixels: 2502
[14:34:44.693] <TB0> INFO: valid pixel hits: 2502
[14:34:44.693] <TB0> INFO: Event errors: 0
[14:34:44.693] <TB0> INFO: start marker: 0
[14:34:44.693] <TB0> INFO: stop marker: 0
[14:34:44.693] <TB0> INFO: overflow: 0
[14:34:44.693] <TB0> INFO: invalid 5bit words: 0
[14:34:44.693] <TB0> INFO: invalid XOR eye diagram: 0
[14:34:44.693] <TB0> INFO: frame (failed synchr.): 0
[14:34:44.693] <TB0> INFO: idle data (no TBM trl): 0
[14:34:44.693] <TB0> INFO: no data (only TBM hdr): 0
[14:34:44.693] <TB0> INFO: TBM errors: 0
[14:34:44.693] <TB0> INFO: flawed TBM headers: 0
[14:34:44.693] <TB0> INFO: flawed TBM trailers: 0
[14:34:44.693] <TB0> INFO: event ID mismatches: 0
[14:34:44.693] <TB0> INFO: ROC errors: 0
[14:34:44.693] <TB0> INFO: missing ROC header(s): 0
[14:34:44.693] <TB0> INFO: misplaced readback start: 0
[14:34:44.693] <TB0> INFO: Pixel decoding errors: 0
[14:34:44.693] <TB0> INFO: pixel data incomplete: 0
[14:34:44.693] <TB0> INFO: pixel address: 0
[14:34:44.693] <TB0> INFO: pulse height fill bit: 0
[14:34:44.693] <TB0> INFO: buffer corruption: 0
[14:34:44.864] <TB0> INFO: ######################################################################
[14:34:44.864] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[14:34:44.864] <TB0> INFO: ######################################################################
[14:34:44.873] <TB0> INFO: scanning low vcal = 10
[14:34:45.128] <TB0> INFO: Expecting 41600 events.
[14:34:48.647] <TB0> INFO: 41600 events read in total (2963ms).
[14:34:48.648] <TB0> INFO: Test took 3775ms.
[14:34:48.649] <TB0> INFO: scanning low vcal = 20
[14:34:48.953] <TB0> INFO: Expecting 41600 events.
[14:34:52.484] <TB0> INFO: 41600 events read in total (2975ms).
[14:34:52.484] <TB0> INFO: Test took 3835ms.
[14:34:52.486] <TB0> INFO: scanning low vcal = 30
[14:34:52.790] <TB0> INFO: Expecting 41600 events.
[14:34:56.318] <TB0> INFO: 41600 events read in total (2972ms).
[14:34:56.318] <TB0> INFO: Test took 3832ms.
[14:34:56.320] <TB0> INFO: scanning low vcal = 40
[14:34:56.619] <TB0> INFO: Expecting 41600 events.
[14:35:00.717] <TB0> INFO: 41600 events read in total (3542ms).
[14:35:00.717] <TB0> INFO: Test took 4397ms.
[14:35:00.720] <TB0> INFO: scanning low vcal = 50
[14:35:00.974] <TB0> INFO: Expecting 41600 events.
[14:35:05.126] <TB0> INFO: 41600 events read in total (3596ms).
[14:35:05.127] <TB0> INFO: Test took 4407ms.
[14:35:05.129] <TB0> INFO: scanning low vcal = 60
[14:35:05.384] <TB0> INFO: Expecting 41600 events.
[14:35:09.535] <TB0> INFO: 41600 events read in total (3595ms).
[14:35:09.536] <TB0> INFO: Test took 4407ms.
[14:35:09.538] <TB0> INFO: scanning low vcal = 70
[14:35:09.792] <TB0> INFO: Expecting 41600 events.
[14:35:13.941] <TB0> INFO: 41600 events read in total (3593ms).
[14:35:13.942] <TB0> INFO: Test took 4404ms.
[14:35:13.944] <TB0> INFO: scanning low vcal = 80
[14:35:14.196] <TB0> INFO: Expecting 41600 events.
[14:35:18.353] <TB0> INFO: 41600 events read in total (3600ms).
[14:35:18.353] <TB0> INFO: Test took 4408ms.
[14:35:18.356] <TB0> INFO: scanning low vcal = 90
[14:35:18.608] <TB0> INFO: Expecting 41600 events.
[14:35:22.758] <TB0> INFO: 41600 events read in total (3593ms).
[14:35:22.758] <TB0> INFO: Test took 4402ms.
[14:35:22.761] <TB0> INFO: scanning low vcal = 100
[14:35:23.013] <TB0> INFO: Expecting 41600 events.
[14:35:27.162] <TB0> INFO: 41600 events read in total (3593ms).
[14:35:27.163] <TB0> INFO: Test took 4401ms.
[14:35:27.165] <TB0> INFO: scanning low vcal = 110
[14:35:27.418] <TB0> INFO: Expecting 41600 events.
[14:35:31.568] <TB0> INFO: 41600 events read in total (3593ms).
[14:35:31.569] <TB0> INFO: Test took 4403ms.
[14:35:31.571] <TB0> INFO: scanning low vcal = 120
[14:35:31.824] <TB0> INFO: Expecting 41600 events.
[14:35:35.974] <TB0> INFO: 41600 events read in total (3594ms).
[14:35:35.975] <TB0> INFO: Test took 4404ms.
[14:35:35.978] <TB0> INFO: scanning low vcal = 130
[14:35:36.230] <TB0> INFO: Expecting 41600 events.
[14:35:40.383] <TB0> INFO: 41600 events read in total (3597ms).
[14:35:40.383] <TB0> INFO: Test took 4405ms.
[14:35:40.386] <TB0> INFO: scanning low vcal = 140
[14:35:40.645] <TB0> INFO: Expecting 41600 events.
[14:35:44.797] <TB0> INFO: 41600 events read in total (3596ms).
[14:35:44.798] <TB0> INFO: Test took 4412ms.
[14:35:44.800] <TB0> INFO: scanning low vcal = 150
[14:35:45.053] <TB0> INFO: Expecting 41600 events.
[14:35:49.203] <TB0> INFO: 41600 events read in total (3593ms).
[14:35:49.203] <TB0> INFO: Test took 4402ms.
[14:35:49.206] <TB0> INFO: scanning low vcal = 160
[14:35:49.457] <TB0> INFO: Expecting 41600 events.
[14:35:53.607] <TB0> INFO: 41600 events read in total (3593ms).
[14:35:53.608] <TB0> INFO: Test took 4402ms.
[14:35:53.611] <TB0> INFO: scanning low vcal = 170
[14:35:53.863] <TB0> INFO: Expecting 41600 events.
[14:35:58.048] <TB0> INFO: 41600 events read in total (3628ms).
[14:35:58.101] <TB0> INFO: Test took 4490ms.
[14:35:58.104] <TB0> INFO: scanning low vcal = 180
[14:35:58.357] <TB0> INFO: Expecting 41600 events.
[14:36:02.522] <TB0> INFO: 41600 events read in total (3609ms).
[14:36:02.522] <TB0> INFO: Test took 4418ms.
[14:36:02.525] <TB0> INFO: scanning low vcal = 190
[14:36:02.778] <TB0> INFO: Expecting 41600 events.
[14:36:06.933] <TB0> INFO: 41600 events read in total (3598ms).
[14:36:06.934] <TB0> INFO: Test took 4409ms.
[14:36:06.937] <TB0> INFO: scanning low vcal = 200
[14:36:07.193] <TB0> INFO: Expecting 41600 events.
[14:36:11.346] <TB0> INFO: 41600 events read in total (3597ms).
[14:36:11.346] <TB0> INFO: Test took 4409ms.
[14:36:11.349] <TB0> INFO: scanning low vcal = 210
[14:36:11.603] <TB0> INFO: Expecting 41600 events.
[14:36:15.756] <TB0> INFO: 41600 events read in total (3596ms).
[14:36:15.756] <TB0> INFO: Test took 4407ms.
[14:36:15.759] <TB0> INFO: scanning low vcal = 220
[14:36:16.013] <TB0> INFO: Expecting 41600 events.
[14:36:20.167] <TB0> INFO: 41600 events read in total (3598ms).
[14:36:20.167] <TB0> INFO: Test took 4408ms.
[14:36:20.170] <TB0> INFO: scanning low vcal = 230
[14:36:20.427] <TB0> INFO: Expecting 41600 events.
[14:36:24.580] <TB0> INFO: 41600 events read in total (3597ms).
[14:36:24.580] <TB0> INFO: Test took 4410ms.
[14:36:24.583] <TB0> INFO: scanning low vcal = 240
[14:36:24.839] <TB0> INFO: Expecting 41600 events.
[14:36:28.991] <TB0> INFO: 41600 events read in total (3596ms).
[14:36:28.991] <TB0> INFO: Test took 4408ms.
[14:36:28.994] <TB0> INFO: scanning low vcal = 250
[14:36:29.250] <TB0> INFO: Expecting 41600 events.
[14:36:33.403] <TB0> INFO: 41600 events read in total (3596ms).
[14:36:33.403] <TB0> INFO: Test took 4409ms.
[14:36:33.407] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[14:36:33.663] <TB0> INFO: Expecting 41600 events.
[14:36:37.817] <TB0> INFO: 41600 events read in total (3597ms).
[14:36:37.817] <TB0> INFO: Test took 4409ms.
[14:36:37.820] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[14:36:38.077] <TB0> INFO: Expecting 41600 events.
[14:36:42.227] <TB0> INFO: 41600 events read in total (3594ms).
[14:36:42.228] <TB0> INFO: Test took 4408ms.
[14:36:42.231] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[14:36:42.487] <TB0> INFO: Expecting 41600 events.
[14:36:46.637] <TB0> INFO: 41600 events read in total (3594ms).
[14:36:46.637] <TB0> INFO: Test took 4406ms.
[14:36:46.640] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[14:36:46.892] <TB0> INFO: Expecting 41600 events.
[14:36:51.042] <TB0> INFO: 41600 events read in total (3594ms).
[14:36:51.042] <TB0> INFO: Test took 4402ms.
[14:36:51.045] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[14:36:51.298] <TB0> INFO: Expecting 41600 events.
[14:36:55.450] <TB0> INFO: 41600 events read in total (3596ms).
[14:36:55.450] <TB0> INFO: Test took 4404ms.
[14:36:55.870] <TB0> INFO: PixTestGainPedestal::measure() done
[14:37:25.909] <TB0> INFO: PixTestGainPedestal::fit() done
[14:37:25.909] <TB0> INFO: non-linearity mean: 0.950 0.958 0.955 0.955 0.957 0.956 0.952 0.954 0.952 0.956 0.957 0.952 0.944 0.952 0.954 0.945
[14:37:25.909] <TB0> INFO: non-linearity RMS: 0.005 0.006 0.005 0.004 0.006 0.006 0.006 0.006 0.005 0.005 0.005 0.006 0.007 0.005 0.006 0.006
[14:37:25.909] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[14:37:25.928] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[14:37:25.946] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[14:37:25.965] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[14:37:25.985] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[14:37:26.005] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[14:37:26.023] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[14:37:26.042] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[14:37:26.061] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[14:37:26.079] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[14:37:26.098] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[14:37:26.116] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[14:37:26.135] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[14:37:26.154] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[14:37:26.172] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[14:37:26.191] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[14:37:26.209] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 161 seconds
[14:37:26.209] <TB0> INFO: Decoding statistics:
[14:37:26.209] <TB0> INFO: General information:
[14:37:26.209] <TB0> INFO: 16bit words read: 2662400
[14:37:26.209] <TB0> INFO: valid events total: 166400
[14:37:26.209] <TB0> INFO: empty events: 0
[14:37:26.209] <TB0> INFO: valid events with pixels: 166400
[14:37:26.209] <TB0> INFO: valid pixel hits: 665590
[14:37:26.209] <TB0> INFO: Event errors: 0
[14:37:26.209] <TB0> INFO: start marker: 0
[14:37:26.209] <TB0> INFO: stop marker: 0
[14:37:26.209] <TB0> INFO: overflow: 0
[14:37:26.210] <TB0> INFO: invalid 5bit words: 0
[14:37:26.210] <TB0> INFO: invalid XOR eye diagram: 0
[14:37:26.210] <TB0> INFO: frame (failed synchr.): 0
[14:37:26.210] <TB0> INFO: idle data (no TBM trl): 0
[14:37:26.210] <TB0> INFO: no data (only TBM hdr): 0
[14:37:26.210] <TB0> INFO: TBM errors: 0
[14:37:26.210] <TB0> INFO: flawed TBM headers: 0
[14:37:26.210] <TB0> INFO: flawed TBM trailers: 0
[14:37:26.210] <TB0> INFO: event ID mismatches: 0
[14:37:26.210] <TB0> INFO: ROC errors: 0
[14:37:26.210] <TB0> INFO: missing ROC header(s): 0
[14:37:26.210] <TB0> INFO: misplaced readback start: 0
[14:37:26.210] <TB0> INFO: Pixel decoding errors: 0
[14:37:26.210] <TB0> INFO: pixel data incomplete: 0
[14:37:26.210] <TB0> INFO: pixel address: 0
[14:37:26.210] <TB0> INFO: pulse height fill bit: 0
[14:37:26.210] <TB0> INFO: buffer corruption: 0
[14:37:26.220] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C15.dat
[14:37:26.221] <TB0> INFO: ######################################################################
[14:37:26.221] <TB0> INFO: PixTestReadback::doTest()
[14:37:26.221] <TB0> INFO: ######################################################################
[14:37:26.221] <TB0> INFO: ----------------------------------------------------------------------
[14:37:26.222] <TB0> INFO: PixTestReadback::CalibrateVd()
[14:37:26.222] <TB0> INFO: ----------------------------------------------------------------------
[14:37:35.660] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C0.dat
[14:37:35.660] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C1.dat
[14:37:35.660] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C2.dat
[14:37:35.660] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C3.dat
[14:37:35.660] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C4.dat
[14:37:35.660] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C5.dat
[14:37:35.660] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C6.dat
[14:37:35.660] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C7.dat
[14:37:35.660] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C8.dat
[14:37:35.660] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C9.dat
[14:37:35.661] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C10.dat
[14:37:35.661] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C11.dat
[14:37:35.661] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C12.dat
[14:37:35.661] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C13.dat
[14:37:35.661] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C14.dat
[14:37:35.661] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C15.dat
[14:37:35.689] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[14:37:35.689] <TB0> INFO: ----------------------------------------------------------------------
[14:37:35.689] <TB0> INFO: PixTestReadback::CalibrateVa()
[14:37:35.689] <TB0> INFO: ----------------------------------------------------------------------
[14:37:45.135] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C0.dat
[14:37:45.136] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C1.dat
[14:37:45.136] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C2.dat
[14:37:45.136] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C3.dat
[14:37:45.136] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C4.dat
[14:37:45.136] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C5.dat
[14:37:45.136] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C6.dat
[14:37:45.137] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C7.dat
[14:37:45.137] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C8.dat
[14:37:45.137] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C9.dat
[14:37:45.137] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C10.dat
[14:37:45.137] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C11.dat
[14:37:45.137] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C12.dat
[14:37:45.137] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C13.dat
[14:37:45.137] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C14.dat
[14:37:45.137] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C15.dat
[14:37:45.164] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[14:37:45.164] <TB0> INFO: ----------------------------------------------------------------------
[14:37:45.164] <TB0> INFO: PixTestReadback::readbackVbg()
[14:37:45.164] <TB0> INFO: ----------------------------------------------------------------------
[14:37:52.420] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[14:37:52.420] <TB0> INFO: ----------------------------------------------------------------------
[14:37:52.420] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[14:37:52.420] <TB0> INFO: ----------------------------------------------------------------------
[14:37:52.420] <TB0> INFO: Vbg will be calibrated using Vd calibration
[14:37:52.420] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 147calibrated Vbg = 1.22424 :::*/*/*/*/
[14:37:52.420] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 148.1calibrated Vbg = 1.21939 :::*/*/*/*/
[14:37:52.420] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 157.8calibrated Vbg = 1.22809 :::*/*/*/*/
[14:37:52.420] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 154.1calibrated Vbg = 1.2295 :::*/*/*/*/
[14:37:52.420] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 156.2calibrated Vbg = 1.22892 :::*/*/*/*/
[14:37:52.420] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 150.4calibrated Vbg = 1.23569 :::*/*/*/*/
[14:37:52.420] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 144.6calibrated Vbg = 1.24226 :::*/*/*/*/
[14:37:52.420] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 157.4calibrated Vbg = 1.23678 :::*/*/*/*/
[14:37:52.420] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 140.2calibrated Vbg = 1.2415 :::*/*/*/*/
[14:37:52.420] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 143.9calibrated Vbg = 1.24155 :::*/*/*/*/
[14:37:52.420] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 162.8calibrated Vbg = 1.23979 :::*/*/*/*/
[14:37:52.420] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 145.4calibrated Vbg = 1.24315 :::*/*/*/*/
[14:37:52.420] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 154.9calibrated Vbg = 1.22955 :::*/*/*/*/
[14:37:52.420] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 155.3calibrated Vbg = 1.22469 :::*/*/*/*/
[14:37:52.420] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 154.3calibrated Vbg = 1.2253 :::*/*/*/*/
[14:37:52.420] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 160.8calibrated Vbg = 1.22405 :::*/*/*/*/
[14:37:52.422] <TB0> INFO: ----------------------------------------------------------------------
[14:37:52.422] <TB0> INFO: PixTestReadback::CalibrateIa()
[14:37:52.422] <TB0> INFO: ----------------------------------------------------------------------
[14:40:27.458] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C0.dat
[14:40:27.465] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C1.dat
[14:40:27.465] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C2.dat
[14:40:27.465] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C3.dat
[14:40:27.465] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C4.dat
[14:40:27.465] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C5.dat
[14:40:27.466] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C6.dat
[14:40:27.466] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C7.dat
[14:40:27.466] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C8.dat
[14:40:27.466] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C9.dat
[14:40:27.466] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C10.dat
[14:40:27.466] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C11.dat
[14:40:27.466] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C12.dat
[14:40:27.466] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C13.dat
[14:40:27.466] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C14.dat
[14:40:27.466] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2276_FullQualification_2016-07-19_09h53m_1468914790//002_FulltestPxar_m20//readbackCal_C15.dat
[14:40:27.493] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[14:40:27.494] <TB0> INFO: PixTestReadback::doTest() done
[14:40:27.494] <TB0> INFO: Decoding statistics:
[14:40:27.494] <TB0> INFO: General information:
[14:40:27.494] <TB0> INFO: 16bit words read: 1024
[14:40:27.494] <TB0> INFO: valid events total: 128
[14:40:27.494] <TB0> INFO: empty events: 128
[14:40:27.494] <TB0> INFO: valid events with pixels: 0
[14:40:27.494] <TB0> INFO: valid pixel hits: 0
[14:40:27.494] <TB0> INFO: Event errors: 0
[14:40:27.494] <TB0> INFO: start marker: 0
[14:40:27.494] <TB0> INFO: stop marker: 0
[14:40:27.494] <TB0> INFO: overflow: 0
[14:40:27.494] <TB0> INFO: invalid 5bit words: 0
[14:40:27.494] <TB0> INFO: invalid XOR eye diagram: 0
[14:40:27.494] <TB0> INFO: frame (failed synchr.): 0
[14:40:27.494] <TB0> INFO: idle data (no TBM trl): 0
[14:40:27.494] <TB0> INFO: no data (only TBM hdr): 0
[14:40:27.494] <TB0> INFO: TBM errors: 0
[14:40:27.494] <TB0> INFO: flawed TBM headers: 0
[14:40:27.494] <TB0> INFO: flawed TBM trailers: 0
[14:40:27.494] <TB0> INFO: event ID mismatches: 0
[14:40:27.494] <TB0> INFO: ROC errors: 0
[14:40:27.494] <TB0> INFO: missing ROC header(s): 0
[14:40:27.494] <TB0> INFO: misplaced readback start: 0
[14:40:27.494] <TB0> INFO: Pixel decoding errors: 0
[14:40:27.494] <TB0> INFO: pixel data incomplete: 0
[14:40:27.494] <TB0> INFO: pixel address: 0
[14:40:27.494] <TB0> INFO: pulse height fill bit: 0
[14:40:27.494] <TB0> INFO: buffer corruption: 0
[14:40:27.514] <TB0> INFO: Decoding statistics:
[14:40:27.514] <TB0> INFO: General information:
[14:40:27.514] <TB0> INFO: 16bit words read: 2755352
[14:40:27.514] <TB0> INFO: valid events total: 176768
[14:40:27.514] <TB0> INFO: empty events: 7866
[14:40:27.514] <TB0> INFO: valid events with pixels: 168902
[14:40:27.514] <TB0> INFO: valid pixel hits: 668092
[14:40:27.514] <TB0> INFO: Event errors: 0
[14:40:27.514] <TB0> INFO: start marker: 0
[14:40:27.514] <TB0> INFO: stop marker: 0
[14:40:27.514] <TB0> INFO: overflow: 0
[14:40:27.514] <TB0> INFO: invalid 5bit words: 0
[14:40:27.514] <TB0> INFO: invalid XOR eye diagram: 0
[14:40:27.514] <TB0> INFO: frame (failed synchr.): 0
[14:40:27.514] <TB0> INFO: idle data (no TBM trl): 0
[14:40:27.514] <TB0> INFO: no data (only TBM hdr): 0
[14:40:27.514] <TB0> INFO: TBM errors: 0
[14:40:27.514] <TB0> INFO: flawed TBM headers: 0
[14:40:27.514] <TB0> INFO: flawed TBM trailers: 0
[14:40:27.514] <TB0> INFO: event ID mismatches: 0
[14:40:27.514] <TB0> INFO: ROC errors: 0
[14:40:27.514] <TB0> INFO: missing ROC header(s): 0
[14:40:27.514] <TB0> INFO: misplaced readback start: 0
[14:40:27.514] <TB0> INFO: Pixel decoding errors: 0
[14:40:27.514] <TB0> INFO: pixel data incomplete: 0
[14:40:27.514] <TB0> INFO: pixel address: 0
[14:40:27.514] <TB0> INFO: pulse height fill bit: 0
[14:40:27.514] <TB0> INFO: buffer corruption: 0
[14:40:27.514] <TB0> INFO: enter test to run
[14:40:27.514] <TB0> INFO: test: exit no parameter change
[14:40:27.675] <TB0> QUIET: Connection to board 73 closed.
[14:40:27.755] <TB0> INFO: pXar: this is the end, my friend