[14:42:34.637] <TB2> INFO: *** Welcome to pxar ***
[14:42:34.637] <TB2> INFO: *** Today: 2016/08/08
[14:42:34.783] <TB2> INFO: *** Version: 9751-dirty
[14:42:34.783] <TB2> INFO: readRocDacs: /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C0.dat .. /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C15.dat
[14:42:34.812] <TB2> INFO: readTbmDacs: /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//tbmParameters_C0b.dat
[14:42:34.815] <TB2> INFO: readMaskFile: /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//defaultMaskFile.dat
[14:42:34.816] <TB2> INFO: readTrimFile: /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters_C0.dat .. /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters_C15.dat
[14:42:34.927] <TB2> INFO: clk: 4
[14:42:34.927] <TB2> INFO: ctr: 4
[14:42:34.927] <TB2> INFO: sda: 19
[14:42:34.927] <TB2> INFO: tin: 9
[14:42:34.927] <TB2> INFO: level: 15
[14:42:34.927] <TB2> INFO: triggerdelay: 0
[14:42:34.927] <TB2> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[14:42:34.927] <TB2> INFO: Log level: INFO
[14:42:34.934] <TB2> INFO: Found DTB DTB_WWXUD2
[14:42:34.944] <TB2> QUIET: Connection to board DTB_WWXUD2 opened.
[14:42:34.946] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 149
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WWXUD2
MAC address: 40D855118095
Hostname: pixelDTB149
Comment:
------------------------------------------------------
[14:42:34.948] <TB2> INFO: RPC call hashes of host and DTB match: 484264910
[14:42:36.503] <TB2> INFO: DUT info:
[14:42:36.503] <TB2> INFO: The DUT currently contains the following objects:
[14:42:36.503] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[14:42:36.503] <TB2> INFO: TBM Core alpha (0): 7 registers set
[14:42:36.503] <TB2> INFO: TBM Core beta (1): 7 registers set
[14:42:36.503] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:42:36.503] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:36.503] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:36.503] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:36.503] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:36.503] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:36.503] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:36.503] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:36.503] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:36.503] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:36.503] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:36.503] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:36.503] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:36.503] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:36.503] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:36.503] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:36.503] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:36.905] <TB2> INFO: enter 'restricted' command line mode
[14:42:36.905] <TB2> INFO: enter test to run
[14:42:36.905] <TB2> INFO: test: pretest no parameter change
[14:42:36.905] <TB2> INFO: running: pretest
[14:42:36.909] <TB2> INFO: ----------------------------------------------------------------------
[14:42:36.909] <TB2> INFO: PixTestPretest::programROC()
[14:42:36.909] <TB2> INFO: ----------------------------------------------------------------------
[14:42:54.928] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[14:42:54.928] <TB2> INFO: IA differences per ROC: 18.5 18.5 20.9 17.7 19.3 20.1 20.9 19.3 19.3 18.5 19.3 19.3 21.7 19.3 20.1 18.5
[14:42:55.044] <TB2> INFO: enter test to run
[14:42:55.044] <TB2> INFO: test: pretest no parameter change
[14:42:55.044] <TB2> INFO: running: pretest
[14:42:55.046] <TB2> INFO: ----------------------------------------------------------------------
[14:42:55.046] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[14:42:55.046] <TB2> INFO: ----------------------------------------------------------------------
[14:43:01.960] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 389.9 mA = 24.3688 mA/ROC
[14:43:01.961] <TB2> INFO: i(loss) [mA/ROC]: 20.1 20.1 19.3 19.3 20.1 19.3 19.3 19.3 20.1 19.3 20.1 20.1 19.3 18.5 19.3 19.3
[14:43:02.007] <TB2> INFO: enter test to run
[14:43:02.007] <TB2> INFO: test: pretest no parameter change
[14:43:02.007] <TB2> INFO: running: pretest
[14:43:02.007] <TB2> INFO: ----------------------------------------------------------------------
[14:43:02.007] <TB2> INFO: PixTestPretest::findTiming()
[14:43:02.007] <TB2> INFO: ----------------------------------------------------------------------
[14:43:02.007] <TB2> INFO: PixTestCmd::init()
[14:43:02.941] <TB2> WARNING: Not unmasking DUT, not setting Calibrate bits!
[14:44:31.699] <TB2> INFO: TBM phases: 160MHz: 0, 400MHz: 4, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[14:44:31.700] <TB2> INFO: (success/tries = 100/100), width = 3
[14:44:31.700] <TB2> INFO: enter test to run
[14:44:31.700] <TB2> INFO: test: pretest no parameter change
[14:44:31.700] <TB2> INFO: running: pretest
[14:44:31.702] <TB2> INFO: ----------------------------------------------------------------------
[14:44:31.702] <TB2> INFO: PixTestPretest::findWorkingPixel()
[14:44:31.702] <TB2> INFO: ----------------------------------------------------------------------
[14:44:31.795] <TB2> INFO: Expecting 231680 events.
[14:44:37.571] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L497> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[14:44:37.656] <TB2> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[14:44:42.050] <TB2> INFO: 231680 events read in total (9698ms).
[14:44:42.056] <TB2> INFO: Test took 10352ms.
[14:44:42.287] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[14:44:42.370] <TB2> INFO: enter test to run
[14:44:42.370] <TB2> INFO: test: pretest no parameter change
[14:44:42.370] <TB2> INFO: running: pretest
[14:44:42.372] <TB2> INFO: ----------------------------------------------------------------------
[14:44:42.372] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[14:44:42.372] <TB2> INFO: ----------------------------------------------------------------------
[14:44:42.467] <TB2> INFO: Expecting 231680 events.
[14:44:52.793] <TB2> INFO: 231680 events read in total (9768ms).
[14:44:52.798] <TB2> INFO: Test took 10421ms.
[14:44:53.054] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[14:44:53.054] <TB2> INFO: CalDel: 128 139 144 142 143 144 111 126 175 143 147 142 154 159 159 144
[14:44:53.054] <TB2> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[14:44:53.089] <TB2> INFO: enter test to run
[14:44:53.089] <TB2> INFO: test: pretest no parameter change
[14:44:53.089] <TB2> INFO: running: pretest
[14:44:53.113] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C0.dat
[14:44:53.119] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C1.dat
[14:44:53.124] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C2.dat
[14:44:53.133] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C3.dat
[14:44:53.138] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C4.dat
[14:44:53.144] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C5.dat
[14:44:53.150] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C6.dat
[14:44:53.156] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C7.dat
[14:44:53.164] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C8.dat
[14:44:53.170] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C9.dat
[14:44:53.179] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C10.dat
[14:44:53.187] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C11.dat
[14:44:53.195] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C12.dat
[14:44:53.204] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C13.dat
[14:44:53.209] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C14.dat
[14:44:53.221] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters_C15.dat
[14:44:53.225] <TB2> INFO: enter test to run
[14:44:53.225] <TB2> INFO: test: fulltest no parameter change
[14:44:53.225] <TB2> INFO: running: fulltest
[14:44:53.225] <TB2> INFO: ######################################################################
[14:44:53.225] <TB2> INFO: PixTestFullTest::doTest()
[14:44:53.225] <TB2> INFO: ######################################################################
[14:44:53.226] <TB2> INFO: ######################################################################
[14:44:53.226] <TB2> INFO: PixTestAlive::doTest()
[14:44:53.226] <TB2> INFO: ######################################################################
[14:44:53.228] <TB2> INFO: ----------------------------------------------------------------------
[14:44:53.228] <TB2> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:44:53.228] <TB2> INFO: ----------------------------------------------------------------------
[14:44:53.502] <TB2> INFO: Expecting 41600 events.
[14:44:57.311] <TB2> INFO: 41600 events read in total (3252ms).
[14:44:57.312] <TB2> INFO: Test took 4082ms.
[14:44:57.558] <TB2> INFO: PixTestAlive::aliveTest() done
[14:44:57.558] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:44:57.560] <TB2> INFO: ----------------------------------------------------------------------
[14:44:57.560] <TB2> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:44:57.560] <TB2> INFO: ----------------------------------------------------------------------
[14:44:57.826] <TB2> INFO: Expecting 41600 events.
[14:45:00.814] <TB2> INFO: 41600 events read in total (2431ms).
[14:45:00.814] <TB2> INFO: Test took 3252ms.
[14:45:00.814] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[14:45:01.078] <TB2> INFO: PixTestAlive::maskTest() done
[14:45:01.078] <TB2> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:45:01.079] <TB2> INFO: ----------------------------------------------------------------------
[14:45:01.079] <TB2> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:45:01.079] <TB2> INFO: ----------------------------------------------------------------------
[14:45:01.350] <TB2> INFO: Expecting 41600 events.
[14:45:05.152] <TB2> INFO: 41600 events read in total (3245ms).
[14:45:05.153] <TB2> INFO: Test took 4071ms.
[14:45:05.399] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[14:45:05.399] <TB2> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:45:05.399] <TB2> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[14:45:05.399] <TB2> INFO: Decoding statistics:
[14:45:05.399] <TB2> INFO: General information:
[14:45:05.399] <TB2> INFO: 16bit words read: 0
[14:45:05.399] <TB2> INFO: valid events total: 0
[14:45:05.399] <TB2> INFO: empty events: 0
[14:45:05.399] <TB2> INFO: valid events with pixels: 0
[14:45:05.399] <TB2> INFO: valid pixel hits: 0
[14:45:05.399] <TB2> INFO: Event errors: 0
[14:45:05.399] <TB2> INFO: start marker: 0
[14:45:05.399] <TB2> INFO: stop marker: 0
[14:45:05.399] <TB2> INFO: overflow: 0
[14:45:05.399] <TB2> INFO: invalid 5bit words: 0
[14:45:05.399] <TB2> INFO: invalid XOR eye diagram: 0
[14:45:05.399] <TB2> INFO: frame (failed synchr.): 0
[14:45:05.399] <TB2> INFO: idle data (no TBM trl): 0
[14:45:05.399] <TB2> INFO: no data (only TBM hdr): 0
[14:45:05.399] <TB2> INFO: TBM errors: 0
[14:45:05.399] <TB2> INFO: flawed TBM headers: 0
[14:45:05.399] <TB2> INFO: flawed TBM trailers: 0
[14:45:05.399] <TB2> INFO: event ID mismatches: 0
[14:45:05.399] <TB2> INFO: ROC errors: 0
[14:45:05.399] <TB2> INFO: missing ROC header(s): 0
[14:45:05.399] <TB2> INFO: misplaced readback start: 0
[14:45:05.399] <TB2> INFO: Pixel decoding errors: 0
[14:45:05.399] <TB2> INFO: pixel data incomplete: 0
[14:45:05.399] <TB2> INFO: pixel address: 0
[14:45:05.399] <TB2> INFO: pulse height fill bit: 0
[14:45:05.399] <TB2> INFO: buffer corruption: 0
[14:45:05.406] <TB2> INFO: ######################################################################
[14:45:05.406] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[14:45:05.406] <TB2> INFO: ######################################################################
[14:45:05.410] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[14:45:05.421] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[14:45:05.421] <TB2> INFO: run 1 of 1
[14:45:05.684] <TB2> INFO: Expecting 3120000 events.
[14:45:47.332] <TB2> INFO: 872580 events read in total (41092ms).
[14:46:28.379] <TB2> INFO: 1729605 events read in total (82140ms).
[14:47:09.238] <TB2> INFO: 2596230 events read in total (122998ms).
[14:47:34.348] <TB2> INFO: 3120000 events read in total (148108ms).
[14:47:34.403] <TB2> INFO: Test took 148981ms.
[14:47:59.098] <TB2> INFO: PixTestBBMap::doTest() done, duration: 173 seconds
[14:47:59.098] <TB2> INFO: number of dead bumps (per ROC): 0 3 0 0 0 1 5 1 0 1 1 0 1 0 1 0
[14:47:59.098] <TB2> INFO: separation cut (per ROC): 91 89 98 87 88 87 103 93 77 89 88 91 93 68 84 90
[14:47:59.098] <TB2> INFO: Decoding statistics:
[14:47:59.098] <TB2> INFO: General information:
[14:47:59.098] <TB2> INFO: 16bit words read: 0
[14:47:59.098] <TB2> INFO: valid events total: 0
[14:47:59.098] <TB2> INFO: empty events: 0
[14:47:59.098] <TB2> INFO: valid events with pixels: 0
[14:47:59.098] <TB2> INFO: valid pixel hits: 0
[14:47:59.098] <TB2> INFO: Event errors: 0
[14:47:59.098] <TB2> INFO: start marker: 0
[14:47:59.098] <TB2> INFO: stop marker: 0
[14:47:59.098] <TB2> INFO: overflow: 0
[14:47:59.098] <TB2> INFO: invalid 5bit words: 0
[14:47:59.098] <TB2> INFO: invalid XOR eye diagram: 0
[14:47:59.098] <TB2> INFO: frame (failed synchr.): 0
[14:47:59.099] <TB2> INFO: idle data (no TBM trl): 0
[14:47:59.099] <TB2> INFO: no data (only TBM hdr): 0
[14:47:59.099] <TB2> INFO: TBM errors: 0
[14:47:59.099] <TB2> INFO: flawed TBM headers: 0
[14:47:59.099] <TB2> INFO: flawed TBM trailers: 0
[14:47:59.099] <TB2> INFO: event ID mismatches: 0
[14:47:59.099] <TB2> INFO: ROC errors: 0
[14:47:59.099] <TB2> INFO: missing ROC header(s): 0
[14:47:59.099] <TB2> INFO: misplaced readback start: 0
[14:47:59.099] <TB2> INFO: Pixel decoding errors: 0
[14:47:59.099] <TB2> INFO: pixel data incomplete: 0
[14:47:59.099] <TB2> INFO: pixel address: 0
[14:47:59.099] <TB2> INFO: pulse height fill bit: 0
[14:47:59.099] <TB2> INFO: buffer corruption: 0
[14:47:59.189] <TB2> INFO: ######################################################################
[14:47:59.189] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[14:47:59.189] <TB2> INFO: ######################################################################
[14:47:59.189] <TB2> INFO: ----------------------------------------------------------------------
[14:47:59.189] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[14:47:59.189] <TB2> INFO: ----------------------------------------------------------------------
[14:47:59.189] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[14:47:59.198] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[14:47:59.198] <TB2> INFO: run 1 of 1
[14:47:59.488] <TB2> INFO: Expecting 26208000 events.
[14:48:33.363] <TB2> INFO: 938350 events read in total (33317ms).
[14:49:06.471] <TB2> INFO: 1862200 events read in total (66425ms).
[14:49:39.658] <TB2> INFO: 2784650 events read in total (99612ms).
[14:50:12.788] <TB2> INFO: 3707950 events read in total (132742ms).
[14:50:46.097] <TB2> INFO: 4630000 events read in total (166051ms).
[14:51:19.255] <TB2> INFO: 5549850 events read in total (199209ms).
[14:51:52.778] <TB2> INFO: 6471700 events read in total (232732ms).
[14:52:25.818] <TB2> INFO: 7390400 events read in total (265772ms).
[14:52:59.111] <TB2> INFO: 8309500 events read in total (299065ms).
[14:53:32.380] <TB2> INFO: 9229450 events read in total (332334ms).
[14:54:05.656] <TB2> INFO: 10147650 events read in total (365610ms).
[14:54:38.825] <TB2> INFO: 11065000 events read in total (398779ms).
[14:55:12.354] <TB2> INFO: 11982350 events read in total (432308ms).
[14:55:45.498] <TB2> INFO: 12896650 events read in total (465452ms).
[14:56:19.720] <TB2> INFO: 13803200 events read in total (499674ms).
[14:56:53.833] <TB2> INFO: 14707700 events read in total (533787ms).
[14:57:27.487] <TB2> INFO: 15609800 events read in total (567441ms).
[14:58:00.585] <TB2> INFO: 16507650 events read in total (600539ms).
[14:58:33.762] <TB2> INFO: 17406900 events read in total (633716ms).
[14:59:06.970] <TB2> INFO: 18307550 events read in total (666924ms).
[14:59:39.790] <TB2> INFO: 19206350 events read in total (699744ms).
[15:00:12.787] <TB2> INFO: 20102900 events read in total (732741ms).
[15:00:45.767] <TB2> INFO: 20999900 events read in total (765721ms).
[15:01:19.040] <TB2> INFO: 21898000 events read in total (798994ms).
[15:01:52.146] <TB2> INFO: 22794850 events read in total (832100ms).
[15:02:25.084] <TB2> INFO: 23692050 events read in total (865038ms).
[15:02:58.407] <TB2> INFO: 24590250 events read in total (898361ms).
[15:03:31.828] <TB2> INFO: 25488900 events read in total (931782ms).
[15:03:57.959] <TB2> INFO: 26208000 events read in total (957913ms).
[15:03:58.007] <TB2> INFO: Test took 958809ms.
[15:03:58.364] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:04:00.087] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:04:01.759] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:04:03.232] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:04:04.840] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:04:06.527] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:04:08.554] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:04:10.280] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:04:11.955] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:04:13.496] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:04:14.987] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:04:16.551] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:04:18.065] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:04:19.534] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:04:21.074] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:04:22.557] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[15:04:24.176] <TB2> INFO: PixTestScurves::scurves() done
[15:04:24.176] <TB2> INFO: Vcal mean: 101.88 103.48 105.55 90.96 95.80 94.46 109.63 104.45 89.71 93.99 94.63 97.01 98.47 82.80 104.15 106.98
[15:04:24.176] <TB2> INFO: Vcal RMS: 5.58 5.32 5.12 5.43 4.93 4.62 4.50 4.72 5.17 5.06 4.56 5.15 5.54 4.97 5.41 4.97
[15:04:24.176] <TB2> INFO: PixTestScurves::fullTest() done, duration: 984 seconds
[15:04:24.176] <TB2> INFO: Decoding statistics:
[15:04:24.176] <TB2> INFO: General information:
[15:04:24.176] <TB2> INFO: 16bit words read: 0
[15:04:24.176] <TB2> INFO: valid events total: 0
[15:04:24.176] <TB2> INFO: empty events: 0
[15:04:24.176] <TB2> INFO: valid events with pixels: 0
[15:04:24.176] <TB2> INFO: valid pixel hits: 0
[15:04:24.176] <TB2> INFO: Event errors: 0
[15:04:24.176] <TB2> INFO: start marker: 0
[15:04:24.176] <TB2> INFO: stop marker: 0
[15:04:24.176] <TB2> INFO: overflow: 0
[15:04:24.176] <TB2> INFO: invalid 5bit words: 0
[15:04:24.176] <TB2> INFO: invalid XOR eye diagram: 0
[15:04:24.176] <TB2> INFO: frame (failed synchr.): 0
[15:04:24.176] <TB2> INFO: idle data (no TBM trl): 0
[15:04:24.176] <TB2> INFO: no data (only TBM hdr): 0
[15:04:24.176] <TB2> INFO: TBM errors: 0
[15:04:24.176] <TB2> INFO: flawed TBM headers: 0
[15:04:24.176] <TB2> INFO: flawed TBM trailers: 0
[15:04:24.177] <TB2> INFO: event ID mismatches: 0
[15:04:24.177] <TB2> INFO: ROC errors: 0
[15:04:24.177] <TB2> INFO: missing ROC header(s): 0
[15:04:24.177] <TB2> INFO: misplaced readback start: 0
[15:04:24.177] <TB2> INFO: Pixel decoding errors: 0
[15:04:24.177] <TB2> INFO: pixel data incomplete: 0
[15:04:24.177] <TB2> INFO: pixel address: 0
[15:04:24.177] <TB2> INFO: pulse height fill bit: 0
[15:04:24.177] <TB2> INFO: buffer corruption: 0
[15:04:24.255] <TB2> INFO: ######################################################################
[15:04:24.255] <TB2> INFO: PixTestTrim::doTest()
[15:04:24.255] <TB2> INFO: ######################################################################
[15:04:24.257] <TB2> INFO: ----------------------------------------------------------------------
[15:04:24.257] <TB2> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[15:04:24.257] <TB2> INFO: ----------------------------------------------------------------------
[15:04:24.340] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[15:04:24.340] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:04:24.349] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:04:24.349] <TB2> INFO: run 1 of 1
[15:04:24.616] <TB2> INFO: Expecting 6281600 events.
[15:05:05.012] <TB2> INFO: 1093590 events read in total (39834ms).
[15:05:44.505] <TB2> INFO: 2179750 events read in total (79327ms).
[15:06:23.894] <TB2> INFO: 3259260 events read in total (118717ms).
[15:07:03.398] <TB2> INFO: 4342800 events read in total (158220ms).
[15:07:42.838] <TB2> INFO: 5429830 events read in total (197660ms).
[15:08:14.795] <TB2> INFO: 6281600 events read in total (229617ms).
[15:08:14.834] <TB2> INFO: Test took 230484ms.
[15:08:34.832] <TB2> INFO: ROC 0 VthrComp = 100
[15:08:34.832] <TB2> INFO: ROC 1 VthrComp = 102
[15:08:34.832] <TB2> INFO: ROC 2 VthrComp = 105
[15:08:34.833] <TB2> INFO: ROC 3 VthrComp = 92
[15:08:34.833] <TB2> INFO: ROC 4 VthrComp = 97
[15:08:34.833] <TB2> INFO: ROC 5 VthrComp = 97
[15:08:34.833] <TB2> INFO: ROC 6 VthrComp = 111
[15:08:34.833] <TB2> INFO: ROC 7 VthrComp = 103
[15:08:34.833] <TB2> INFO: ROC 8 VthrComp = 88
[15:08:34.833] <TB2> INFO: ROC 9 VthrComp = 94
[15:08:34.833] <TB2> INFO: ROC 10 VthrComp = 97
[15:08:34.834] <TB2> INFO: ROC 11 VthrComp = 98
[15:08:34.834] <TB2> INFO: ROC 12 VthrComp = 99
[15:08:34.834] <TB2> INFO: ROC 13 VthrComp = 83
[15:08:34.834] <TB2> INFO: ROC 14 VthrComp = 97
[15:08:34.834] <TB2> INFO: ROC 15 VthrComp = 101
[15:08:34.834] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[15:08:34.834] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[15:08:34.845] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:08:34.845] <TB2> INFO: run 1 of 1
[15:08:35.132] <TB2> INFO: Expecting 6281600 events.
[15:09:11.043] <TB2> INFO: 755670 events read in total (35354ms).
[15:09:46.585] <TB2> INFO: 1509750 events read in total (70896ms).
[15:10:21.711] <TB2> INFO: 2264670 events read in total (106022ms).
[15:10:57.188] <TB2> INFO: 3020430 events read in total (141499ms).
[15:11:32.333] <TB2> INFO: 3770440 events read in total (176644ms).
[15:12:07.412] <TB2> INFO: 4516740 events read in total (211723ms).
[15:12:42.713] <TB2> INFO: 5260890 events read in total (247024ms).
[15:13:17.835] <TB2> INFO: 6004030 events read in total (282146ms).
[15:13:32.322] <TB2> INFO: 6281600 events read in total (296633ms).
[15:13:32.369] <TB2> INFO: Test took 297523ms.
[15:14:00.303] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 58.5757 for pixel 11/73 mean/min/max = 45.2472/31.8475/58.6469
[15:14:00.304] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 58.3366 for pixel 17/7 mean/min/max = 44.8152/31.2931/58.3374
[15:14:00.304] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 60.2069 for pixel 0/3 mean/min/max = 47.328/34.4196/60.2364
[15:14:00.304] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 58.1839 for pixel 0/41 mean/min/max = 45.775/33.1084/58.4416
[15:14:00.304] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 56.3694 for pixel 16/67 mean/min/max = 44.5653/32.6379/56.4928
[15:14:00.304] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 55.699 for pixel 19/11 mean/min/max = 44.0464/32.2342/55.8585
[15:14:00.305] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 58.404 for pixel 2/7 mean/min/max = 46.3395/34.2546/58.4243
[15:14:00.305] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 57.3072 for pixel 9/0 mean/min/max = 45.1408/32.7162/57.5655
[15:14:00.305] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 61.7287 for pixel 24/33 mean/min/max = 48.1283/34.4508/61.8059
[15:14:00.305] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 58.3215 for pixel 23/10 mean/min/max = 45.5305/32.7357/58.3254
[15:14:00.306] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 56.7901 for pixel 7/79 mean/min/max = 44.2902/31.7617/56.8186
[15:14:00.306] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 57.9007 for pixel 17/0 mean/min/max = 44.9898/32.0217/57.9579
[15:14:00.306] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 59.4249 for pixel 15/41 mean/min/max = 45.5186/31.5293/59.508
[15:14:00.306] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 59.5557 for pixel 0/6 mean/min/max = 46.0541/32.463/59.6452
[15:14:00.307] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 62.8553 for pixel 0/79 mean/min/max = 47.8914/32.911/62.8717
[15:14:00.307] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 59.3379 for pixel 18/2 mean/min/max = 46.0865/32.4441/59.729
[15:14:00.307] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:14:00.401] <TB2> INFO: Expecting 514560 events.
[15:14:13.489] <TB2> INFO: 514560 events read in total (12527ms).
[15:14:13.496] <TB2> INFO: Expecting 514560 events.
[15:14:26.315] <TB2> INFO: 514560 events read in total (12414ms).
[15:14:26.322] <TB2> INFO: Expecting 514560 events.
[15:14:39.309] <TB2> INFO: 514560 events read in total (12564ms).
[15:14:39.322] <TB2> INFO: Expecting 514560 events.
[15:14:52.102] <TB2> INFO: 514560 events read in total (12376ms).
[15:14:52.115] <TB2> INFO: Expecting 514560 events.
[15:15:04.906] <TB2> INFO: 514560 events read in total (12376ms).
[15:15:04.922] <TB2> INFO: Expecting 514560 events.
[15:15:17.751] <TB2> INFO: 514560 events read in total (12420ms).
[15:15:17.768] <TB2> INFO: Expecting 514560 events.
[15:15:30.629] <TB2> INFO: 514560 events read in total (12452ms).
[15:15:30.647] <TB2> INFO: Expecting 514560 events.
[15:15:43.448] <TB2> INFO: 514560 events read in total (12398ms).
[15:15:43.472] <TB2> INFO: Expecting 514560 events.
[15:15:56.353] <TB2> INFO: 514560 events read in total (12478ms).
[15:15:56.375] <TB2> INFO: Expecting 514560 events.
[15:16:09.270] <TB2> INFO: 514560 events read in total (12491ms).
[15:16:09.294] <TB2> INFO: Expecting 514560 events.
[15:16:22.014] <TB2> INFO: 514560 events read in total (12318ms).
[15:16:22.042] <TB2> INFO: Expecting 514560 events.
[15:16:35.026] <TB2> INFO: 514560 events read in total (12580ms).
[15:16:35.055] <TB2> INFO: Expecting 514560 events.
[15:16:47.898] <TB2> INFO: 514560 events read in total (12440ms).
[15:16:47.928] <TB2> INFO: Expecting 514560 events.
[15:17:00.666] <TB2> INFO: 514560 events read in total (12336ms).
[15:17:00.697] <TB2> INFO: Expecting 514560 events.
[15:17:13.397] <TB2> INFO: 514560 events read in total (12298ms).
[15:17:13.429] <TB2> INFO: Expecting 514560 events.
[15:17:26.191] <TB2> INFO: 514560 events read in total (12360ms).
[15:17:26.227] <TB2> INFO: Test took 205920ms.
[15:17:27.266] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[15:17:27.276] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:17:27.276] <TB2> INFO: run 1 of 1
[15:17:27.562] <TB2> INFO: Expecting 6281600 events.
[15:18:02.535] <TB2> INFO: 741450 events read in total (34416ms).
[15:18:37.335] <TB2> INFO: 1482760 events read in total (69216ms).
[15:19:11.984] <TB2> INFO: 2224770 events read in total (103865ms).
[15:19:46.593] <TB2> INFO: 2967500 events read in total (138474ms).
[15:20:21.439] <TB2> INFO: 3704520 events read in total (173320ms).
[15:20:55.984] <TB2> INFO: 4436590 events read in total (207865ms).
[15:21:30.753] <TB2> INFO: 5166380 events read in total (242634ms).
[15:22:05.820] <TB2> INFO: 5895460 events read in total (277701ms).
[15:22:25.235] <TB2> INFO: 6281600 events read in total (297116ms).
[15:22:25.281] <TB2> INFO: Test took 298005ms.
[15:22:50.377] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 0.203281 .. 54.974027
[15:22:50.457] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 64 (-1/-1) hits flags = 528 (plus default)
[15:22:50.465] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:22:50.465] <TB2> INFO: run 1 of 1
[15:22:50.727] <TB2> INFO: Expecting 2704000 events.
[15:23:29.195] <TB2> INFO: 909750 events read in total (37906ms).
[15:24:06.726] <TB2> INFO: 1817320 events read in total (75437ms).
[15:24:44.015] <TB2> INFO: 2704000 events read in total (112726ms).
[15:24:44.036] <TB2> INFO: Test took 113571ms.
[15:24:59.469] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 15.206116 .. 54.974027
[15:24:59.548] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 5 .. 64 (-1/-1) hits flags = 528 (plus default)
[15:24:59.556] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:24:59.556] <TB2> INFO: run 1 of 1
[15:24:59.821] <TB2> INFO: Expecting 2496000 events.
[15:25:38.852] <TB2> INFO: 883270 events read in total (38474ms).
[15:26:17.210] <TB2> INFO: 1765730 events read in total (76833ms).
[15:26:48.675] <TB2> INFO: 2496000 events read in total (108298ms).
[15:26:48.695] <TB2> INFO: Test took 109139ms.
[15:27:04.341] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 20.023635 .. 54.974027
[15:27:04.422] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 10 .. 64 (-1/-1) hits flags = 528 (plus default)
[15:27:04.432] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:27:04.432] <TB2> INFO: run 1 of 1
[15:27:04.701] <TB2> INFO: Expecting 2288000 events.
[15:27:42.398] <TB2> INFO: 858430 events read in total (37140ms).
[15:28:19.480] <TB2> INFO: 1716980 events read in total (74222ms).
[15:28:44.247] <TB2> INFO: 2288000 events read in total (98989ms).
[15:28:44.264] <TB2> INFO: Test took 99832ms.
[15:28:59.626] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 23.497677 .. 54.974027
[15:28:59.701] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 13 .. 64 (-1/-1) hits flags = 528 (plus default)
[15:28:59.710] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:28:59.711] <TB2> INFO: run 1 of 1
[15:28:59.990] <TB2> INFO: Expecting 2163200 events.
[15:29:37.451] <TB2> INFO: 841710 events read in total (36903ms).
[15:30:14.363] <TB2> INFO: 1683120 events read in total (73815ms).
[15:30:35.564] <TB2> INFO: 2163200 events read in total (95016ms).
[15:30:35.607] <TB2> INFO: Test took 95896ms.
[15:30:50.478] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[15:30:50.479] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[15:30:50.488] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[15:30:50.488] <TB2> INFO: run 1 of 1
[15:30:50.755] <TB2> INFO: Expecting 1705600 events.
[15:31:29.812] <TB2> INFO: 877610 events read in total (38500ms).
[15:32:05.278] <TB2> INFO: 1705600 events read in total (73966ms).
[15:32:05.302] <TB2> INFO: Test took 74815ms.
[15:32:19.680] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C0.dat
[15:32:19.684] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C1.dat
[15:32:19.688] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C2.dat
[15:32:19.692] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C3.dat
[15:32:19.696] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C4.dat
[15:32:19.699] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C5.dat
[15:32:19.703] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C6.dat
[15:32:19.707] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C7.dat
[15:32:19.711] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C8.dat
[15:32:19.714] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C9.dat
[15:32:19.718] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C10.dat
[15:32:19.722] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C11.dat
[15:32:19.726] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C12.dat
[15:32:19.730] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C13.dat
[15:32:19.733] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C14.dat
[15:32:19.737] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C15.dat
[15:32:19.741] <TB2> INFO: write trim parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C0.dat
[15:32:19.753] <TB2> INFO: write trim parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C1.dat
[15:32:19.768] <TB2> INFO: write trim parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C2.dat
[15:32:19.779] <TB2> INFO: write trim parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C3.dat
[15:32:19.789] <TB2> INFO: write trim parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C4.dat
[15:32:19.799] <TB2> INFO: write trim parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C5.dat
[15:32:19.811] <TB2> INFO: write trim parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C6.dat
[15:32:19.822] <TB2> INFO: write trim parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C7.dat
[15:32:19.837] <TB2> INFO: write trim parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C8.dat
[15:32:19.852] <TB2> INFO: write trim parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C9.dat
[15:32:19.867] <TB2> INFO: write trim parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C10.dat
[15:32:19.878] <TB2> INFO: write trim parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C11.dat
[15:32:19.890] <TB2> INFO: write trim parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C12.dat
[15:32:19.906] <TB2> INFO: write trim parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C13.dat
[15:32:19.916] <TB2> INFO: write trim parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C14.dat
[15:32:19.928] <TB2> INFO: write trim parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//trimParameters35_C15.dat
[15:32:19.940] <TB2> INFO: PixTestTrim::trimTest() done
[15:32:19.940] <TB2> INFO: vtrim: 103 99 115 92 96 96 111 98 108 100 91 113 108 94 101 99
[15:32:19.940] <TB2> INFO: vthrcomp: 100 102 105 92 97 97 111 103 88 94 97 98 99 83 97 101
[15:32:19.940] <TB2> INFO: vcal mean: 34.86 34.94 35.01 34.99 35.02 34.94 34.99 34.98 34.97 34.94 34.95 34.95 34.94 34.98 35.01 34.98
[15:32:19.940] <TB2> INFO: vcal RMS: 0.90 0.86 0.83 0.84 0.95 0.85 0.84 0.87 0.93 0.90 0.83 0.85 0.89 0.85 0.96 0.96
[15:32:19.940] <TB2> INFO: bits mean: 9.82 9.97 8.70 9.11 9.78 10.14 8.98 9.50 8.86 9.71 9.83 9.86 9.59 9.15 8.20 9.76
[15:32:19.940] <TB2> INFO: bits RMS: 2.55 2.58 2.59 2.76 2.54 2.50 2.51 2.66 2.51 2.57 2.63 2.59 2.71 2.77 2.95 2.52
[15:32:19.950] <TB2> INFO: ----------------------------------------------------------------------
[15:32:19.950] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[15:32:19.950] <TB2> INFO: ----------------------------------------------------------------------
[15:32:19.954] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[15:32:19.962] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[15:32:19.962] <TB2> INFO: run 1 of 1
[15:32:20.227] <TB2> INFO: Expecting 4160000 events.
[15:33:03.323] <TB2> INFO: 962405 events read in total (42539ms).
[15:33:45.484] <TB2> INFO: 1917810 events read in total (84700ms).
[15:34:27.745] <TB2> INFO: 2861550 events read in total (126962ms).
[15:35:09.572] <TB2> INFO: 3801995 events read in total (168788ms).
[15:35:25.950] <TB2> INFO: 4160000 events read in total (185166ms).
[15:35:25.990] <TB2> INFO: Test took 186028ms.
[15:35:55.825] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 171 (-1/-1) hits flags = 528 (plus default)
[15:35:55.834] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[15:35:55.834] <TB2> INFO: run 1 of 1
[15:35:56.100] <TB2> INFO: Expecting 3577600 events.
[15:36:39.622] <TB2> INFO: 990600 events read in total (42965ms).
[15:37:22.441] <TB2> INFO: 1971320 events read in total (85785ms).
[15:38:05.540] <TB2> INFO: 2940195 events read in total (128883ms).
[15:38:33.531] <TB2> INFO: 3577600 events read in total (156874ms).
[15:38:33.568] <TB2> INFO: Test took 157734ms.
[15:39:00.714] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 159 (-1/-1) hits flags = 528 (plus default)
[15:39:00.722] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[15:39:00.722] <TB2> INFO: run 1 of 1
[15:39:00.990] <TB2> INFO: Expecting 3328000 events.
[15:39:45.465] <TB2> INFO: 1027970 events read in total (43918ms).
[15:40:28.782] <TB2> INFO: 2042295 events read in total (87235ms).
[15:41:11.772] <TB2> INFO: 3044815 events read in total (130225ms).
[15:41:24.458] <TB2> INFO: 3328000 events read in total (142911ms).
[15:41:24.499] <TB2> INFO: Test took 143776ms.
[15:41:50.830] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 161 (-1/-1) hits flags = 528 (plus default)
[15:41:50.839] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[15:41:50.839] <TB2> INFO: run 1 of 1
[15:41:51.107] <TB2> INFO: Expecting 3369600 events.
[15:42:35.372] <TB2> INFO: 1020620 events read in total (43708ms).
[15:43:18.889] <TB2> INFO: 2028515 events read in total (87225ms).
[15:44:02.075] <TB2> INFO: 3024405 events read in total (130412ms).
[15:44:17.002] <TB2> INFO: 3369600 events read in total (145338ms).
[15:44:17.036] <TB2> INFO: Test took 146198ms.
[15:44:42.692] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 159 (-1/-1) hits flags = 528 (plus default)
[15:44:42.701] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[15:44:42.701] <TB2> INFO: run 1 of 1
[15:44:42.970] <TB2> INFO: Expecting 3328000 events.
[15:45:27.324] <TB2> INFO: 1026845 events read in total (43797ms).
[15:46:10.902] <TB2> INFO: 2039700 events read in total (87375ms).
[15:46:54.239] <TB2> INFO: 3041410 events read in total (130712ms).
[15:47:06.670] <TB2> INFO: 3328000 events read in total (143143ms).
[15:47:06.703] <TB2> INFO: Test took 144002ms.
[15:47:33.139] <TB2> INFO: PixTestTrim::trimBitTest() done
[15:47:33.141] <TB2> INFO: PixTestTrim::doTest() done, duration: 2588 seconds
[15:47:33.141] <TB2> INFO: Decoding statistics:
[15:47:33.141] <TB2> INFO: General information:
[15:47:33.141] <TB2> INFO: 16bit words read: 0
[15:47:33.141] <TB2> INFO: valid events total: 0
[15:47:33.141] <TB2> INFO: empty events: 0
[15:47:33.141] <TB2> INFO: valid events with pixels: 0
[15:47:33.141] <TB2> INFO: valid pixel hits: 0
[15:47:33.141] <TB2> INFO: Event errors: 0
[15:47:33.141] <TB2> INFO: start marker: 0
[15:47:33.141] <TB2> INFO: stop marker: 0
[15:47:33.141] <TB2> INFO: overflow: 0
[15:47:33.141] <TB2> INFO: invalid 5bit words: 0
[15:47:33.141] <TB2> INFO: invalid XOR eye diagram: 0
[15:47:33.141] <TB2> INFO: frame (failed synchr.): 0
[15:47:33.141] <TB2> INFO: idle data (no TBM trl): 0
[15:47:33.141] <TB2> INFO: no data (only TBM hdr): 0
[15:47:33.141] <TB2> INFO: TBM errors: 0
[15:47:33.141] <TB2> INFO: flawed TBM headers: 0
[15:47:33.141] <TB2> INFO: flawed TBM trailers: 0
[15:47:33.141] <TB2> INFO: event ID mismatches: 0
[15:47:33.141] <TB2> INFO: ROC errors: 0
[15:47:33.141] <TB2> INFO: missing ROC header(s): 0
[15:47:33.141] <TB2> INFO: misplaced readback start: 0
[15:47:33.141] <TB2> INFO: Pixel decoding errors: 0
[15:47:33.141] <TB2> INFO: pixel data incomplete: 0
[15:47:33.141] <TB2> INFO: pixel address: 0
[15:47:33.141] <TB2> INFO: pulse height fill bit: 0
[15:47:33.141] <TB2> INFO: buffer corruption: 0
[15:47:33.931] <TB2> INFO: ######################################################################
[15:47:33.931] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[15:47:33.931] <TB2> INFO: ######################################################################
[15:47:34.227] <TB2> INFO: Expecting 41600 events.
[15:47:38.001] <TB2> INFO: 41600 events read in total (3217ms).
[15:47:38.001] <TB2> INFO: Test took 4069ms.
[15:47:38.480] <TB2> INFO: Expecting 41600 events.
[15:47:42.342] <TB2> INFO: 41600 events read in total (3306ms).
[15:47:42.343] <TB2> INFO: Test took 4135ms.
[15:47:42.634] <TB2> INFO: Expecting 41600 events.
[15:47:46.467] <TB2> INFO: 41600 events read in total (3276ms).
[15:47:46.467] <TB2> INFO: Test took 4099ms.
[15:47:46.724] <TB2> INFO: Expecting 2560 events.
[15:47:47.579] <TB2> INFO: 2560 events read in total (298ms).
[15:47:47.579] <TB2> INFO: Test took 1098ms.
[15:47:47.886] <TB2> INFO: Expecting 2560 events.
[15:47:48.742] <TB2> INFO: 2560 events read in total (299ms).
[15:47:48.743] <TB2> INFO: Test took 1163ms.
[15:47:49.049] <TB2> INFO: Expecting 2560 events.
[15:47:49.903] <TB2> INFO: 2560 events read in total (297ms).
[15:47:49.903] <TB2> INFO: Test took 1160ms.
[15:47:50.210] <TB2> INFO: Expecting 2560 events.
[15:47:51.064] <TB2> INFO: 2560 events read in total (298ms).
[15:47:51.064] <TB2> INFO: Test took 1160ms.
[15:47:51.371] <TB2> INFO: Expecting 2560 events.
[15:47:52.225] <TB2> INFO: 2560 events read in total (298ms).
[15:47:52.225] <TB2> INFO: Test took 1160ms.
[15:47:52.532] <TB2> INFO: Expecting 2560 events.
[15:47:53.387] <TB2> INFO: 2560 events read in total (298ms).
[15:47:53.388] <TB2> INFO: Test took 1162ms.
[15:47:53.694] <TB2> INFO: Expecting 2560 events.
[15:47:54.551] <TB2> INFO: 2560 events read in total (300ms).
[15:47:54.552] <TB2> INFO: Test took 1164ms.
[15:47:54.857] <TB2> INFO: Expecting 2560 events.
[15:47:55.714] <TB2> INFO: 2560 events read in total (300ms).
[15:47:55.714] <TB2> INFO: Test took 1161ms.
[15:47:56.020] <TB2> INFO: Expecting 2560 events.
[15:47:56.875] <TB2> INFO: 2560 events read in total (298ms).
[15:47:56.875] <TB2> INFO: Test took 1161ms.
[15:47:57.181] <TB2> INFO: Expecting 2560 events.
[15:47:58.037] <TB2> INFO: 2560 events read in total (299ms).
[15:47:58.038] <TB2> INFO: Test took 1163ms.
[15:47:58.344] <TB2> INFO: Expecting 2560 events.
[15:47:59.198] <TB2> INFO: 2560 events read in total (297ms).
[15:47:59.199] <TB2> INFO: Test took 1161ms.
[15:47:59.505] <TB2> INFO: Expecting 2560 events.
[15:48:00.359] <TB2> INFO: 2560 events read in total (297ms).
[15:48:00.360] <TB2> INFO: Test took 1161ms.
[15:48:00.665] <TB2> INFO: Expecting 2560 events.
[15:48:01.523] <TB2> INFO: 2560 events read in total (301ms).
[15:48:01.523] <TB2> INFO: Test took 1163ms.
[15:48:01.829] <TB2> INFO: Expecting 2560 events.
[15:48:02.686] <TB2> INFO: 2560 events read in total (300ms).
[15:48:02.687] <TB2> INFO: Test took 1164ms.
[15:48:02.993] <TB2> INFO: Expecting 2560 events.
[15:48:03.850] <TB2> INFO: 2560 events read in total (299ms).
[15:48:03.850] <TB2> INFO: Test took 1163ms.
[15:48:04.157] <TB2> INFO: Expecting 2560 events.
[15:48:05.013] <TB2> INFO: 2560 events read in total (299ms).
[15:48:05.013] <TB2> INFO: Test took 1162ms.
[15:48:05.018] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:48:05.321] <TB2> INFO: Expecting 655360 events.
[15:48:22.137] <TB2> INFO: 655360 events read in total (16260ms).
[15:48:22.146] <TB2> INFO: Expecting 655360 events.
[15:48:38.852] <TB2> INFO: 655360 events read in total (16304ms).
[15:48:38.864] <TB2> INFO: Expecting 655360 events.
[15:48:55.571] <TB2> INFO: 655360 events read in total (16305ms).
[15:48:55.590] <TB2> INFO: Expecting 655360 events.
[15:49:12.246] <TB2> INFO: 655360 events read in total (16254ms).
[15:49:12.266] <TB2> INFO: Expecting 655360 events.
[15:49:29.021] <TB2> INFO: 655360 events read in total (16352ms).
[15:49:29.060] <TB2> INFO: Expecting 655360 events.
[15:49:45.767] <TB2> INFO: 655360 events read in total (16304ms).
[15:49:45.793] <TB2> INFO: Expecting 655360 events.
[15:50:02.532] <TB2> INFO: 655360 events read in total (16337ms).
[15:50:02.564] <TB2> INFO: Expecting 655360 events.
[15:50:19.347] <TB2> INFO: 655360 events read in total (16381ms).
[15:50:19.380] <TB2> INFO: Expecting 655360 events.
[15:50:36.084] <TB2> INFO: 655360 events read in total (16301ms).
[15:50:36.125] <TB2> INFO: Expecting 655360 events.
[15:50:52.808] <TB2> INFO: 655360 events read in total (16280ms).
[15:50:52.852] <TB2> INFO: Expecting 655360 events.
[15:51:09.729] <TB2> INFO: 655360 events read in total (16474ms).
[15:51:09.800] <TB2> INFO: Expecting 655360 events.
[15:51:26.624] <TB2> INFO: 655360 events read in total (16422ms).
[15:51:26.676] <TB2> INFO: Expecting 655360 events.
[15:51:43.424] <TB2> INFO: 655360 events read in total (16346ms).
[15:51:43.488] <TB2> INFO: Expecting 655360 events.
[15:52:00.158] <TB2> INFO: 655360 events read in total (16268ms).
[15:52:00.213] <TB2> INFO: Expecting 655360 events.
[15:52:16.952] <TB2> INFO: 655360 events read in total (16337ms).
[15:52:17.016] <TB2> INFO: Expecting 655360 events.
[15:52:33.815] <TB2> INFO: 655360 events read in total (16397ms).
[15:52:33.911] <TB2> INFO: Test took 268893ms.
[15:52:34.018] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:52:34.244] <TB2> INFO: Expecting 655360 events.
[15:52:51.015] <TB2> INFO: 655360 events read in total (16214ms).
[15:52:51.028] <TB2> INFO: Expecting 655360 events.
[15:53:07.705] <TB2> INFO: 655360 events read in total (16274ms).
[15:53:07.718] <TB2> INFO: Expecting 655360 events.
[15:53:24.540] <TB2> INFO: 655360 events read in total (16420ms).
[15:53:24.563] <TB2> INFO: Expecting 655360 events.
[15:53:41.294] <TB2> INFO: 655360 events read in total (16329ms).
[15:53:41.313] <TB2> INFO: Expecting 655360 events.
[15:53:57.868] <TB2> INFO: 655360 events read in total (16153ms).
[15:53:57.890] <TB2> INFO: Expecting 655360 events.
[15:54:14.630] <TB2> INFO: 655360 events read in total (16337ms).
[15:54:14.661] <TB2> INFO: Expecting 655360 events.
[15:54:31.549] <TB2> INFO: 655360 events read in total (16485ms).
[15:54:31.581] <TB2> INFO: Expecting 655360 events.
[15:54:48.349] <TB2> INFO: 655360 events read in total (16366ms).
[15:54:48.384] <TB2> INFO: Expecting 655360 events.
[15:55:05.072] <TB2> INFO: 655360 events read in total (16285ms).
[15:55:05.109] <TB2> INFO: Expecting 655360 events.
[15:55:21.883] <TB2> INFO: 655360 events read in total (16372ms).
[15:55:21.928] <TB2> INFO: Expecting 655360 events.
[15:55:38.588] <TB2> INFO: 655360 events read in total (16258ms).
[15:55:38.640] <TB2> INFO: Expecting 655360 events.
[15:55:55.403] <TB2> INFO: 655360 events read in total (16360ms).
[15:55:55.457] <TB2> INFO: Expecting 655360 events.
[15:56:12.250] <TB2> INFO: 655360 events read in total (16391ms).
[15:56:12.308] <TB2> INFO: Expecting 655360 events.
[15:56:28.912] <TB2> INFO: 655360 events read in total (16202ms).
[15:56:28.977] <TB2> INFO: Expecting 655360 events.
[15:56:45.713] <TB2> INFO: 655360 events read in total (16334ms).
[15:56:45.780] <TB2> INFO: Expecting 655360 events.
[15:57:02.522] <TB2> INFO: 655360 events read in total (16341ms).
[15:57:02.591] <TB2> INFO: Test took 268573ms.
[15:57:02.794] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:57:02.801] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:57:02.808] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[15:57:02.814] <TB2> INFO: safety margin for low PH: adding 2, margin is now 22
[15:57:02.821] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:57:02.827] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:57:02.834] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:57:02.841] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:57:02.847] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:57:02.854] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:57:02.860] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:57:02.867] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[15:57:02.873] <TB2> INFO: safety margin for low PH: adding 2, margin is now 22
[15:57:02.880] <TB2> INFO: safety margin for low PH: adding 3, margin is now 23
[15:57:02.887] <TB2> INFO: safety margin for low PH: adding 4, margin is now 24
[15:57:02.893] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:57:02.900] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:57:02.906] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:57:02.913] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:57:02.920] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:57:02.926] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:57:02.933] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[15:57:02.996] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C0.dat
[15:57:03.001] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C1.dat
[15:57:03.006] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C2.dat
[15:57:03.011] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C3.dat
[15:57:03.017] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C4.dat
[15:57:03.022] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C5.dat
[15:57:03.027] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C6.dat
[15:57:03.032] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C7.dat
[15:57:03.037] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C8.dat
[15:57:03.044] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C9.dat
[15:57:03.050] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C10.dat
[15:57:03.055] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C11.dat
[15:57:03.060] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C12.dat
[15:57:03.065] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C13.dat
[15:57:03.070] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C14.dat
[15:57:03.075] <TB2> INFO: write dac parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//dacParameters35_C15.dat
[15:57:03.354] <TB2> INFO: Expecting 41600 events.
[15:57:06.857] <TB2> INFO: 41600 events read in total (2946ms).
[15:57:06.858] <TB2> INFO: Test took 3776ms.
[15:57:07.327] <TB2> INFO: Expecting 41600 events.
[15:57:10.819] <TB2> INFO: 41600 events read in total (2936ms).
[15:57:10.821] <TB2> INFO: Test took 3775ms.
[15:57:11.273] <TB2> INFO: Expecting 41600 events.
[15:57:14.759] <TB2> INFO: 41600 events read in total (2930ms).
[15:57:14.760] <TB2> INFO: Test took 3752ms.
[15:57:14.952] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:15.042] <TB2> INFO: Expecting 2560 events.
[15:57:15.899] <TB2> INFO: 2560 events read in total (300ms).
[15:57:15.899] <TB2> INFO: Test took 947ms.
[15:57:15.902] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:16.205] <TB2> INFO: Expecting 2560 events.
[15:57:17.059] <TB2> INFO: 2560 events read in total (297ms).
[15:57:17.060] <TB2> INFO: Test took 1158ms.
[15:57:17.062] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:17.366] <TB2> INFO: Expecting 2560 events.
[15:57:18.221] <TB2> INFO: 2560 events read in total (298ms).
[15:57:18.221] <TB2> INFO: Test took 1159ms.
[15:57:18.224] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:18.528] <TB2> INFO: Expecting 2560 events.
[15:57:19.383] <TB2> INFO: 2560 events read in total (298ms).
[15:57:19.383] <TB2> INFO: Test took 1159ms.
[15:57:19.386] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:19.690] <TB2> INFO: Expecting 2560 events.
[15:57:20.545] <TB2> INFO: 2560 events read in total (298ms).
[15:57:20.545] <TB2> INFO: Test took 1159ms.
[15:57:20.548] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:20.852] <TB2> INFO: Expecting 2560 events.
[15:57:21.712] <TB2> INFO: 2560 events read in total (303ms).
[15:57:21.712] <TB2> INFO: Test took 1164ms.
[15:57:21.716] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:22.018] <TB2> INFO: Expecting 2560 events.
[15:57:22.873] <TB2> INFO: 2560 events read in total (298ms).
[15:57:22.873] <TB2> INFO: Test took 1159ms.
[15:57:22.876] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:23.180] <TB2> INFO: Expecting 2560 events.
[15:57:24.035] <TB2> INFO: 2560 events read in total (298ms).
[15:57:24.036] <TB2> INFO: Test took 1160ms.
[15:57:24.038] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:24.342] <TB2> INFO: Expecting 2560 events.
[15:57:25.197] <TB2> INFO: 2560 events read in total (298ms).
[15:57:25.197] <TB2> INFO: Test took 1159ms.
[15:57:25.200] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:25.504] <TB2> INFO: Expecting 2560 events.
[15:57:26.358] <TB2> INFO: 2560 events read in total (297ms).
[15:57:26.359] <TB2> INFO: Test took 1159ms.
[15:57:26.361] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:26.666] <TB2> INFO: Expecting 2560 events.
[15:57:27.520] <TB2> INFO: 2560 events read in total (298ms).
[15:57:27.521] <TB2> INFO: Test took 1160ms.
[15:57:27.524] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:27.828] <TB2> INFO: Expecting 2560 events.
[15:57:28.683] <TB2> INFO: 2560 events read in total (298ms).
[15:57:28.683] <TB2> INFO: Test took 1159ms.
[15:57:28.685] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:28.990] <TB2> INFO: Expecting 2560 events.
[15:57:29.846] <TB2> INFO: 2560 events read in total (299ms).
[15:57:29.846] <TB2> INFO: Test took 1161ms.
[15:57:29.849] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:30.153] <TB2> INFO: Expecting 2560 events.
[15:57:31.008] <TB2> INFO: 2560 events read in total (298ms).
[15:57:31.009] <TB2> INFO: Test took 1161ms.
[15:57:31.011] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:31.316] <TB2> INFO: Expecting 2560 events.
[15:57:32.172] <TB2> INFO: 2560 events read in total (299ms).
[15:57:32.173] <TB2> INFO: Test took 1162ms.
[15:57:32.175] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:32.479] <TB2> INFO: Expecting 2560 events.
[15:57:33.333] <TB2> INFO: 2560 events read in total (297ms).
[15:57:33.333] <TB2> INFO: Test took 1158ms.
[15:57:33.336] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:33.640] <TB2> INFO: Expecting 2560 events.
[15:57:34.496] <TB2> INFO: 2560 events read in total (299ms).
[15:57:34.496] <TB2> INFO: Test took 1160ms.
[15:57:34.501] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:34.803] <TB2> INFO: Expecting 2560 events.
[15:57:35.658] <TB2> INFO: 2560 events read in total (299ms).
[15:57:35.658] <TB2> INFO: Test took 1158ms.
[15:57:35.661] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:35.965] <TB2> INFO: Expecting 2560 events.
[15:57:36.819] <TB2> INFO: 2560 events read in total (297ms).
[15:57:36.820] <TB2> INFO: Test took 1159ms.
[15:57:36.822] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:37.127] <TB2> INFO: Expecting 2560 events.
[15:57:37.982] <TB2> INFO: 2560 events read in total (298ms).
[15:57:37.982] <TB2> INFO: Test took 1160ms.
[15:57:37.985] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:38.289] <TB2> INFO: Expecting 2560 events.
[15:57:39.144] <TB2> INFO: 2560 events read in total (298ms).
[15:57:39.144] <TB2> INFO: Test took 1159ms.
[15:57:39.147] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:39.451] <TB2> INFO: Expecting 2560 events.
[15:57:40.307] <TB2> INFO: 2560 events read in total (299ms).
[15:57:40.307] <TB2> INFO: Test took 1161ms.
[15:57:40.310] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:40.614] <TB2> INFO: Expecting 2560 events.
[15:57:41.471] <TB2> INFO: 2560 events read in total (300ms).
[15:57:41.472] <TB2> INFO: Test took 1162ms.
[15:57:41.475] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:41.779] <TB2> INFO: Expecting 2560 events.
[15:57:42.634] <TB2> INFO: 2560 events read in total (298ms).
[15:57:42.634] <TB2> INFO: Test took 1159ms.
[15:57:42.637] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:42.941] <TB2> INFO: Expecting 2560 events.
[15:57:43.796] <TB2> INFO: 2560 events read in total (298ms).
[15:57:43.796] <TB2> INFO: Test took 1159ms.
[15:57:43.799] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:44.103] <TB2> INFO: Expecting 2560 events.
[15:57:44.959] <TB2> INFO: 2560 events read in total (299ms).
[15:57:44.959] <TB2> INFO: Test took 1160ms.
[15:57:44.962] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:45.267] <TB2> INFO: Expecting 2560 events.
[15:57:46.122] <TB2> INFO: 2560 events read in total (298ms).
[15:57:46.123] <TB2> INFO: Test took 1161ms.
[15:57:46.126] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:46.429] <TB2> INFO: Expecting 2560 events.
[15:57:47.287] <TB2> INFO: 2560 events read in total (301ms).
[15:57:47.288] <TB2> INFO: Test took 1162ms.
[15:57:47.290] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:47.595] <TB2> INFO: Expecting 2560 events.
[15:57:48.450] <TB2> INFO: 2560 events read in total (298ms).
[15:57:48.450] <TB2> INFO: Test took 1160ms.
[15:57:48.452] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:48.757] <TB2> INFO: Expecting 2560 events.
[15:57:49.616] <TB2> INFO: 2560 events read in total (302ms).
[15:57:49.617] <TB2> INFO: Test took 1165ms.
[15:57:49.619] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:49.925] <TB2> INFO: Expecting 2560 events.
[15:57:50.780] <TB2> INFO: 2560 events read in total (299ms).
[15:57:50.780] <TB2> INFO: Test took 1161ms.
[15:57:50.783] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[15:57:51.087] <TB2> INFO: Expecting 2560 events.
[15:57:51.941] <TB2> INFO: 2560 events read in total (297ms).
[15:57:51.941] <TB2> INFO: Test took 1158ms.
[15:57:52.441] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 618 seconds
[15:57:52.441] <TB2> INFO: PH scale (per ROC): 66 75 70 78 77 76 75 72 74 83 81 65 74 72 64 71
[15:57:52.441] <TB2> INFO: PH offset (per ROC): 184 192 173 164 171 160 175 191 176 173 162 187 160 165 189 184
[15:57:52.446] <TB2> INFO: Decoding statistics:
[15:57:52.446] <TB2> INFO: General information:
[15:57:52.446] <TB2> INFO: 16bit words read: 91928
[15:57:52.446] <TB2> INFO: valid events total: 10240
[15:57:52.446] <TB2> INFO: empty events: 7738
[15:57:52.446] <TB2> INFO: valid events with pixels: 2502
[15:57:52.446] <TB2> INFO: valid pixel hits: 2502
[15:57:52.446] <TB2> INFO: Event errors: 0
[15:57:52.446] <TB2> INFO: start marker: 0
[15:57:52.446] <TB2> INFO: stop marker: 0
[15:57:52.446] <TB2> INFO: overflow: 0
[15:57:52.446] <TB2> INFO: invalid 5bit words: 0
[15:57:52.446] <TB2> INFO: invalid XOR eye diagram: 0
[15:57:52.446] <TB2> INFO: frame (failed synchr.): 0
[15:57:52.446] <TB2> INFO: idle data (no TBM trl): 0
[15:57:52.446] <TB2> INFO: no data (only TBM hdr): 0
[15:57:52.446] <TB2> INFO: TBM errors: 0
[15:57:52.446] <TB2> INFO: flawed TBM headers: 0
[15:57:52.446] <TB2> INFO: flawed TBM trailers: 0
[15:57:52.446] <TB2> INFO: event ID mismatches: 0
[15:57:52.446] <TB2> INFO: ROC errors: 0
[15:57:52.446] <TB2> INFO: missing ROC header(s): 0
[15:57:52.446] <TB2> INFO: misplaced readback start: 0
[15:57:52.446] <TB2> INFO: Pixel decoding errors: 0
[15:57:52.446] <TB2> INFO: pixel data incomplete: 0
[15:57:52.446] <TB2> INFO: pixel address: 0
[15:57:52.446] <TB2> INFO: pulse height fill bit: 0
[15:57:52.446] <TB2> INFO: buffer corruption: 0
[15:57:52.618] <TB2> INFO: ######################################################################
[15:57:52.618] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[15:57:52.618] <TB2> INFO: ######################################################################
[15:57:52.628] <TB2> INFO: scanning low vcal = 10
[15:57:52.891] <TB2> INFO: Expecting 41600 events.
[15:57:56.434] <TB2> INFO: 41600 events read in total (2986ms).
[15:57:56.434] <TB2> INFO: Test took 3806ms.
[15:57:56.436] <TB2> INFO: scanning low vcal = 20
[15:57:56.741] <TB2> INFO: Expecting 41600 events.
[15:58:00.315] <TB2> INFO: 41600 events read in total (3017ms).
[15:58:00.316] <TB2> INFO: Test took 3880ms.
[15:58:00.318] <TB2> INFO: scanning low vcal = 30
[15:58:00.622] <TB2> INFO: Expecting 41600 events.
[15:58:04.203] <TB2> INFO: 41600 events read in total (3023ms).
[15:58:04.203] <TB2> INFO: Test took 3885ms.
[15:58:04.206] <TB2> INFO: scanning low vcal = 40
[15:58:04.502] <TB2> INFO: Expecting 41600 events.
[15:58:08.664] <TB2> INFO: 41600 events read in total (3605ms).
[15:58:08.665] <TB2> INFO: Test took 4459ms.
[15:58:08.668] <TB2> INFO: scanning low vcal = 50
[15:58:08.940] <TB2> INFO: Expecting 41600 events.
[15:58:13.126] <TB2> INFO: 41600 events read in total (3631ms).
[15:58:13.127] <TB2> INFO: Test took 4458ms.
[15:58:13.130] <TB2> INFO: scanning low vcal = 60
[15:58:13.401] <TB2> INFO: Expecting 41600 events.
[15:58:17.598] <TB2> INFO: 41600 events read in total (3640ms).
[15:58:17.598] <TB2> INFO: Test took 4467ms.
[15:58:17.602] <TB2> INFO: scanning low vcal = 70
[15:58:17.867] <TB2> INFO: Expecting 41600 events.
[15:58:22.061] <TB2> INFO: 41600 events read in total (3637ms).
[15:58:22.062] <TB2> INFO: Test took 4460ms.
[15:58:22.065] <TB2> INFO: scanning low vcal = 80
[15:58:22.334] <TB2> INFO: Expecting 41600 events.
[15:58:26.541] <TB2> INFO: 41600 events read in total (3651ms).
[15:58:26.542] <TB2> INFO: Test took 4477ms.
[15:58:26.547] <TB2> INFO: scanning low vcal = 90
[15:58:26.820] <TB2> INFO: Expecting 41600 events.
[15:58:31.022] <TB2> INFO: 41600 events read in total (3646ms).
[15:58:31.023] <TB2> INFO: Test took 4475ms.
[15:58:31.026] <TB2> INFO: scanning low vcal = 100
[15:58:31.291] <TB2> INFO: Expecting 41600 events.
[15:58:35.499] <TB2> INFO: 41600 events read in total (3651ms).
[15:58:35.500] <TB2> INFO: Test took 4473ms.
[15:58:35.503] <TB2> INFO: scanning low vcal = 110
[15:58:35.769] <TB2> INFO: Expecting 41600 events.
[15:58:39.967] <TB2> INFO: 41600 events read in total (3641ms).
[15:58:39.968] <TB2> INFO: Test took 4464ms.
[15:58:39.971] <TB2> INFO: scanning low vcal = 120
[15:58:40.247] <TB2> INFO: Expecting 41600 events.
[15:58:44.468] <TB2> INFO: 41600 events read in total (3664ms).
[15:58:44.469] <TB2> INFO: Test took 4498ms.
[15:58:44.473] <TB2> INFO: scanning low vcal = 130
[15:58:44.745] <TB2> INFO: Expecting 41600 events.
[15:58:48.950] <TB2> INFO: 41600 events read in total (3648ms).
[15:58:48.951] <TB2> INFO: Test took 4478ms.
[15:58:48.954] <TB2> INFO: scanning low vcal = 140
[15:58:49.220] <TB2> INFO: Expecting 41600 events.
[15:58:53.409] <TB2> INFO: 41600 events read in total (3632ms).
[15:58:53.409] <TB2> INFO: Test took 4455ms.
[15:58:53.412] <TB2> INFO: scanning low vcal = 150
[15:58:53.676] <TB2> INFO: Expecting 41600 events.
[15:58:57.892] <TB2> INFO: 41600 events read in total (3659ms).
[15:58:57.892] <TB2> INFO: Test took 4480ms.
[15:58:57.896] <TB2> INFO: scanning low vcal = 160
[15:58:58.170] <TB2> INFO: Expecting 41600 events.
[15:59:02.355] <TB2> INFO: 41600 events read in total (3628ms).
[15:59:02.356] <TB2> INFO: Test took 4460ms.
[15:59:02.359] <TB2> INFO: scanning low vcal = 170
[15:59:02.625] <TB2> INFO: Expecting 41600 events.
[15:59:06.813] <TB2> INFO: 41600 events read in total (3631ms).
[15:59:06.814] <TB2> INFO: Test took 4455ms.
[15:59:06.818] <TB2> INFO: scanning low vcal = 180
[15:59:07.084] <TB2> INFO: Expecting 41600 events.
[15:59:11.290] <TB2> INFO: 41600 events read in total (3649ms).
[15:59:11.291] <TB2> INFO: Test took 4472ms.
[15:59:11.294] <TB2> INFO: scanning low vcal = 190
[15:59:11.566] <TB2> INFO: Expecting 41600 events.
[15:59:15.811] <TB2> INFO: 41600 events read in total (3687ms).
[15:59:15.812] <TB2> INFO: Test took 4518ms.
[15:59:15.815] <TB2> INFO: scanning low vcal = 200
[15:59:16.087] <TB2> INFO: Expecting 41600 events.
[15:59:20.305] <TB2> INFO: 41600 events read in total (3661ms).
[15:59:20.306] <TB2> INFO: Test took 4491ms.
[15:59:20.309] <TB2> INFO: scanning low vcal = 210
[15:59:20.580] <TB2> INFO: Expecting 41600 events.
[15:59:24.811] <TB2> INFO: 41600 events read in total (3674ms).
[15:59:24.811] <TB2> INFO: Test took 4502ms.
[15:59:24.814] <TB2> INFO: scanning low vcal = 220
[15:59:25.076] <TB2> INFO: Expecting 41600 events.
[15:59:29.316] <TB2> INFO: 41600 events read in total (3683ms).
[15:59:29.316] <TB2> INFO: Test took 4501ms.
[15:59:29.320] <TB2> INFO: scanning low vcal = 230
[15:59:29.591] <TB2> INFO: Expecting 41600 events.
[15:59:33.806] <TB2> INFO: 41600 events read in total (3658ms).
[15:59:33.807] <TB2> INFO: Test took 4487ms.
[15:59:33.810] <TB2> INFO: scanning low vcal = 240
[15:59:34.088] <TB2> INFO: Expecting 41600 events.
[15:59:38.308] <TB2> INFO: 41600 events read in total (3663ms).
[15:59:38.309] <TB2> INFO: Test took 4499ms.
[15:59:38.312] <TB2> INFO: scanning low vcal = 250
[15:59:38.577] <TB2> INFO: Expecting 41600 events.
[15:59:42.806] <TB2> INFO: 41600 events read in total (3672ms).
[15:59:42.806] <TB2> INFO: Test took 4494ms.
[15:59:42.812] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[15:59:43.082] <TB2> INFO: Expecting 41600 events.
[15:59:47.303] <TB2> INFO: 41600 events read in total (3664ms).
[15:59:47.304] <TB2> INFO: Test took 4492ms.
[15:59:47.307] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[15:59:47.582] <TB2> INFO: Expecting 41600 events.
[15:59:51.793] <TB2> INFO: 41600 events read in total (3654ms).
[15:59:51.794] <TB2> INFO: Test took 4487ms.
[15:59:51.797] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[15:59:52.061] <TB2> INFO: Expecting 41600 events.
[15:59:56.302] <TB2> INFO: 41600 events read in total (3684ms).
[15:59:56.303] <TB2> INFO: Test took 4505ms.
[15:59:56.306] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[15:59:56.584] <TB2> INFO: Expecting 41600 events.
[16:00:00.788] <TB2> INFO: 41600 events read in total (3647ms).
[16:00:00.789] <TB2> INFO: Test took 4482ms.
[16:00:00.793] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[16:00:01.068] <TB2> INFO: Expecting 41600 events.
[16:00:05.265] <TB2> INFO: 41600 events read in total (3640ms).
[16:00:05.266] <TB2> INFO: Test took 4473ms.
[16:00:06.376] <TB2> INFO: PixTestGainPedestal::measure() done
[16:00:39.764] <TB2> INFO: PixTestGainPedestal::fit() done
[16:00:39.764] <TB2> INFO: non-linearity mean: 0.949 0.956 0.958 0.952 0.954 0.956 0.953 0.955 0.960 0.960 0.957 0.954 0.956 0.959 0.961 0.963
[16:00:39.764] <TB2> INFO: non-linearity RMS: 0.007 0.007 0.006 0.006 0.008 0.006 0.007 0.009 0.006 0.007 0.006 0.008 0.006 0.006 0.007 0.007
[16:00:39.765] <TB2> INFO: write gain/ped parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[16:00:39.808] <TB2> INFO: write gain/ped parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[16:00:39.848] <TB2> INFO: write gain/ped parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[16:00:39.886] <TB2> INFO: write gain/ped parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[16:00:39.926] <TB2> INFO: write gain/ped parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[16:00:39.968] <TB2> INFO: write gain/ped parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[16:00:40.026] <TB2> INFO: write gain/ped parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[16:00:40.064] <TB2> INFO: write gain/ped parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[16:00:40.103] <TB2> INFO: write gain/ped parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[16:00:40.148] <TB2> INFO: write gain/ped parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[16:00:40.188] <TB2> INFO: write gain/ped parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[16:00:40.232] <TB2> INFO: write gain/ped parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[16:00:40.270] <TB2> INFO: write gain/ped parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[16:00:40.309] <TB2> INFO: write gain/ped parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[16:00:40.350] <TB2> INFO: write gain/ped parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[16:00:40.390] <TB2> INFO: write gain/ped parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[16:00:40.433] <TB2> INFO: PixTestGainPedestal::fullTest() done, duration: 167 seconds
[16:00:40.433] <TB2> INFO: Decoding statistics:
[16:00:40.434] <TB2> INFO: General information:
[16:00:40.434] <TB2> INFO: 16bit words read: 2662400
[16:00:40.434] <TB2> INFO: valid events total: 166400
[16:00:40.434] <TB2> INFO: empty events: 0
[16:00:40.434] <TB2> INFO: valid events with pixels: 166400
[16:00:40.434] <TB2> INFO: valid pixel hits: 665571
[16:00:40.434] <TB2> INFO: Event errors: 0
[16:00:40.434] <TB2> INFO: start marker: 0
[16:00:40.434] <TB2> INFO: stop marker: 0
[16:00:40.434] <TB2> INFO: overflow: 0
[16:00:40.434] <TB2> INFO: invalid 5bit words: 0
[16:00:40.434] <TB2> INFO: invalid XOR eye diagram: 0
[16:00:40.434] <TB2> INFO: frame (failed synchr.): 0
[16:00:40.434] <TB2> INFO: idle data (no TBM trl): 0
[16:00:40.434] <TB2> INFO: no data (only TBM hdr): 0
[16:00:40.434] <TB2> INFO: TBM errors: 0
[16:00:40.434] <TB2> INFO: flawed TBM headers: 0
[16:00:40.434] <TB2> INFO: flawed TBM trailers: 0
[16:00:40.434] <TB2> INFO: event ID mismatches: 0
[16:00:40.434] <TB2> INFO: ROC errors: 0
[16:00:40.434] <TB2> INFO: missing ROC header(s): 0
[16:00:40.434] <TB2> INFO: misplaced readback start: 0
[16:00:40.434] <TB2> INFO: Pixel decoding errors: 0
[16:00:40.434] <TB2> INFO: pixel data incomplete: 0
[16:00:40.434] <TB2> INFO: pixel address: 0
[16:00:40.434] <TB2> INFO: pulse height fill bit: 0
[16:00:40.434] <TB2> INFO: buffer corruption: 0
[16:00:40.443] <TB2> INFO: readReadbackCal: /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C0.dat .. /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C15.dat
[16:00:40.568] <TB2> INFO: ######################################################################
[16:00:40.568] <TB2> INFO: PixTestReadback::doTest()
[16:00:40.568] <TB2> INFO: ######################################################################
[16:00:40.568] <TB2> INFO: ----------------------------------------------------------------------
[16:00:40.568] <TB2> INFO: PixTestReadback::CalibrateVd()
[16:00:40.568] <TB2> INFO: ----------------------------------------------------------------------
[16:00:50.146] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C0.dat
[16:00:50.171] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C1.dat
[16:00:50.176] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C2.dat
[16:00:50.183] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C3.dat
[16:00:50.188] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C4.dat
[16:00:50.193] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C5.dat
[16:00:50.199] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C6.dat
[16:00:50.204] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C7.dat
[16:00:50.209] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C8.dat
[16:00:50.214] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C9.dat
[16:00:50.219] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C10.dat
[16:00:50.225] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C11.dat
[16:00:50.231] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C12.dat
[16:00:50.237] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C13.dat
[16:00:50.242] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C14.dat
[16:00:50.249] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C15.dat
[16:00:50.298] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[16:00:50.298] <TB2> INFO: ----------------------------------------------------------------------
[16:00:50.298] <TB2> INFO: PixTestReadback::CalibrateVa()
[16:00:50.298] <TB2> INFO: ----------------------------------------------------------------------
[16:00:59.883] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C0.dat
[16:00:59.890] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C1.dat
[16:00:59.896] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C2.dat
[16:00:59.902] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C3.dat
[16:00:59.907] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C4.dat
[16:00:59.914] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C5.dat
[16:00:59.919] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C6.dat
[16:00:59.924] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C7.dat
[16:00:59.931] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C8.dat
[16:00:59.937] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C9.dat
[16:00:59.942] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C10.dat
[16:00:59.948] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C11.dat
[16:00:59.953] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C12.dat
[16:00:59.958] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C13.dat
[16:00:59.963] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C14.dat
[16:00:59.969] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C15.dat
[16:01:00.018] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[16:01:00.018] <TB2> INFO: ----------------------------------------------------------------------
[16:01:00.018] <TB2> INFO: PixTestReadback::readbackVbg()
[16:01:00.018] <TB2> INFO: ----------------------------------------------------------------------
[16:01:07.355] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[16:01:07.356] <TB2> INFO: ----------------------------------------------------------------------
[16:01:07.356] <TB2> INFO: PixTestReadback::getCalibratedVbg()
[16:01:07.356] <TB2> INFO: ----------------------------------------------------------------------
[16:01:07.356] <TB2> INFO: Vbg will be calibrated using Vd calibration
[16:01:07.356] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 151.9calibrated Vbg = 1.24083 :::*/*/*/*/
[16:01:07.356] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 160.1calibrated Vbg = 1.24009 :::*/*/*/*/
[16:01:07.356] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 151.3calibrated Vbg = 1.23867 :::*/*/*/*/
[16:01:07.356] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 151.9calibrated Vbg = 1.2541 :::*/*/*/*/
[16:01:07.356] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 156.6calibrated Vbg = 1.25168 :::*/*/*/*/
[16:01:07.356] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 156.1calibrated Vbg = 1.24723 :::*/*/*/*/
[16:01:07.356] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 154.2calibrated Vbg = 1.2528 :::*/*/*/*/
[16:01:07.356] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 155.7calibrated Vbg = 1.25371 :::*/*/*/*/
[16:01:07.356] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 145.4calibrated Vbg = 1.25315 :::*/*/*/*/
[16:01:07.356] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 157calibrated Vbg = 1.24798 :::*/*/*/*/
[16:01:07.356] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 153calibrated Vbg = 1.24953 :::*/*/*/*/
[16:01:07.356] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 156calibrated Vbg = 1.24362 :::*/*/*/*/
[16:01:07.356] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 148.4calibrated Vbg = 1.24319 :::*/*/*/*/
[16:01:07.356] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 157.7calibrated Vbg = 1.2477 :::*/*/*/*/
[16:01:07.356] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 157.2calibrated Vbg = 1.24214 :::*/*/*/*/
[16:01:07.356] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 157.7calibrated Vbg = 1.24562 :::*/*/*/*/
[16:01:07.358] <TB2> INFO: ----------------------------------------------------------------------
[16:01:07.358] <TB2> INFO: PixTestReadback::CalibrateIa()
[16:01:07.358] <TB2> INFO: ----------------------------------------------------------------------
[16:03:43.360] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C0.dat
[16:03:43.365] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C1.dat
[16:03:43.370] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C2.dat
[16:03:43.375] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C3.dat
[16:03:43.380] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C4.dat
[16:03:43.385] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C5.dat
[16:03:43.390] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C6.dat
[16:03:43.395] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C7.dat
[16:03:43.400] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C8.dat
[16:03:43.405] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C9.dat
[16:03:43.410] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C10.dat
[16:03:43.415] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C11.dat
[16:03:43.420] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C12.dat
[16:03:43.425] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C13.dat
[16:03:43.431] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C14.dat
[16:03:43.436] <TB2> INFO: write readback calibration parameters into /home/production/dataCH/M2289_FullQualification_2016-08-08_09h46m_1470642396//004_FulltestPxar_p17//readbackCal_C15.dat
[16:03:43.483] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[16:03:43.484] <TB2> INFO: PixTestReadback::doTest() done
[16:03:43.485] <TB2> INFO: Decoding statistics:
[16:03:43.485] <TB2> INFO: General information:
[16:03:43.485] <TB2> INFO: 16bit words read: 1024
[16:03:43.485] <TB2> INFO: valid events total: 128
[16:03:43.485] <TB2> INFO: empty events: 128
[16:03:43.485] <TB2> INFO: valid events with pixels: 0
[16:03:43.485] <TB2> INFO: valid pixel hits: 0
[16:03:43.485] <TB2> INFO: Event errors: 0
[16:03:43.485] <TB2> INFO: start marker: 0
[16:03:43.485] <TB2> INFO: stop marker: 0
[16:03:43.485] <TB2> INFO: overflow: 0
[16:03:43.485] <TB2> INFO: invalid 5bit words: 0
[16:03:43.485] <TB2> INFO: invalid XOR eye diagram: 0
[16:03:43.485] <TB2> INFO: frame (failed synchr.): 0
[16:03:43.485] <TB2> INFO: idle data (no TBM trl): 0
[16:03:43.485] <TB2> INFO: no data (only TBM hdr): 0
[16:03:43.485] <TB2> INFO: TBM errors: 0
[16:03:43.485] <TB2> INFO: flawed TBM headers: 0
[16:03:43.485] <TB2> INFO: flawed TBM trailers: 0
[16:03:43.485] <TB2> INFO: event ID mismatches: 0
[16:03:43.485] <TB2> INFO: ROC errors: 0
[16:03:43.485] <TB2> INFO: missing ROC header(s): 0
[16:03:43.485] <TB2> INFO: misplaced readback start: 0
[16:03:43.485] <TB2> INFO: Pixel decoding errors: 0
[16:03:43.485] <TB2> INFO: pixel data incomplete: 0
[16:03:43.485] <TB2> INFO: pixel address: 0
[16:03:43.485] <TB2> INFO: pulse height fill bit: 0
[16:03:43.485] <TB2> INFO: buffer corruption: 0
[16:03:43.505] <TB2> INFO: Decoding statistics:
[16:03:43.505] <TB2> INFO: General information:
[16:03:43.505] <TB2> INFO: 16bit words read: 2755352
[16:03:43.505] <TB2> INFO: valid events total: 176768
[16:03:43.505] <TB2> INFO: empty events: 7866
[16:03:43.505] <TB2> INFO: valid events with pixels: 168902
[16:03:43.506] <TB2> INFO: valid pixel hits: 668073
[16:03:43.506] <TB2> INFO: Event errors: 0
[16:03:43.506] <TB2> INFO: start marker: 0
[16:03:43.506] <TB2> INFO: stop marker: 0
[16:03:43.506] <TB2> INFO: overflow: 0
[16:03:43.506] <TB2> INFO: invalid 5bit words: 0
[16:03:43.506] <TB2> INFO: invalid XOR eye diagram: 0
[16:03:43.506] <TB2> INFO: frame (failed synchr.): 0
[16:03:43.506] <TB2> INFO: idle data (no TBM trl): 0
[16:03:43.506] <TB2> INFO: no data (only TBM hdr): 0
[16:03:43.506] <TB2> INFO: TBM errors: 0
[16:03:43.506] <TB2> INFO: flawed TBM headers: 0
[16:03:43.506] <TB2> INFO: flawed TBM trailers: 0
[16:03:43.506] <TB2> INFO: event ID mismatches: 0
[16:03:43.506] <TB2> INFO: ROC errors: 0
[16:03:43.506] <TB2> INFO: missing ROC header(s): 0
[16:03:43.506] <TB2> INFO: misplaced readback start: 0
[16:03:43.506] <TB2> INFO: Pixel decoding errors: 0
[16:03:43.506] <TB2> INFO: pixel data incomplete: 0
[16:03:43.506] <TB2> INFO: pixel address: 0
[16:03:43.506] <TB2> INFO: pulse height fill bit: 0
[16:03:43.506] <TB2> INFO: buffer corruption: 0
[16:03:43.506] <TB2> INFO: enter test to run
[16:03:43.506] <TB2> INFO: test: exit no parameter change
[16:03:43.545] <TB2> QUIET: Connection to board 149 closed.
[16:03:43.625] <TB2> INFO: pXar: this is the end, my friend