[17:31:30.473] <TB3> INFO: *** Welcome to pxar ***
[17:31:30.473] <TB3> INFO: *** Today: 2015/10/29
[17:31:30.536] <TB3> INFO: *** Version: 9da6
[17:31:30.536] <TB3> INFO: readRocDacs: /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters_C15.dat
[17:31:30.537] <TB3> INFO: readTbmDacs: /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//tbmParameters_C0b.dat
[17:31:30.537] <TB3> INFO: readMaskFile: /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//defaultMaskFile.dat
[17:31:30.537] <TB3> INFO: readTrimFile: /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//trimParameters_C15.dat
[17:31:30.618] <TB3> INFO: clk: 4
[17:31:30.618] <TB3> INFO: ctr: 4
[17:31:30.618] <TB3> INFO: sda: 19
[17:31:30.618] <TB3> INFO: tin: 9
[17:31:30.618] <TB3> INFO: level: 15
[17:31:30.618] <TB3> INFO: triggerdelay: 0
[17:31:30.618] <TB3> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[17:31:30.618] <TB3> INFO: Log level: INFO
[17:31:30.628] <TB3> INFO: Found DTB DTB_WWXJGB
[17:31:30.635] <TB3> QUIET: Connection to board DTB_WWXJGB opened.
[17:31:30.638] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 137
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WWXJGB
MAC address: 40D855118089
Hostname: pixelDTB137
Comment:
------------------------------------------------------
[17:31:30.640] <TB3> INFO: RPC call hashes of host and DTB match: 398089610
[17:31:32.184] <TB3> INFO: DUT info:
[17:31:32.184] <TB3> INFO: The DUT currently contains the following objects:
[17:31:32.184] <TB3> INFO: 2 TBM Cores tbm08c (2 ON)
[17:31:32.184] <TB3> INFO: TBM Core alpha (0): 7 registers set
[17:31:32.184] <TB3> INFO: TBM Core beta (1): 7 registers set
[17:31:32.184] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:31:32.184] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:31:32.184] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:31:32.184] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:31:32.184] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:31:32.184] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:31:32.184] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:31:32.184] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:31:32.184] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:31:32.184] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:31:32.185] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:31:32.185] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:31:32.185] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:31:32.185] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:31:32.185] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:31:32.185] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:31:32.185] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:31:32.586] <TB3> INFO: enter 'restricted' command line mode
[17:31:32.586] <TB3> INFO: enter test to run
[17:31:32.586] <TB3> INFO: test: pretest no parameter change
[17:31:32.586] <TB3> INFO: running: pretest
[17:31:32.593] <TB3> INFO: ######################################################################
[17:31:32.593] <TB3> INFO: PixTestPretest::doTest()
[17:31:32.593] <TB3> INFO: ######################################################################
[17:31:32.595] <TB3> INFO: ----------------------------------------------------------------------
[17:31:32.595] <TB3> INFO: PixTestPretest::programROC()
[17:31:32.595] <TB3> INFO: ----------------------------------------------------------------------
[17:31:50.612] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[17:31:50.612] <TB3> INFO: IA differences per ROC: 18.5 16.1 17.7 20.1 20.9 19.3 19.3 19.3 16.1 19.3 18.5 18.5 20.9 18.5 19.3 20.1
[17:31:50.681] <TB3> INFO: ----------------------------------------------------------------------
[17:31:50.681] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[17:31:50.681] <TB3> INFO: ----------------------------------------------------------------------
[17:32:11.954] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 384.3 mA = 24.0187 mA/ROC
[17:32:11.954] <TB3> INFO: i(loss) [mA/ROC]: 20.1 19.3 19.3 19.3 19.3 18.5 18.5 20.1 19.3 19.3 19.3 19.3 19.3 19.3 20.1 19.3
[17:32:11.984] <TB3> INFO: ----------------------------------------------------------------------
[17:32:11.984] <TB3> INFO: PixTestPretest::findTiming()
[17:32:11.984] <TB3> INFO: ----------------------------------------------------------------------
[17:32:11.984] <TB3> INFO: PixTestCmd::init()
[17:32:12.578] <TB3> WARNING: Not unmasking DUT, not setting Calibrate bits!
[17:33:51.079] <TB3> INFO: TBM phases: 160MHz: 1, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[17:33:51.079] <TB3> INFO: (success/tries = 100/100), width = 3
[17:33:51.081] <TB3> INFO: ----------------------------------------------------------------------
[17:33:51.081] <TB3> INFO: PixTestPretest::findWorkingPixel()
[17:33:51.081] <TB3> INFO: ----------------------------------------------------------------------
[17:33:51.216] <TB3> INFO: Expecting 231680 events.
[17:33:55.825] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 0 Number of ROCs (1) != Token Chain Length (8)
[17:33:55.828] <TB3> ERROR: <datapipe.cc/CheckEventID:L461> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[17:33:58.936] <TB3> INFO: 231680 events read in total (7005ms).
[17:33:58.939] <TB3> INFO: Test took 7856ms.
[17:33:59.350] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[17:33:59.379] <TB3> INFO: ----------------------------------------------------------------------
[17:33:59.379] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[17:33:59.379] <TB3> INFO: ----------------------------------------------------------------------
[17:33:59.514] <TB3> INFO: Expecting 231680 events.
[17:34:08.228] <TB3> INFO: 231680 events read in total (7999ms).
[17:34:08.232] <TB3> INFO: Test took 8849ms.
[17:34:08.660] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[17:34:08.660] <TB3> INFO: CalDel: 115 117 109 114 131 115 124 121 119 123 109 120 128 117 114 125
[17:34:08.660] <TB3> INFO: VthrComp: 51 51 51 51 51 52 51 51 51 51 51 51 51 51 51 51
[17:34:08.663] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters_C0.dat
[17:34:08.664] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters_C1.dat
[17:34:08.664] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters_C2.dat
[17:34:08.664] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters_C3.dat
[17:34:08.664] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters_C4.dat
[17:34:08.665] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters_C5.dat
[17:34:08.665] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters_C6.dat
[17:34:08.665] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters_C7.dat
[17:34:08.665] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters_C8.dat
[17:34:08.666] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters_C9.dat
[17:34:08.666] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters_C10.dat
[17:34:08.666] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters_C11.dat
[17:34:08.666] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters_C12.dat
[17:34:08.666] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters_C13.dat
[17:34:08.666] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters_C14.dat
[17:34:08.667] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters_C15.dat
[17:34:08.667] <TB3> INFO: write tbm parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//tbmParameters_C0a.dat
[17:34:08.667] <TB3> INFO: write tbm parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//tbmParameters_C0b.dat
[17:34:08.667] <TB3> INFO: PixTestPretest::doTest() done, duration: 156 seconds
[17:34:08.732] <TB3> INFO: enter test to run
[17:34:08.732] <TB3> INFO: test: fulltest no parameter change
[17:34:08.732] <TB3> INFO: running: fulltest
[17:34:08.732] <TB3> INFO: ######################################################################
[17:34:08.732] <TB3> INFO: PixTestFullTest::doTest()
[17:34:08.732] <TB3> INFO: ######################################################################
[17:34:08.733] <TB3> INFO: ######################################################################
[17:34:08.733] <TB3> INFO: PixTestAlive::doTest()
[17:34:08.733] <TB3> INFO: ######################################################################
[17:34:08.735] <TB3> INFO: ----------------------------------------------------------------------
[17:34:08.735] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:34:08.735] <TB3> INFO: ----------------------------------------------------------------------
[17:34:09.065] <TB3> INFO: Expecting 41600 events.
[17:34:13.348] <TB3> INFO: 41600 events read in total (3568ms).
[17:34:13.349] <TB3> INFO: Test took 4613ms.
[17:34:13.355] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:34:13.736] <TB3> INFO: PixTestAlive::aliveTest() done
[17:34:13.737] <TB3> INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 1 4 6 2 0 0 0 4 0 8
[17:34:13.738] <TB3> INFO: ----------------------------------------------------------------------
[17:34:13.738] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:34:13.738] <TB3> INFO: ----------------------------------------------------------------------
[17:34:14.055] <TB3> INFO: Expecting 41600 events.
[17:34:17.045] <TB3> INFO: 41600 events read in total (2275ms).
[17:34:17.045] <TB3> INFO: Test took 3305ms.
[17:34:17.045] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:34:17.046] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:34:17.448] <TB3> INFO: PixTestAlive::maskTest() done
[17:34:17.448] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:34:17.449] <TB3> INFO: ----------------------------------------------------------------------
[17:34:17.449] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:34:17.449] <TB3> INFO: ----------------------------------------------------------------------
[17:34:17.756] <TB3> INFO: Expecting 41600 events.
[17:34:22.061] <TB3> INFO: 41600 events read in total (3590ms).
[17:34:22.062] <TB3> INFO: Test took 4612ms.
[17:34:22.070] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:34:22.442] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[17:34:22.442] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:34:22.442] <TB3> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[17:34:22.442] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:34:22.442] <TB3> INFO: Decoding statistics:
[17:34:22.442] <TB3> INFO: General information:
[17:34:22.442] <TB3> INFO: 16bit words read: 0
[17:34:22.442] <TB3> INFO: valid events total: 0
[17:34:22.442] <TB3> INFO: empty events: 0
[17:34:22.442] <TB3> INFO: valid events with pixels: 0
[17:34:22.442] <TB3> INFO: valid pixel hits: 0
[17:34:22.442] <TB3> INFO: Event errors: 0
[17:34:22.442] <TB3> INFO: start marker: 0
[17:34:22.442] <TB3> INFO: stop marker: 0
[17:34:22.442] <TB3> INFO: overflow: 0
[17:34:22.442] <TB3> INFO: invalid 5bit words: 0
[17:34:22.442] <TB3> INFO: invalid XOR eye diagram: 0
[17:34:22.442] <TB3> INFO: TBM errors: 0
[17:34:22.442] <TB3> INFO: flawed TBM headers: 0
[17:34:22.442] <TB3> INFO: flawed TBM trailers: 0
[17:34:22.442] <TB3> INFO: event ID mismatches: 0
[17:34:22.442] <TB3> INFO: ROC errors: 0
[17:34:22.442] <TB3> INFO: missing ROC header(s): 0
[17:34:22.442] <TB3> INFO: misplaced readback start: 0
[17:34:22.442] <TB3> INFO: Pixel decoding errors: 0
[17:34:22.442] <TB3> INFO: pixel data incomplete: 0
[17:34:22.442] <TB3> INFO: pixel address: 0
[17:34:22.442] <TB3> INFO: pulse height fill bit: 0
[17:34:22.442] <TB3> INFO: buffer corruption: 0
[17:34:22.456] <TB3> INFO: ######################################################################
[17:34:22.456] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[17:34:22.456] <TB3> INFO: ######################################################################
[17:34:22.459] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[17:34:22.470] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[17:34:22.470] <TB3> INFO: run 1 of 1
[17:34:22.779] <TB3> INFO: Expecting 3120000 events.
[17:34:45.270] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 0 Number of ROCs (3) != Token Chain Length (8)
[17:34:45.270] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:34:45.270] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ec 80b1 4388 21 248c 4388 21 24ca 4388 21 24cf 4388 21 24cd 4388 21 24cb 4388 21 2488 4388 21 24ca 4388 21 24a2 e022 c000
[17:34:45.270] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e6 8000 4188 21 248c 4188 21 24cc 4188 21 24e0 4188 21 24ca 4188 21 24cb 4188 21 2489 4188 21 24c9 4188 21 24a3 e022 c000
[17:34:45.270] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e7 8040 4188 21 248c 4188 21 24cc 4188 21 24cf 4188 21 24ca 4188 21 24cc 4188 21 2488 4188 21 24cc 4188 21 24a2 e022 c000
[17:34:45.270] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e8 80b1 4188 21 248c 4188 21 24cd 4188 21 24e0 4188 21 24ca 4188 21 24ca 4188 21 2488 4188 21 24c9 4188 21 24a2 e022 c000
[17:34:45.270] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e9 80c0 4189 21 248a 4189 21 24cc 4389 21 e040 c085
[17:34:45.270] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ea 8000 4388 21 248c 4388 21 24cc 4388 21 24ce 4388 21 24ca 4388 21 24ca 4388 21 2489 4388 21 24c9 4388 21 24a2 e022 c000
[17:34:45.270] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0eb 8040 4389 21 248c 4389 21 24cc 4389 21 24ce 4389 21 24ca 4389 21 24ca 4389 21 2489 4389 21 24ca 4389 21 24a3 e022 c000
[17:35:09.663] <TB3> INFO: 989510 events read in total (46170ms).
[17:35:55.170] <TB3> INFO: 1961085 events read in total (91678ms).
[17:36:38.945] <TB3> INFO: 2962670 events read in total (135453ms).
[17:36:46.412] <TB3> INFO: 3120000 events read in total (142919ms).
[17:36:46.463] <TB3> INFO: Test took 143994ms.
[17:36:46.579] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:37:10.431] <TB3> INFO: PixTestBBMap::doTest() done with 1 decoding errors: , duration: 167 seconds
[17:37:10.431] <TB3> INFO: number of dead bumps (per ROC): 1 1 1 0 0 1 1 1 2 0 0 0 0 1 0 3
[17:37:10.431] <TB3> INFO: separation cut (per ROC): 86 75 79 85 85 86 72 83 78 81 82 80 82 80 83 85
[17:37:10.431] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:37:10.431] <TB3> INFO: Decoding statistics:
[17:37:10.431] <TB3> INFO: General information:
[17:37:10.431] <TB3> INFO: 16bit words read: 0
[17:37:10.431] <TB3> INFO: valid events total: 0
[17:37:10.431] <TB3> INFO: empty events: 0
[17:37:10.431] <TB3> INFO: valid events with pixels: 0
[17:37:10.431] <TB3> INFO: valid pixel hits: 0
[17:37:10.431] <TB3> INFO: Event errors: 0
[17:37:10.431] <TB3> INFO: start marker: 0
[17:37:10.431] <TB3> INFO: stop marker: 0
[17:37:10.431] <TB3> INFO: overflow: 0
[17:37:10.431] <TB3> INFO: invalid 5bit words: 0
[17:37:10.431] <TB3> INFO: invalid XOR eye diagram: 0
[17:37:10.431] <TB3> INFO: TBM errors: 0
[17:37:10.431] <TB3> INFO: flawed TBM headers: 0
[17:37:10.431] <TB3> INFO: flawed TBM trailers: 0
[17:37:10.431] <TB3> INFO: event ID mismatches: 0
[17:37:10.431] <TB3> INFO: ROC errors: 0
[17:37:10.431] <TB3> INFO: missing ROC header(s): 0
[17:37:10.431] <TB3> INFO: misplaced readback start: 0
[17:37:10.431] <TB3> INFO: Pixel decoding errors: 0
[17:37:10.431] <TB3> INFO: pixel data incomplete: 0
[17:37:10.431] <TB3> INFO: pixel address: 0
[17:37:10.431] <TB3> INFO: pulse height fill bit: 0
[17:37:10.431] <TB3> INFO: buffer corruption: 0
[17:37:10.543] <TB3> INFO: ######################################################################
[17:37:10.543] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[17:37:10.543] <TB3> INFO: ######################################################################
[17:37:10.543] <TB3> INFO: ----------------------------------------------------------------------
[17:37:10.543] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[17:37:10.543] <TB3> INFO: ----------------------------------------------------------------------
[17:37:10.543] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[17:37:10.554] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[17:37:10.554] <TB3> INFO: run 1 of 1
[17:37:10.941] <TB3> INFO: Expecting 26208000 events.
[17:37:44.108] <TB3> INFO: 1112000 events read in total (32452ms).
[17:38:15.897] <TB3> INFO: 2202900 events read in total (64241ms).
[17:38:47.973] <TB3> INFO: 3291500 events read in total (96317ms).
[17:39:20.366] <TB3> INFO: 4376500 events read in total (128710ms).
[17:39:52.184] <TB3> INFO: 5461000 events read in total (160528ms).
[17:40:24.180] <TB3> INFO: 6548050 events read in total (192524ms).
[17:40:56.076] <TB3> INFO: 7630150 events read in total (224420ms).
[17:41:28.951] <TB3> INFO: 8712350 events read in total (257295ms).
[17:42:01.090] <TB3> INFO: 9794150 events read in total (289434ms).
[17:42:32.622] <TB3> INFO: 10871600 events read in total (320966ms).
[17:43:03.772] <TB3> INFO: 11949300 events read in total (352116ms).
[17:43:12.410] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[17:43:12.411] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[17:43:12.411] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:43:12.411] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06b 8040 4388 349 26a4 4388 349 26c9 4388 349 26e7 4388 349 26ed 4388 349 26c4 4388 349 268e 4388 349 26cd 4388 349 26c0 e022 c000
[17:43:12.411] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a065 80c0 4188 349 26a4 4188 349 26c9 4188 349 26e8 4188 349 26ed 4188 349 26c5 4188 349 268e 4188 349 26ce 4188 349 26c0 e022 c000
[17:43:12.411] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a066 8000 4188 349 26a4 4188 349 26c8 4188 349 26e8 4188 349 2700 4188 349 26c4 4188 349 268f 4188 349 26ce 4188 349 26c0 e022 c000
[17:43:12.411] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a067 8040 4188 349 26a4 4188 349 26c9 4188 349 26e8 4188 349 26ed 4188 349 26c5 4188 349 268e 4188 349 26ce 4188 349 26c0 e022 c000
[17:43:12.411] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a068 80b1 4189 349 26a4 4389 349 26c9 419 2349 6e8 4389 349 26ed 4389 349 26c5 4389 349 268e 4389 349 26ce 4389 349 26c0 e022 c000
[17:43:12.411] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a069 80c0 4388 349 26a4 4388 349 26c9 4388 349 26e6 4388 349 26ee 4388 349 26c5 4388 349 268f 4388 349 26ce 4388 349 26ae e022 c000
[17:43:12.411] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06a 8000 4388 349 26a4 4388 349 26c8 4388 349 26e8 4388 349 26ed 4388 349 26c4 4388 349 268f 4388 349 26ce 4388 349 26c0 e022 c000
[17:43:12.411] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:43:12.411] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16b 8040 4388 349 26e8 4388 349 26e1 4388 349 26e1 4388 349 26cc 4388 349 26eb 4388 349 2722 4388 349 26c8 4388 349 26c6 e022 c000
[17:43:12.411] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a165 80c0 4188 349 26e8 4188 349 26e2 4188 349 26e2 4188 349 26cc 4188 349 26e9 4188 349 2722 4188 349 26c8 4188 349 26c4 e022 c000
[17:43:12.411] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a166 8000 4188 349 26e8 4188 349 26e2 4188 349 26e1 4188 349 26cc 4188 349 26e9 4188 349 2722 4188 349 26ca 4188 349 26c4 e022 c000
[17:43:12.411] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a167 8040 4188 349 26e8 4188 349 26e2 4188 349 26e2 4188 349 26cc 4188 349 26e9 4188 349 2722 4188 349 26c9 4188 349 26c5 e022 c000
[17:43:12.411] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a168 80b1 4189 349 26e8 4389 349 26e3 e5c 29a4 b71 4389 4389 349 26ea 4389 349 2722 4389 349 26c8 4389 349 26c6 e022 c000
[17:43:12.411] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a169 80c0 4388 349 26e8 4388 349 26e1 4388 349 26e2 4388 349 26cc 4388 349 26ea 4388 349 2722 4388 349 26c7 4388 349 26c6 e022 c000
[17:43:12.411] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a16a 8000 4388 349 26ea 4388 349 26e2 4388 349 26e2 4388 349 26cc 4388 349 26ea 4388 349 2721 4388 349 26c8 4388 349 26c4 e022 c000
[17:43:35.698] <TB3> INFO: 13024100 events read in total (384042ms).
[17:44:07.384] <TB3> INFO: 14084950 events read in total (415728ms).
[17:44:39.017] <TB3> INFO: 15144200 events read in total (447361ms).
[17:45:10.931] <TB3> INFO: 16200350 events read in total (479275ms).
[17:45:42.155] <TB3> INFO: 17253700 events read in total (510499ms).
[17:46:13.753] <TB3> INFO: 18306950 events read in total (542097ms).
[17:46:45.472] <TB3> INFO: 19357400 events read in total (573816ms).
[17:46:53.836] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L477> Channel 0 has NoTokenPass but 3 ROCs were found
[17:46:53.836] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[17:46:53.836] <TB3> ERROR: <datapipe.cc/CheckEventID:L461> Channel 0 Event ID mismatch: local ID (161) != TBM ID (47)
[17:46:53.836] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 0 Number of ROCs (0) != Token Chain Length (8)
[17:46:53.836] <TB3> ERROR: <datapipe.cc/CheckEventID:L461> Channel 0 Event ID mismatch: local ID (48) != TBM ID (161)
[17:46:53.836] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:46:53.836] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a3 8040 4388 4389 4388 4389 4388 4389 4388 4389 e022 c000
[17:46:53.836] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a19d 80c0 4189 4189 4189 4189 4189 4189 4189 4189 e022 c000
[17:46:53.836] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a19e 8000 4188 4188 4188 4188 4188 4188 4188 4188 e022 c000
[17:46:53.836] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a19f 8040 418a 418a 418a 418a 418a 418a 418a 418a e022 c000
[17:46:53.836] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a0 80b1 4189 4189 4389 4389 4389 7ff 4389 4389 e022 c000
[17:46:53.836] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a1 80c0 4388 4388 4388 4388 4389 4389 4389 4389 e022 c000
[17:46:53.836] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1a2 8000 4388 4388 4389 4389 4388 4388 4389 4389 e022 c000
[17:46:53.836] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:46:53.836] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a4 80b1 4388 4388 4388 4388 4388 4388 4388 4388 e022 c000
[17:46:53.836] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09f 8040 418a 418a 418a 418a 418a 418a 418a 418a e022 c000
[17:46:53.836] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a0a0 80b1 4188 4188 4388 4388 4388
[17:46:53.836] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a02f 8087 e022 c000
[17:46:53.836] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a1 80c0 4388 4388 4388 4388 4389 4389 4389 4389 e022 c000
[17:46:53.836] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a2 8000 4388 4388 4389 4389 4388 4388 4389 4389 e022 c000
[17:46:53.836] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a3 8040 4388 4389 4388 4389 4388 4389 4388 4389 e022 c000
[17:47:16.269] <TB3> CRITICAL: <hal.cc/condenseTriggers:L1899> Data size does not correspond to 50 triggers! Aborting data processing!
[17:47:16.288] <TB3> INFO: 19357400 events read in total (604632ms).
[17:47:48.611] <TB3> INFO: 20407450 events read in total (636955ms).
[17:47:56.971] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[17:47:56.971] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[17:47:56.971] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:47:56.971] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a068 80b1 4388 6cd 24aa 4388 6cd 24cc 4388 6cd 24e6 4388 6cd 24cd 4388 6cd 24ca 4388 6cd 24a2 4388 6cd 24cf 4388 6cd 24c0 e022 c000
[17:47:56.971] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a062 8000 4188 6cd 24a9 4188 6cd 24cc 4189 6cd 24e7 4189 6cd 24cd 4188 6cd 24c8 4188 6cd 24a2 4189 6cd 24e0 4189 6cd 24c0 e022 c000
[17:47:56.971] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a063 8040 4188 6cd 24a9 4189 6cd 24ca 4188 6cd 24e6 4189 6cd 24cd 4188 6cd 24c9 4189 6cd 24a2 4188 6cd 24cf 4189 6cd 24c1 e022 c000
[17:47:56.971] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a064 80b1 4188 6cd 24a8 4188 6cd 24cc 4188 6cd 24e8 4188 6cd 24cd 4188 6cd 24c9 4188 6cd 24a2 4188 6cd 24cf 4188 6cd 24c1 e022 c000
[17:47:56.971] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a065 80c0 4388 6cd 24a9 9f8 26cd 4cc 4388 6cd 24e8 4388 6cd 24cf 4388 6cd 24c9 4388 6cd 24a3 4388 6cd 24cf 4388 6cd 24c0 e022 c000
[17:47:56.971] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a066 8000 4388 6cd 24a9 4388 6cd 24ca 4388 6cd 24e6 4388 6cd 24cd 4388 6cd 24ca 4388 6cd 24a2 4388 6cd 24cf 4388 6cd 24c2 e022 c000
[17:47:56.971] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a067 8040 4388 6cd 24a9 4388 6cd 24cc 4388 6cd 24e6 4388 6cd 24cd 4388 6cd 24c8 4388 6cd 24a3 4388 6cd 24cf 4388 6cd 24c0 e022 c000
[17:47:56.971] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[17:47:56.971] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a168 80b1 4388 6cd 2500 4388 6cd 24e2 4388 6cd 24ca 4388 6cd 24e5 4388 6cd 24e6 4388 6cd 2509 4388 6cd 24c9 4388 6cd 24c8 e022 c000
[17:47:56.971] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a162 8000 4188 6cd 2500 4188 6cd 24e1 4189 6cd 24c9 4189 6cd 24e5 4188 6cd 24e5 4188 6cd 2507 4189 6cd 24ca 4189 6cd 24c8 e022 c000
[17:47:56.971] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a163 8040 4188 6cd 2500 4189 6cd 24e1 4188 6cd 24c9 4189 6cd 24e4 4188 6cd 24e6 4189 6cd 250a 4188 6cd 24cb 4189 6cd 24c7 e022 c000
[17:47:56.971] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a164 80b1 4188 6cd 2500 4188 6cd 24e1 4188 6cd 24c9 4188 6cd 24e5 4188 6cd 24e6 4188 6cd 2509 4188 6cd 24c8 4188 6cd 24c7 e022 c000
[17:47:56.971] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a165 80c0 4388 6cd 2500 8fc 2366 a71 4388 4388 6cd 24e5 4388 6cd 24e6 4388 6cd 2508 4388 6cd 24ca 4388 6cd 24c8 e022 c000
[17:47:56.971] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a166 8000 4388 6cd 2500 4388 6cd 24e1 4388 6cd 24c9 4388 6cd 24e5 4388 6cd 24e6 4388 6cd 250a 4388 6cd 24c9 4388 6cd 24c8 e022 c000
[17:47:56.971] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a167 8040 4388 6cd 2500 4388 6cd 24e3 4388 6cd 24c9 4388 6cd 24e4 4388 6cd 24e7 4388 6cd 2509 4388 6cd 24cc 4388 6cd 24c7 e022 c000
[17:48:20.572] <TB3> INFO: 21453900 events read in total (668916ms).
[17:48:51.451] <TB3> INFO: 22500950 events read in total (699795ms).
[17:49:22.530] <TB3> INFO: 23550750 events read in total (730874ms).
[17:49:55.358] <TB3> INFO: 24598050 events read in total (763702ms).
[17:50:13.632] <TB3> INFO: 25160150 events read in total (781976ms).
[17:50:13.633] <TB3> CRITICAL: <hal.cc/MultiRocAllPixelsDacScan:L958> Incomplete DAQ data readout! Missing 20957 Events.
[17:50:13.949] <TB3> INFO: Expecting 26208000 events.
[17:50:47.036] <TB3> INFO: 1112350 events read in total (32372ms).
[17:51:19.056] <TB3> INFO: 2203150 events read in total (64392ms).
[17:51:50.709] <TB3> INFO: 3292050 events read in total (96045ms).
[17:52:22.106] <TB3> INFO: 4377150 events read in total (127442ms).
[17:52:53.533] <TB3> INFO: 5461800 events read in total (158869ms).
[17:53:25.081] <TB3> INFO: 6549350 events read in total (190417ms).
[17:53:56.520] <TB3> INFO: 7632200 events read in total (221856ms).
[17:54:29.168] <TB3> INFO: 8714100 events read in total (254504ms).
[17:55:01.079] <TB3> INFO: 9795100 events read in total (286415ms).
[17:55:32.874] <TB3> INFO: 10872300 events read in total (318210ms).
[17:56:05.742] <TB3> INFO: 11950000 events read in total (351078ms).
[17:56:37.642] <TB3> INFO: 13025350 events read in total (382978ms).
[17:57:09.618] <TB3> INFO: 14085600 events read in total (414954ms).
[17:57:41.667] <TB3> INFO: 15144850 events read in total (447003ms).
[17:58:12.144] <TB3> INFO: 16201000 events read in total (477480ms).
[17:58:45.389] <TB3> INFO: 17254200 events read in total (510725ms).
[17:59:17.522] <TB3> INFO: 18307500 events read in total (542858ms).
[17:59:49.548] <TB3> INFO: 19357800 events read in total (574884ms).
[18:00:22.025] <TB3> INFO: 20405500 events read in total (607361ms).
[18:00:30.353] <TB3> WARNING: Channel 0 ROC 7: Readback start marker after 2 readouts!
[18:00:30.353] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[18:00:30.353] <TB3> ERROR: <datapipe.cc/CheckEventID:L461> Channel 1 Event ID mismatch: local ID (66) != TBM ID (21)
[18:00:30.353] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 1 Number of ROCs (0) != Token Chain Length (8)
[18:00:30.353] <TB3> ERROR: <datapipe.cc/CheckEventID:L461> Channel 1 Event ID mismatch: local ID (22) != TBM ID (66)
[18:00:30.353] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[18:00:30.353] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a044 80b1 4388 691 28c4 4388 691 28ca 4388 691 28ed 4388 691 28e0 4388 691 28e0 4388 691 28a0 4388 691 28ce 4388 691 28c2 e022 c000
[18:00:30.353] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a03e 8000 4188 691 28c4 4188 691 28ca 4188 691 28ec 4188 691 28e0 4188 691 28e0 4188 691 28a2 4188 691 28cd 4188 691 28c2 e022 c000
[18:00:30.353] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a03f 8040 418a 691 28c2 418a 691 28c9 418a 691 28ed 418a 691 28e1 418a 691 28ce 418a 691 28a0 418a 691 28cd 418a 691 28c4 e022 c000
[18:00:30.353] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a040 80b1 4188 691 28c4 4188 691 28c9 4188 691 28eb 4188 691 28e2 4188 691 28e0 4188 691 28a1 4188 691 28ce 4188 691 28c2 e022 c000
[18:00:30.353] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a041 80c0 4188 691 28c2 4188 691 28c9 4188 691 28ea 4188 691 28e2 4189 691 28e1 4389 691 28a1 4389 438b 59b 2ff9 691 28c2 e022 c000
[18:00:30.353] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a042 8000 4388 691 28c2 4388 691 28ca 4389 691 28ea 4389 691 28cf 4388 691 28ce 4388 691 28a0 4389 691 28cc 4389 691 28c4 e022 c000
[18:00:30.353] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a043 8040 4388 691 28c2 4389 691 28cc 4388 691 28ea 4389 691 28e1 4388 691 28cf 4389 691 288f 4388 691 28cd 4389 691 28c3 e022 c000
[18:00:30.353] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[18:00:30.353] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a145 80c0 4388 691 2902 4388 691 28e8 4388 691 28cf 4388 691 28e0 4388 691 28ea 4388 691 2922 4388 691 28cc 4388 691 28e1 e022 c000
[18:00:30.353] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a140 80b1 4189 691 2902 4189 691 28e5 4189 691 28cf 4189 691 28cd 4189 691 28ec 4189 691 2922 4189 691 28cc 4189 691 28e0 e022 c000
[18:00:30.353] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 2 ====== a141 80c0 4188 691 2904 4188 691 28e7 4188 691 28e0 4188 691 28e0 4189 691 28ec 4389 691 2922 4389
[18:00:30.353] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a115 8020 3cb 248c e022 c000
[18:00:30.353] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a142 8000 4388 691 2904 4388 691 28e8 4389 691 28e2 4389 691 28e2 4388 691 28eb 4388 691 2924 4389 691 28ce 4389 691 28e0 e022 c000
[18:00:30.353] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a143 8040 4388 691 2902 4389 691 28e7 4388 691 28cf 4389 691 28ce 4388 691 28ea 4389 691 2923 4388 691 28cd 4389 691 28e2 e022 c000
[18:00:30.353] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a144 80b1 4388 691 2902 4388 691 28e7 4388 691 28e1 4388 691 28ce 4388 691 28ec 4388 691 2923 4388 691 28cc 4388 691 28e2 e022 c000
[18:00:30.353] <TB3> WARNING: Channel 0 ROC 7: Readback start marker after 14 readouts!
[18:00:30.354] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[18:00:30.354] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a052 8000 4388 691 28c2 4388 691 28ca 4389 691 28ec 4389 691 28e2 4388 691 28ce 4388 691 28a1 4389 691 28cd 4389 691 28c4 e022 c000
[18:00:30.354] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a04c 80b1 4388 691 28c2 4388 691 28c9 4388 691 28ec 4388 691 28e1 4388 691 28e1 4388 691 28a1 4388 691 28ce 4388 691 28c4 e022 c000
[18:00:30.354] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a04d 80c0 4388 691 28c2 4388 691 28c9 4388 691 28ea 4388 691 28e2 4388 691 28e0 4388 691 28a0 4388 691 28cd 4388 691 28c2 e022 c000
[18:00:30.354] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a04e 8000 4388 691 28c2 4388 691 28cc 4388 691 28eb 4388 691 28e1 4388 691 28ce 4388 691 28a1 4388 691 28cd 4388 691 28c3 e022 c000
[18:00:30.354] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a04f 8040 438b 691 28c1 438b 691 28ca 438b 691 28eb 438b 691 28e0 438b 691 28e0 438b 691 28a1 438b 691 28cd 438b 691 28c4 e022 c000
[18:00:30.354] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a050 80b1 4388 691 28c2 4388 691 28c9 4388 691 28ec 4388 691 28e0 4388 691 28cf 4388 691 28a1 4388 691 28ce 4388 691 28c3 e022 c000
[18:00:30.354] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a051 80c0 4388 691 28c2 4388 691 28ca 4388 691 28eb 4388 691 28e2 4389 691 28cf 4389 691 28a1 4389 691 28ce 4389 691 28c3 e022 c000
[18:00:55.022] <TB3> CRITICAL: <hal.cc/condenseTriggers:L1899> Data size does not correspond to 50 triggers! Aborting data processing!
[18:00:55.045] <TB3> INFO: 20405500 events read in total (640381ms).
[18:01:27.091] <TB3> INFO: 21451800 events read in total (672427ms).
[18:01:35.564] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 0 Number of ROCs (6) != Token Chain Length (8)
[18:01:35.564] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L488> Channel 1 Number of ROCs (6) != Token Chain Length (8)
[18:01:35.564] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[18:01:35.564] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ff 8040 438a 70b 20a6 438a 70b 20c9 438a 70b 20e6 438a 70b 20e8 438a 70b 20ca 438a 70b 208f 438a 70b 20ca 438a 70b 20c2 e022 c000
[18:01:35.564] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f9 80c0 4189 70b 20a8 4189 70b 20c8 4189 70b 20e6 4189 70b 20e7 4189 70b 20c9 4189 70b 208e 4189 70b 20cc 4189 70b 20c2 e022 c000
[18:01:35.564] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fa 8000 4189 70b 20a6 4189 70b 20ca 4189 70b 20e6 4189 70b 20e6 4189 70b 20ca 4189 70b 208f 4189 70b 20ca 4189 70b 20c2 e022 c000
[18:01:35.564] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fb 8040 4188 70b 20a6 4188 70b 20c9 4188 70b 20e6 4188 70b 20e7 4188 70b 20ca 4188 70b 208f 4188 70b 20ca 4188 70b 20c2 e022 c000
[18:01:35.564] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fc 80b1 4189 70b 20a8 4189 70b 20c9 4189 70b 20e8 4189 70b 20e6 4189 70b 20ca 5389 170b 308e 7e0 203e 39e 20b9 70b 20c2 e022 c000
[18:01:35.564] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fd 80c0 4389 70b 20a6 4389 70b 20c9 4389 70b 20e6 4389 70b 20e6 4389 70b 20ca 4389 70b 208f 4389 70b 20cc 4389 70b 20c2 e022 c000
[18:01:35.564] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0fe 8000 4389 70b 20a6 4389 70b 20c9 4389 70b 20e4 4389 70b 20e8 4389 70b 20c9 4389 70b 208e 4389 70b 20cc 4389 70b 20c2 e022 c000
[18:01:35.564] <TB3> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[18:01:35.564] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1ff 8040 438a 70b 20e6 438a 70b 20e0 438a 70b 20cc 438a 70b 20c9 438a 70b 20ea 438a 70b 210a 438a 70b 20c8 438a 70b 20c4 e022 c000
[18:01:35.564] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1f9 80c0 4189 70b 20e6 4189 70b 20ce 4189 70b 20cc 4189 70b 20c9 4189 70b 20e9 4189 70b 210a 4189 70b 20c9 4189 70b 20c5 e022 c000
[18:01:35.564] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fa 8000 4189 70b 20e6 4189 70b 20ce 4189 70b 20cc 4189 70b 20cc 4189 70b 20e9 4189 70b 210a 4189 70b 20c9 4189 70b 20c4 e022 c000
[18:01:35.564] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fb 8040 4188 70b 20e4 4188 70b 20e0 4188 70b 20cc 4188 70b 20c9 4188 70b 20ea 4188 70b 210a 4188 70b 20ca 4188 70b 20c6 e022 c000
[18:01:35.564] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fc 80b1 4189 70b 20e4 4189 70b 20e0 4189 70b 20cb 4189 70b 20ca 4189 70b 20ea 5389 170b 310a 7e0 2155 554 24fc b85 2862 e022 c000
[18:01:35.564] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fd 80c0 4389 70b 20e5 4389 70b 20e0 4389 70b 20cd 4389 70b 20cb 4389 70b 20ec 4389 70b 210a 4389 70b 20c9 4389 70b 20c4 e022 c000
[18:01:35.564] <TB3> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a1fe 8000 4389 70b 20e6 4389 70b 20cf 4389 70b 20cc 4389 70b 20c9 4389 70b 20e8 4389 70b 210a 4389 70b 20c9 4389 70b 20c5 e022 c000
[18:01:58.218] <TB3> INFO: 22498800 events read in total (703554ms).
[18:02:31.371] <TB3> INFO: 23548350 events read in total (736707ms).
[18:03:04.801] <TB3> INFO: 24595750 events read in total (770137ms).
[18:03:21.405] <TB3> INFO: 25158200 events read in total (786741ms).
[18:03:21.406] <TB3> CRITICAL: <hal.cc/MultiRocAllPixelsDacScan:L958> Incomplete DAQ data readout! Missing 20996 Events.
[18:03:21.738] <TB3> INFO: Expecting 26208000 events.
[18:03:54.930] <TB3> INFO: 1112200 events read in total (32477ms).
[18:04:27.810] <TB3> INFO: 2202900 events read in total (65357ms).
[18:04:59.199] <TB3> INFO: 3292050 events read in total (96746ms).
[18:05:30.527] <TB3> INFO: 4377200 events read in total (128074ms).
[18:06:01.079] <TB3> INFO: 5461650 events read in total (158626ms).
[18:06:33.411] <TB3> INFO: 6549200 events read in total (190958ms).
[18:07:05.239] <TB3> INFO: 7632050 events read in total (222786ms).
[18:07:37.276] <TB3> INFO: 8714050 events read in total (254823ms).
[18:08:09.607] <TB3> INFO: 9795100 events read in total (287154ms).
[18:08:41.194] <TB3> INFO: 10872300 events read in total (318741ms).
[18:09:12.913] <TB3> INFO: 11949950 events read in total (350460ms).
[18:09:44.812] <TB3> INFO: 13025100 events read in total (382359ms).
[18:10:16.012] <TB3> INFO: 14085550 events read in total (413559ms).
[18:10:47.251] <TB3> INFO: 15144850 events read in total (444798ms).
[18:11:19.161] <TB3> INFO: 16201050 events read in total (476708ms).
[18:11:51.062] <TB3> INFO: 17254200 events read in total (508609ms).
[18:12:23.909] <TB3> INFO: 18307450 events read in total (541456ms).
[18:12:56.765] <TB3> INFO: 19357850 events read in total (574312ms).
[18:13:28.489] <TB3> INFO: 20405550 events read in total (606036ms).
[18:14:00.151] <TB3> INFO: 21455550 events read in total (637698ms).
[18:14:32.271] <TB3> INFO: 22501800 events read in total (669818ms).
[18:15:03.778] <TB3> INFO: 23548700 events read in total (701325ms).
[18:15:35.598] <TB3> INFO: 24598350 events read in total (733145ms).
[18:16:06.596] <TB3> INFO: 25645700 events read in total (764143ms).
[18:16:22.764] <TB3> INFO: 26208000 events read in total (780311ms).
[18:16:22.794] <TB3> INFO: Test took 781375ms.
[18:16:22.872] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:16:23.034] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:16:24.483] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:16:26.118] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:16:27.761] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:16:29.198] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:16:30.716] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:16:32.203] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:16:33.699] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:16:35.133] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:16:36.572] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:16:38.012] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:16:39.434] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:16:40.879] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:16:42.336] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:16:43.836] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:16:45.206] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[18:16:46.656] <TB3> INFO: PixTestScurves::scurves() done
[18:16:46.656] <TB3> INFO: Vcal mean: 91.31 78.09 79.42 91.18 96.86 100.66 75.10 90.66 87.38 91.29 96.60 95.07 81.71 77.68 90.39 101.32
[18:16:46.656] <TB3> INFO: Vcal RMS: 6.33 4.16 3.93 5.70 9.00 6.04 4.47 6.65 6.62 5.70 6.40 5.92 4.53 5.13 5.71 8.44
[18:16:46.657] <TB3> INFO: PixTestScurves::fullTest() done, duration: 2376 seconds
[18:16:46.657] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:16:46.657] <TB3> INFO: Decoding statistics:
[18:16:46.657] <TB3> INFO: General information:
[18:16:46.657] <TB3> INFO: 16bit words read: 0
[18:16:46.657] <TB3> INFO: valid events total: 0
[18:16:46.657] <TB3> INFO: empty events: 0
[18:16:46.657] <TB3> INFO: valid events with pixels: 0
[18:16:46.657] <TB3> INFO: valid pixel hits: 0
[18:16:46.657] <TB3> INFO: Event errors: 0
[18:16:46.657] <TB3> INFO: start marker: 0
[18:16:46.657] <TB3> INFO: stop marker: 0
[18:16:46.657] <TB3> INFO: overflow: 0
[18:16:46.657] <TB3> INFO: invalid 5bit words: 0
[18:16:46.657] <TB3> INFO: invalid XOR eye diagram: 0
[18:16:46.657] <TB3> INFO: TBM errors: 0
[18:16:46.657] <TB3> INFO: flawed TBM headers: 0
[18:16:46.657] <TB3> INFO: flawed TBM trailers: 0
[18:16:46.657] <TB3> INFO: event ID mismatches: 0
[18:16:46.657] <TB3> INFO: ROC errors: 0
[18:16:46.657] <TB3> INFO: missing ROC header(s): 0
[18:16:46.657] <TB3> INFO: misplaced readback start: 0
[18:16:46.657] <TB3> INFO: Pixel decoding errors: 0
[18:16:46.657] <TB3> INFO: pixel data incomplete: 0
[18:16:46.657] <TB3> INFO: pixel address: 0
[18:16:46.657] <TB3> INFO: pulse height fill bit: 0
[18:16:46.657] <TB3> INFO: buffer corruption: 0
[18:16:46.728] <TB3> INFO: ######################################################################
[18:16:46.728] <TB3> INFO: PixTestTrim::doTest()
[18:16:46.728] <TB3> INFO: ######################################################################
[18:16:46.729] <TB3> INFO: ----------------------------------------------------------------------
[18:16:46.729] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[18:16:46.729] <TB3> INFO: ----------------------------------------------------------------------
[18:16:46.813] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[18:16:46.813] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[18:16:46.821] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[18:16:46.821] <TB3> INFO: run 1 of 1
[18:16:47.126] <TB3> INFO: Expecting 6281600 events.
[18:17:31.796] <TB3> INFO: 1422540 events read in total (43955ms).
[18:18:14.949] <TB3> INFO: 2832850 events read in total (87108ms).
[18:18:59.348] <TB3> INFO: 4248750 events read in total (131507ms).
[18:19:42.857] <TB3> INFO: 5674700 events read in total (175017ms).
[18:20:01.191] <TB3> INFO: 6281600 events read in total (193350ms).
[18:20:01.221] <TB3> INFO: Test took 194400ms.
[18:20:01.269] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:20:20.211] <TB3> INFO: ROC 0 VthrComp = 93
[18:20:20.212] <TB3> INFO: ROC 1 VthrComp = 80
[18:20:20.212] <TB3> INFO: ROC 2 VthrComp = 83
[18:20:20.212] <TB3> INFO: ROC 3 VthrComp = 94
[18:20:20.212] <TB3> INFO: ROC 4 VthrComp = 88
[18:20:20.212] <TB3> INFO: ROC 5 VthrComp = 100
[18:20:20.212] <TB3> INFO: ROC 6 VthrComp = 77
[18:20:20.212] <TB3> INFO: ROC 7 VthrComp = 93
[18:20:20.212] <TB3> INFO: ROC 8 VthrComp = 88
[18:20:20.212] <TB3> INFO: ROC 9 VthrComp = 94
[18:20:20.212] <TB3> INFO: ROC 10 VthrComp = 96
[18:20:20.212] <TB3> INFO: ROC 11 VthrComp = 96
[18:20:20.213] <TB3> INFO: ROC 12 VthrComp = 87
[18:20:20.213] <TB3> INFO: ROC 13 VthrComp = 83
[18:20:20.213] <TB3> INFO: ROC 14 VthrComp = 93
[18:20:20.213] <TB3> INFO: ROC 15 VthrComp = 96
[18:20:20.213] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[18:20:20.213] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[18:20:20.220] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[18:20:20.220] <TB3> INFO: run 1 of 1
[18:20:20.523] <TB3> INFO: Expecting 6281600 events.
[18:20:56.615] <TB3> INFO: 894480 events read in total (35377ms).
[18:21:31.255] <TB3> INFO: 1784260 events read in total (70017ms).
[18:22:07.679] <TB3> INFO: 2674850 events read in total (106441ms).
[18:22:43.498] <TB3> INFO: 3560400 events read in total (142260ms).
[18:23:18.710] <TB3> INFO: 4438710 events read in total (177472ms).
[18:23:55.291] <TB3> INFO: 5312720 events read in total (214053ms).
[18:24:31.645] <TB3> INFO: 6187680 events read in total (250407ms).
[18:24:35.789] <TB3> INFO: 6281600 events read in total (254551ms).
[18:24:35.853] <TB3> INFO: Test took 255632ms.
[18:24:36.007] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:25:01.085] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 61.2185 for pixel 18/4 mean/min/max = 46.5791/31.919/61.2392
[18:25:01.085] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 57.4246 for pixel 49/6 mean/min/max = 44.9312/32.3251/57.5373
[18:25:01.086] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 56.2311 for pixel 51/11 mean/min/max = 44.262/32.2416/56.2825
[18:25:01.086] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 58.8994 for pixel 21/68 mean/min/max = 45.3754/31.7601/58.9907
[18:25:01.086] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 69.3229 for pixel 1/11 mean/min/max = 50.9963/32.6257/69.3669
[18:25:01.087] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 61.9229 for pixel 7/4 mean/min/max = 46.7602/31.2893/62.2311
[18:25:01.087] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 59.6436 for pixel 0/0 mean/min/max = 47.6963/35.7244/59.6682
[18:25:01.087] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 60.9469 for pixel 17/15 mean/min/max = 46.5275/32.0941/60.9608
[18:25:01.087] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 61.6982 for pixel 24/9 mean/min/max = 47.1911/32.6229/61.7592
[18:25:01.087] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 60.1062 for pixel 23/3 mean/min/max = 46.2152/32.2843/60.146
[18:25:01.088] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 61.5981 for pixel 19/23 mean/min/max = 46.846/31.8187/61.8733
[18:25:01.088] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 60.7723 for pixel 21/10 mean/min/max = 46.1339/31.4649/60.8029
[18:25:01.088] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 57.4533 for pixel 25/2 mean/min/max = 44.8337/32.1162/57.5513
[18:25:01.088] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 58.3231 for pixel 51/76 mean/min/max = 45.1129/31.7861/58.4396
[18:25:01.089] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 59.807 for pixel 25/2 mean/min/max = 45.9724/32.1331/59.8117
[18:25:01.089] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 64.3357 for pixel 0/62 mean/min/max = 47.9953/31.4385/64.5522
[18:25:01.089] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:25:01.220] <TB3> INFO: Expecting 514560 events.
[18:25:11.532] <TB3> INFO: 514560 events read in total (9597ms).
[18:25:11.536] <TB3> INFO: Expecting 514560 events.
[18:25:21.475] <TB3> INFO: 514560 events read in total (9268ms).
[18:25:21.481] <TB3> INFO: Expecting 514560 events.
[18:25:31.262] <TB3> INFO: 514560 events read in total (9104ms).
[18:25:31.270] <TB3> INFO: Expecting 514560 events.
[18:25:41.191] <TB3> INFO: 514560 events read in total (9254ms).
[18:25:41.201] <TB3> INFO: Expecting 514560 events.
[18:25:51.093] <TB3> INFO: 514560 events read in total (9226ms).
[18:25:51.106] <TB3> INFO: Expecting 514560 events.
[18:26:00.803] <TB3> INFO: 514560 events read in total (9028ms).
[18:26:00.817] <TB3> INFO: Expecting 514560 events.
[18:26:10.841] <TB3> INFO: 514560 events read in total (9359ms).
[18:26:10.857] <TB3> INFO: Expecting 514560 events.
[18:26:20.445] <TB3> INFO: 514560 events read in total (8922ms).
[18:26:20.462] <TB3> INFO: Expecting 514560 events.
[18:26:30.170] <TB3> INFO: 514560 events read in total (9049ms).
[18:26:30.189] <TB3> INFO: Expecting 514560 events.
[18:26:39.772] <TB3> INFO: 514560 events read in total (8924ms).
[18:26:39.793] <TB3> INFO: Expecting 514560 events.
[18:26:49.629] <TB3> INFO: 514560 events read in total (9184ms).
[18:26:49.652] <TB3> INFO: Expecting 514560 events.
[18:26:59.715] <TB3> INFO: 514560 events read in total (9418ms).
[18:26:59.744] <TB3> INFO: Expecting 514560 events.
[18:27:09.690] <TB3> INFO: 514560 events read in total (9306ms).
[18:27:09.716] <TB3> INFO: Expecting 514560 events.
[18:27:20.104] <TB3> INFO: 514560 events read in total (9738ms).
[18:27:20.132] <TB3> INFO: Expecting 514560 events.
[18:27:30.201] <TB3> INFO: 514560 events read in total (9425ms).
[18:27:30.232] <TB3> INFO: Expecting 514560 events.
[18:27:39.845] <TB3> INFO: 514560 events read in total (8974ms).
[18:27:39.876] <TB3> INFO: Test took 158787ms.
[18:27:40.901] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[18:27:40.909] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[18:27:40.909] <TB3> INFO: run 1 of 1
[18:27:41.215] <TB3> INFO: Expecting 6281600 events.
[18:28:16.917] <TB3> INFO: 873390 events read in total (34987ms).
[18:28:52.231] <TB3> INFO: 1741960 events read in total (70301ms).
[18:29:27.553] <TB3> INFO: 2611000 events read in total (105623ms).
[18:30:03.438] <TB3> INFO: 3475650 events read in total (141508ms).
[18:30:38.027] <TB3> INFO: 4332460 events read in total (176098ms).
[18:31:13.845] <TB3> INFO: 5185770 events read in total (211915ms).
[18:31:49.967] <TB3> INFO: 6038300 events read in total (248037ms).
[18:32:00.299] <TB3> INFO: 6281600 events read in total (258369ms).
[18:32:00.360] <TB3> INFO: Test took 259451ms.
[18:32:00.504] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:32:26.602] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.016973 .. 255.000000
[18:32:26.700] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[18:32:26.709] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[18:32:26.709] <TB3> INFO: run 1 of 1
[18:32:27.036] <TB3> INFO: Expecting 10649600 events.
[18:33:02.504] <TB3> INFO: 827460 events read in total (34753ms).
[18:33:37.538] <TB3> INFO: 1654550 events read in total (69787ms).
[18:34:12.641] <TB3> INFO: 2481500 events read in total (104890ms).
[18:34:47.881] <TB3> INFO: 3308630 events read in total (140130ms).
[18:35:23.643] <TB3> INFO: 4135720 events read in total (175892ms).
[18:35:57.684] <TB3> INFO: 4962850 events read in total (209933ms).
[18:36:31.598] <TB3> INFO: 5789420 events read in total (243847ms).
[18:37:05.599] <TB3> INFO: 6615660 events read in total (277848ms).
[18:37:40.391] <TB3> INFO: 7440980 events read in total (312640ms).
[18:38:14.877] <TB3> INFO: 8265720 events read in total (347126ms).
[18:38:50.116] <TB3> INFO: 9089880 events read in total (382365ms).
[18:39:25.015] <TB3> INFO: 9914440 events read in total (417264ms).
[18:39:56.112] <TB3> INFO: 10649600 events read in total (448361ms).
[18:39:56.223] <TB3> INFO: Test took 449515ms.
[18:39:56.500] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:40:26.524] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 0.079257 .. 91.618219
[18:40:26.622] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 0 .. 101 (-1/-1) hits flags = 528 (plus default)
[18:40:26.631] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[18:40:26.631] <TB3> INFO: run 1 of 1
[18:40:26.966] <TB3> INFO: Expecting 4243200 events.
[18:41:04.596] <TB3> INFO: 951570 events read in total (36915ms).
[18:41:40.990] <TB3> INFO: 1903130 events read in total (73309ms).
[18:42:19.179] <TB3> INFO: 2853760 events read in total (111498ms).
[18:42:55.774] <TB3> INFO: 3803900 events read in total (148093ms).
[18:43:12.867] <TB3> INFO: 4243200 events read in total (165186ms).
[18:43:12.898] <TB3> INFO: Test took 166267ms.
[18:43:12.979] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:43:32.764] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 2.471389 .. 75.039678
[18:43:32.864] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 2 .. 85 (-1/-1) hits flags = 528 (plus default)
[18:43:32.873] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[18:43:32.873] <TB3> INFO: run 1 of 1
[18:43:33.208] <TB3> INFO: Expecting 3494400 events.
[18:44:11.432] <TB3> INFO: 988750 events read in total (37509ms).
[18:44:47.775] <TB3> INFO: 1976940 events read in total (73853ms).
[18:45:24.604] <TB3> INFO: 2964530 events read in total (110682ms).
[18:45:45.715] <TB3> INFO: 3494400 events read in total (131792ms).
[18:45:45.750] <TB3> INFO: Test took 132878ms.
[18:45:45.825] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:46:03.344] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 2.471389 .. 51.307415
[18:46:03.426] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 2 .. 61 (-1/-1) hits flags = 528 (plus default)
[18:46:03.434] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[18:46:03.434] <TB3> INFO: run 1 of 1
[18:46:03.740] <TB3> INFO: Expecting 2496000 events.
[18:46:43.289] <TB3> INFO: 1125570 events read in total (38834ms).
[18:47:23.764] <TB3> INFO: 2250590 events read in total (79309ms).
[18:47:32.730] <TB3> INFO: 2496000 events read in total (88275ms).
[18:47:32.743] <TB3> INFO: Test took 89310ms.
[18:47:32.779] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:47:47.099] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[18:47:47.099] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[18:47:47.106] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[18:47:47.106] <TB3> INFO: run 1 of 1
[18:47:47.410] <TB3> INFO: Expecting 1705600 events.
[18:48:27.715] <TB3> INFO: 1075750 events read in total (39590ms).
[18:48:51.192] <TB3> INFO: 1705600 events read in total (63067ms).
[18:48:51.208] <TB3> INFO: Test took 64102ms.
[18:48:51.239] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:49:05.447] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C0.dat
[18:49:05.447] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C1.dat
[18:49:05.447] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C2.dat
[18:49:05.447] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C3.dat
[18:49:05.448] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C4.dat
[18:49:05.448] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C5.dat
[18:49:05.448] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C6.dat
[18:49:05.448] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C7.dat
[18:49:05.448] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C8.dat
[18:49:05.448] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C9.dat
[18:49:05.448] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C10.dat
[18:49:05.448] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C11.dat
[18:49:05.449] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C12.dat
[18:49:05.449] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C13.dat
[18:49:05.449] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C14.dat
[18:49:05.449] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C15.dat
[18:49:05.449] <TB3> INFO: write trim parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//trimParameters35_C0.dat
[18:49:05.457] <TB3> INFO: write trim parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//trimParameters35_C1.dat
[18:49:05.466] <TB3> INFO: write trim parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//trimParameters35_C2.dat
[18:49:05.475] <TB3> INFO: write trim parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//trimParameters35_C3.dat
[18:49:05.483] <TB3> INFO: write trim parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//trimParameters35_C4.dat
[18:49:05.492] <TB3> INFO: write trim parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//trimParameters35_C5.dat
[18:49:05.498] <TB3> INFO: write trim parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//trimParameters35_C6.dat
[18:49:05.504] <TB3> INFO: write trim parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//trimParameters35_C7.dat
[18:49:05.510] <TB3> INFO: write trim parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//trimParameters35_C8.dat
[18:49:05.516] <TB3> INFO: write trim parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//trimParameters35_C9.dat
[18:49:05.522] <TB3> INFO: write trim parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//trimParameters35_C10.dat
[18:49:05.529] <TB3> INFO: write trim parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//trimParameters35_C11.dat
[18:49:05.535] <TB3> INFO: write trim parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//trimParameters35_C12.dat
[18:49:05.541] <TB3> INFO: write trim parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//trimParameters35_C13.dat
[18:49:05.546] <TB3> INFO: write trim parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//trimParameters35_C14.dat
[18:49:05.553] <TB3> INFO: write trim parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//trimParameters35_C15.dat
[18:49:05.559] <TB3> INFO: PixTestTrim::trimTest() done
[18:49:05.559] <TB3> INFO: vtrim: 98 82 81 99 103 99 86 107 108 108 114 98 98 93 97 94
[18:49:05.559] <TB3> INFO: vthrcomp: 93 80 83 94 88 100 77 93 88 94 96 96 87 83 93 96
[18:49:05.559] <TB3> INFO: vcal mean: 34.97 34.96 34.98 34.94 34.95 34.95 34.99 34.95 34.95 34.93 34.95 35.03 34.96 34.96 34.99 34.91
[18:49:05.559] <TB3> INFO: vcal RMS: 1.04 0.86 0.80 0.93 1.48 0.95 0.99 1.41 1.60 1.15 1.18 0.90 0.83 1.29 0.91 1.81
[18:49:05.559] <TB3> INFO: bits mean: 9.22 9.62 9.84 9.56 8.85 9.51 8.12 9.42 9.22 9.28 9.59 9.55 9.71 9.62 9.35 8.67
[18:49:05.559] <TB3> INFO: bits RMS: 2.81 2.66 2.61 2.74 2.44 2.72 2.60 2.68 2.67 2.74 2.59 2.72 2.66 2.63 2.72 2.92
[18:49:05.566] <TB3> INFO: ----------------------------------------------------------------------
[18:49:05.566] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[18:49:05.566] <TB3> INFO: ----------------------------------------------------------------------
[18:49:05.568] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[18:49:05.576] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[18:49:05.576] <TB3> INFO: run 1 of 1
[18:49:05.881] <TB3> INFO: Expecting 8320000 events.
[18:49:47.218] <TB3> INFO: 1155110 events read in total (40622ms).
[18:50:28.649] <TB3> INFO: 2298750 events read in total (82053ms).
[18:51:09.671] <TB3> INFO: 3437830 events read in total (123075ms).
[18:51:50.359] <TB3> INFO: 4567890 events read in total (163763ms).
[18:52:30.795] <TB3> INFO: 5687450 events read in total (204199ms).
[18:53:10.880] <TB3> INFO: 6803560 events read in total (244284ms).
[18:53:51.441] <TB3> INFO: 7918860 events read in total (284845ms).
[18:54:05.900] <TB3> INFO: 8320000 events read in total (299304ms).
[18:54:05.941] <TB3> INFO: Test took 300365ms.
[18:54:06.051] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[18:54:31.803] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 197 (-1/-1) hits flags = 528 (plus default)
[18:54:31.812] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[18:54:31.812] <TB3> INFO: run 1 of 1
[18:54:32.117] <TB3> INFO: Expecting 8236800 events.
[18:55:10.518] <TB3> INFO: 1109560 events read in total (37686ms).
[18:55:52.035] <TB3> INFO: 2209660 events read in total (79203ms).
[18:56:29.458] <TB3> INFO: 3307610 events read in total (116626ms).
[18:57:11.007] <TB3> INFO: 4397580 events read in total (158175ms).
[18:57:51.273] <TB3> INFO: 5477930 events read in total (198441ms).
[18:58:31.737] <TB3> INFO: 6554990 events read in total (238905ms).
[18:59:12.269] <TB3> INFO: 7630670 events read in total (279437ms).
[18:59:35.653] <TB3> INFO: 8236800 events read in total (302821ms).
[18:59:35.700] <TB3> INFO: Test took 303888ms.
[18:59:35.812] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:00:04.568] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 178 (-1/-1) hits flags = 528 (plus default)
[19:00:04.576] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[19:00:04.576] <TB3> INFO: run 1 of 1
[19:00:04.878] <TB3> INFO: Expecting 7446400 events.
[19:00:47.678] <TB3> INFO: 1163680 events read in total (42086ms).
[19:01:29.086] <TB3> INFO: 2317570 events read in total (83494ms).
[19:02:09.280] <TB3> INFO: 3466440 events read in total (123688ms).
[19:02:48.598] <TB3> INFO: 4600580 events read in total (163006ms).
[19:03:28.247] <TB3> INFO: 5727500 events read in total (202655ms).
[19:04:08.612] <TB3> INFO: 6851840 events read in total (243020ms).
[19:04:30.110] <TB3> INFO: 7446400 events read in total (264518ms).
[19:04:30.148] <TB3> INFO: Test took 265572ms.
[19:04:30.242] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:04:54.964] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 181 (-1/-1) hits flags = 528 (plus default)
[19:04:54.972] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[19:04:54.972] <TB3> INFO: run 1 of 1
[19:04:55.276] <TB3> INFO: Expecting 7571200 events.
[19:05:36.794] <TB3> INFO: 1152250 events read in total (40803ms).
[19:06:16.729] <TB3> INFO: 2295690 events read in total (80738ms).
[19:06:54.195] <TB3> INFO: 3433930 events read in total (118204ms).
[19:07:30.802] <TB3> INFO: 4559700 events read in total (154811ms).
[19:08:06.992] <TB3> INFO: 5676680 events read in total (191001ms).
[19:08:43.519] <TB3> INFO: 6791960 events read in total (227528ms).
[19:09:08.811] <TB3> INFO: 7571200 events read in total (252820ms).
[19:09:08.846] <TB3> INFO: Test took 253874ms.
[19:09:08.939] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:09:32.089] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 178 (-1/-1) hits flags = 528 (plus default)
[19:09:32.097] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[19:09:32.097] <TB3> INFO: run 1 of 1
[19:09:32.397] <TB3> INFO: Expecting 7446400 events.
[19:10:10.531] <TB3> INFO: 1162210 events read in total (37419ms).
[19:10:47.957] <TB3> INFO: 2315230 events read in total (74845ms).
[19:11:25.394] <TB3> INFO: 3462430 events read in total (112282ms).
[19:12:02.369] <TB3> INFO: 4594790 events read in total (149257ms).
[19:12:39.284] <TB3> INFO: 5720260 events read in total (186172ms).
[19:13:15.994] <TB3> INFO: 6843210 events read in total (222882ms).
[19:13:35.837] <TB3> INFO: 7446400 events read in total (242725ms).
[19:13:35.870] <TB3> INFO: Test took 243773ms.
[19:13:35.960] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:13:58.899] <TB3> INFO: PixTestTrim::trimBitTest() done
[19:13:58.900] <TB3> INFO: PixTestTrim::doTest() done, duration: 3432 seconds
[19:13:58.900] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:13:58.900] <TB3> INFO: Decoding statistics:
[19:13:58.900] <TB3> INFO: General information:
[19:13:58.900] <TB3> INFO: 16bit words read: 0
[19:13:58.900] <TB3> INFO: valid events total: 0
[19:13:58.900] <TB3> INFO: empty events: 0
[19:13:58.900] <TB3> INFO: valid events with pixels: 0
[19:13:58.900] <TB3> INFO: valid pixel hits: 0
[19:13:58.900] <TB3> INFO: Event errors: 0
[19:13:58.900] <TB3> INFO: start marker: 0
[19:13:58.900] <TB3> INFO: stop marker: 0
[19:13:58.900] <TB3> INFO: overflow: 0
[19:13:58.900] <TB3> INFO: invalid 5bit words: 0
[19:13:58.900] <TB3> INFO: invalid XOR eye diagram: 0
[19:13:58.900] <TB3> INFO: TBM errors: 0
[19:13:58.900] <TB3> INFO: flawed TBM headers: 0
[19:13:58.900] <TB3> INFO: flawed TBM trailers: 0
[19:13:58.900] <TB3> INFO: event ID mismatches: 0
[19:13:58.900] <TB3> INFO: ROC errors: 0
[19:13:58.900] <TB3> INFO: missing ROC header(s): 0
[19:13:58.900] <TB3> INFO: misplaced readback start: 0
[19:13:58.900] <TB3> INFO: Pixel decoding errors: 0
[19:13:58.900] <TB3> INFO: pixel data incomplete: 0
[19:13:58.900] <TB3> INFO: pixel address: 0
[19:13:58.900] <TB3> INFO: pulse height fill bit: 0
[19:13:58.900] <TB3> INFO: buffer corruption: 0
[19:13:59.550] <TB3> INFO: ######################################################################
[19:13:59.551] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[19:13:59.551] <TB3> INFO: ######################################################################
[19:13:59.851] <TB3> INFO: Expecting 41600 events.
[19:14:03.950] <TB3> INFO: 41600 events read in total (3385ms).
[19:14:03.950] <TB3> INFO: Test took 4398ms.
[19:14:03.956] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:14:04.614] <TB3> INFO: Expecting 41600 events.
[19:14:08.685] <TB3> INFO: 41600 events read in total (3356ms).
[19:14:08.685] <TB3> INFO: Test took 4370ms.
[19:14:08.691] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:14:09.142] <TB3> INFO: Expecting 41600 events.
[19:14:13.220] <TB3> INFO: 41600 events read in total (3363ms).
[19:14:13.221] <TB3> INFO: Test took 4402ms.
[19:14:13.227] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:14:13.674] <TB3> INFO: Expecting 2560 events.
[19:14:14.645] <TB3> INFO: 2560 events read in total (256ms).
[19:14:14.645] <TB3> INFO: Test took 1412ms.
[19:14:15.153] <TB3> INFO: Expecting 2560 events.
[19:14:16.124] <TB3> INFO: 2560 events read in total (256ms).
[19:14:16.124] <TB3> INFO: Test took 1479ms.
[19:14:16.632] <TB3> INFO: Expecting 2560 events.
[19:14:17.602] <TB3> INFO: 2560 events read in total (255ms).
[19:14:17.602] <TB3> INFO: Test took 1478ms.
[19:14:18.110] <TB3> INFO: Expecting 2560 events.
[19:14:19.065] <TB3> INFO: 2560 events read in total (241ms).
[19:14:19.066] <TB3> INFO: Test took 1464ms.
[19:14:19.574] <TB3> INFO: Expecting 2560 events.
[19:14:20.530] <TB3> INFO: 2560 events read in total (241ms).
[19:14:20.530] <TB3> INFO: Test took 1464ms.
[19:14:21.037] <TB3> INFO: Expecting 2560 events.
[19:14:21.993] <TB3> INFO: 2560 events read in total (241ms).
[19:14:21.993] <TB3> INFO: Test took 1463ms.
[19:14:22.501] <TB3> INFO: Expecting 2560 events.
[19:14:23.457] <TB3> INFO: 2560 events read in total (241ms).
[19:14:23.457] <TB3> INFO: Test took 1463ms.
[19:14:23.964] <TB3> INFO: Expecting 2560 events.
[19:14:24.920] <TB3> INFO: 2560 events read in total (241ms).
[19:14:24.920] <TB3> INFO: Test took 1463ms.
[19:14:25.428] <TB3> INFO: Expecting 2560 events.
[19:14:26.384] <TB3> INFO: 2560 events read in total (241ms).
[19:14:26.384] <TB3> INFO: Test took 1464ms.
[19:14:26.892] <TB3> INFO: Expecting 2560 events.
[19:14:27.863] <TB3> INFO: 2560 events read in total (256ms).
[19:14:27.863] <TB3> INFO: Test took 1479ms.
[19:14:28.371] <TB3> INFO: Expecting 2560 events.
[19:14:29.327] <TB3> INFO: 2560 events read in total (241ms).
[19:14:29.327] <TB3> INFO: Test took 1464ms.
[19:14:29.835] <TB3> INFO: Expecting 2560 events.
[19:14:30.792] <TB3> INFO: 2560 events read in total (242ms).
[19:14:30.792] <TB3> INFO: Test took 1465ms.
[19:14:31.300] <TB3> INFO: Expecting 2560 events.
[19:14:32.256] <TB3> INFO: 2560 events read in total (241ms).
[19:14:32.257] <TB3> INFO: Test took 1465ms.
[19:14:32.765] <TB3> INFO: Expecting 2560 events.
[19:14:33.735] <TB3> INFO: 2560 events read in total (255ms).
[19:14:33.736] <TB3> INFO: Test took 1479ms.
[19:14:34.244] <TB3> INFO: Expecting 2560 events.
[19:14:35.200] <TB3> INFO: 2560 events read in total (242ms).
[19:14:35.200] <TB3> INFO: Test took 1464ms.
[19:14:35.708] <TB3> INFO: Expecting 2560 events.
[19:14:36.665] <TB3> INFO: 2560 events read in total (242ms).
[19:14:36.665] <TB3> INFO: Test took 1464ms.
[19:14:36.667] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:14:37.175] <TB3> INFO: Expecting 655360 events.
[19:14:49.442] <TB3> INFO: 655360 events read in total (11552ms).
[19:14:49.450] <TB3> INFO: Expecting 655360 events.
[19:15:01.568] <TB3> INFO: 655360 events read in total (11511ms).
[19:15:01.579] <TB3> INFO: Expecting 655360 events.
[19:15:13.830] <TB3> INFO: 655360 events read in total (11643ms).
[19:15:13.845] <TB3> INFO: Expecting 655360 events.
[19:15:25.887] <TB3> INFO: 655360 events read in total (11436ms).
[19:15:25.905] <TB3> INFO: Expecting 655360 events.
[19:15:37.923] <TB3> INFO: 655360 events read in total (11415ms).
[19:15:37.944] <TB3> INFO: Expecting 655360 events.
[19:15:50.075] <TB3> INFO: 655360 events read in total (11534ms).
[19:15:50.100] <TB3> INFO: Expecting 655360 events.
[19:16:02.230] <TB3> INFO: 655360 events read in total (11536ms).
[19:16:02.257] <TB3> INFO: Expecting 655360 events.
[19:16:14.421] <TB3> INFO: 655360 events read in total (11570ms).
[19:16:14.453] <TB3> INFO: Expecting 655360 events.
[19:16:26.335] <TB3> INFO: 655360 events read in total (11293ms).
[19:16:26.369] <TB3> INFO: Expecting 655360 events.
[19:16:38.495] <TB3> INFO: 655360 events read in total (11539ms).
[19:16:38.532] <TB3> INFO: Expecting 655360 events.
[19:16:50.417] <TB3> INFO: 655360 events read in total (11305ms).
[19:16:50.457] <TB3> INFO: Expecting 655360 events.
[19:17:02.571] <TB3> INFO: 655360 events read in total (11536ms).
[19:17:02.615] <TB3> INFO: Expecting 655360 events.
[19:17:14.814] <TB3> INFO: 655360 events read in total (11623ms).
[19:17:14.860] <TB3> INFO: Expecting 655360 events.
[19:17:27.075] <TB3> INFO: 655360 events read in total (11641ms).
[19:17:27.125] <TB3> INFO: Expecting 655360 events.
[19:17:39.306] <TB3> INFO: 655360 events read in total (11612ms).
[19:17:39.365] <TB3> INFO: Expecting 655360 events.
[19:17:51.305] <TB3> INFO: 655360 events read in total (11381ms).
[19:17:51.362] <TB3> INFO: Test took 194695ms.
[19:17:51.438] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:17:51.747] <TB3> INFO: Expecting 655360 events.
[19:18:03.764] <TB3> INFO: 655360 events read in total (11302ms).
[19:18:03.772] <TB3> INFO: Expecting 655360 events.
[19:18:15.774] <TB3> INFO: 655360 events read in total (11392ms).
[19:18:15.786] <TB3> INFO: Expecting 655360 events.
[19:18:27.712] <TB3> INFO: 655360 events read in total (11308ms).
[19:18:27.727] <TB3> INFO: Expecting 655360 events.
[19:18:39.904] <TB3> INFO: 655360 events read in total (11573ms).
[19:18:39.922] <TB3> INFO: Expecting 655360 events.
[19:18:52.132] <TB3> INFO: 655360 events read in total (11605ms).
[19:18:52.153] <TB3> INFO: Expecting 655360 events.
[19:19:04.212] <TB3> INFO: 655360 events read in total (11455ms).
[19:19:04.237] <TB3> INFO: Expecting 655360 events.
[19:19:16.366] <TB3> INFO: 655360 events read in total (11533ms).
[19:19:16.394] <TB3> INFO: Expecting 655360 events.
[19:19:28.675] <TB3> INFO: 655360 events read in total (11687ms).
[19:19:28.705] <TB3> INFO: Expecting 655360 events.
[19:19:40.759] <TB3> INFO: 655360 events read in total (11468ms).
[19:19:40.795] <TB3> INFO: Expecting 655360 events.
[19:19:52.896] <TB3> INFO: 655360 events read in total (11514ms).
[19:19:52.935] <TB3> INFO: Expecting 655360 events.
[19:20:05.184] <TB3> INFO: 655360 events read in total (11668ms).
[19:20:05.226] <TB3> INFO: Expecting 655360 events.
[19:20:17.295] <TB3> INFO: 655360 events read in total (11492ms).
[19:20:17.340] <TB3> INFO: Expecting 655360 events.
[19:20:29.287] <TB3> INFO: 655360 events read in total (11370ms).
[19:20:29.335] <TB3> INFO: Expecting 655360 events.
[19:20:41.529] <TB3> INFO: 655360 events read in total (11625ms).
[19:20:41.579] <TB3> INFO: Expecting 655360 events.
[19:20:53.763] <TB3> INFO: 655360 events read in total (11614ms).
[19:20:53.821] <TB3> INFO: Expecting 655360 events.
[19:21:05.801] <TB3> INFO: 655360 events read in total (11413ms).
[19:21:05.859] <TB3> INFO: Test took 194421ms.
[19:21:06.034] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:21:06.040] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:21:06.047] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:21:06.053] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:21:06.059] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:21:06.066] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:21:06.072] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[19:21:06.079] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[19:21:06.085] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[19:21:06.091] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[19:21:06.098] <TB3> INFO: safety margin for low PH: adding 5, margin is now 25
[19:21:06.104] <TB3> INFO: safety margin for low PH: adding 6, margin is now 26
[19:21:06.110] <TB3> INFO: safety margin for low PH: adding 7, margin is now 27
[19:21:06.117] <TB3> INFO: safety margin for low PH: adding 8, margin is now 28
[19:21:06.123] <TB3> INFO: safety margin for low PH: adding 9, margin is now 29
[19:21:06.129] <TB3> INFO: safety margin for low PH: adding 10, margin is now 30
[19:21:06.136] <TB3> INFO: safety margin for low PH: adding 11, margin is now 31
[19:21:06.142] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:21:06.148] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:21:06.155] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:21:06.161] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:21:06.167] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[19:21:06.174] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[19:21:06.180] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[19:21:06.186] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:21:06.193] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:21:06.199] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:21:06.205] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:21:06.212] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:21:06.218] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[19:21:06.224] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[19:21:06.230] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[19:21:06.237] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[19:21:06.271] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C0.dat
[19:21:06.271] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C1.dat
[19:21:06.271] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C2.dat
[19:21:06.271] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C3.dat
[19:21:06.271] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C4.dat
[19:21:06.271] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C5.dat
[19:21:06.271] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C6.dat
[19:21:06.271] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C7.dat
[19:21:06.272] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C8.dat
[19:21:06.272] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C9.dat
[19:21:06.272] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C10.dat
[19:21:06.272] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C11.dat
[19:21:06.272] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C12.dat
[19:21:06.272] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C13.dat
[19:21:06.272] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C14.dat
[19:21:06.272] <TB3> INFO: write dac parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//dacParameters35_C15.dat
[19:21:06.575] <TB3> INFO: Expecting 41600 events.
[19:21:10.459] <TB3> INFO: 41600 events read in total (3169ms).
[19:21:10.460] <TB3> INFO: Test took 4185ms.
[19:21:11.094] <TB3> INFO: Expecting 41600 events.
[19:21:14.949] <TB3> INFO: 41600 events read in total (3140ms).
[19:21:14.950] <TB3> INFO: Test took 4155ms.
[19:21:15.587] <TB3> INFO: Expecting 41600 events.
[19:21:19.409] <TB3> INFO: 41600 events read in total (3107ms).
[19:21:19.409] <TB3> INFO: Test took 4124ms.
[19:21:19.744] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:19.875] <TB3> INFO: Expecting 2560 events.
[19:21:20.860] <TB3> INFO: 2560 events read in total (270ms).
[19:21:20.860] <TB3> INFO: Test took 1116ms.
[19:21:20.861] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:21.368] <TB3> INFO: Expecting 2560 events.
[19:21:22.338] <TB3> INFO: 2560 events read in total (255ms).
[19:21:22.338] <TB3> INFO: Test took 1477ms.
[19:21:22.340] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:22.846] <TB3> INFO: Expecting 2560 events.
[19:21:23.802] <TB3> INFO: 2560 events read in total (241ms).
[19:21:23.802] <TB3> INFO: Test took 1462ms.
[19:21:23.803] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:24.310] <TB3> INFO: Expecting 2560 events.
[19:21:25.280] <TB3> INFO: 2560 events read in total (255ms).
[19:21:25.280] <TB3> INFO: Test took 1477ms.
[19:21:25.282] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:25.789] <TB3> INFO: Expecting 2560 events.
[19:21:26.744] <TB3> INFO: 2560 events read in total (241ms).
[19:21:26.744] <TB3> INFO: Test took 1462ms.
[19:21:26.746] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:27.253] <TB3> INFO: Expecting 2560 events.
[19:21:28.208] <TB3> INFO: 2560 events read in total (241ms).
[19:21:28.208] <TB3> INFO: Test took 1462ms.
[19:21:28.210] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:28.717] <TB3> INFO: Expecting 2560 events.
[19:21:29.687] <TB3> INFO: 2560 events read in total (255ms).
[19:21:29.687] <TB3> INFO: Test took 1477ms.
[19:21:29.688] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:30.195] <TB3> INFO: Expecting 2560 events.
[19:21:31.151] <TB3> INFO: 2560 events read in total (241ms).
[19:21:31.151] <TB3> INFO: Test took 1463ms.
[19:21:31.153] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:31.659] <TB3> INFO: Expecting 2560 events.
[19:21:32.629] <TB3> INFO: 2560 events read in total (255ms).
[19:21:32.630] <TB3> INFO: Test took 1478ms.
[19:21:32.631] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:33.138] <TB3> INFO: Expecting 2560 events.
[19:21:34.093] <TB3> INFO: 2560 events read in total (240ms).
[19:21:34.094] <TB3> INFO: Test took 1463ms.
[19:21:34.095] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:34.602] <TB3> INFO: Expecting 2560 events.
[19:21:35.557] <TB3> INFO: 2560 events read in total (240ms).
[19:21:35.558] <TB3> INFO: Test took 1463ms.
[19:21:35.559] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:36.066] <TB3> INFO: Expecting 2560 events.
[19:21:37.022] <TB3> INFO: 2560 events read in total (241ms).
[19:21:37.022] <TB3> INFO: Test took 1463ms.
[19:21:37.023] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:37.530] <TB3> INFO: Expecting 2560 events.
[19:21:38.486] <TB3> INFO: 2560 events read in total (241ms).
[19:21:38.486] <TB3> INFO: Test took 1463ms.
[19:21:38.488] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:38.994] <TB3> INFO: Expecting 2560 events.
[19:21:39.965] <TB3> INFO: 2560 events read in total (256ms).
[19:21:39.965] <TB3> INFO: Test took 1477ms.
[19:21:39.966] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:40.473] <TB3> INFO: Expecting 2560 events.
[19:21:41.429] <TB3> INFO: 2560 events read in total (241ms).
[19:21:41.429] <TB3> INFO: Test took 1463ms.
[19:21:41.430] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:41.937] <TB3> INFO: Expecting 2560 events.
[19:21:42.893] <TB3> INFO: 2560 events read in total (241ms).
[19:21:42.893] <TB3> INFO: Test took 1463ms.
[19:21:42.895] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:43.402] <TB3> INFO: Expecting 2560 events.
[19:21:44.357] <TB3> INFO: 2560 events read in total (241ms).
[19:21:44.357] <TB3> INFO: Test took 1462ms.
[19:21:44.359] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:44.866] <TB3> INFO: Expecting 2560 events.
[19:21:45.836] <TB3> INFO: 2560 events read in total (255ms).
[19:21:45.836] <TB3> INFO: Test took 1477ms.
[19:21:45.838] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:46.345] <TB3> INFO: Expecting 2560 events.
[19:21:47.301] <TB3> INFO: 2560 events read in total (241ms).
[19:21:47.301] <TB3> INFO: Test took 1463ms.
[19:21:47.303] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:47.809] <TB3> INFO: Expecting 2560 events.
[19:21:48.780] <TB3> INFO: 2560 events read in total (256ms).
[19:21:48.780] <TB3> INFO: Test took 1478ms.
[19:21:48.782] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:49.289] <TB3> INFO: Expecting 2560 events.
[19:21:50.244] <TB3> INFO: 2560 events read in total (241ms).
[19:21:50.245] <TB3> INFO: Test took 1463ms.
[19:21:50.246] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:50.753] <TB3> INFO: Expecting 2560 events.
[19:21:51.709] <TB3> INFO: 2560 events read in total (241ms).
[19:21:51.709] <TB3> INFO: Test took 1463ms.
[19:21:51.711] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:52.217] <TB3> INFO: Expecting 2560 events.
[19:21:53.173] <TB3> INFO: 2560 events read in total (241ms).
[19:21:53.173] <TB3> INFO: Test took 1462ms.
[19:21:53.175] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:53.682] <TB3> INFO: Expecting 2560 events.
[19:21:54.638] <TB3> INFO: 2560 events read in total (241ms).
[19:21:54.638] <TB3> INFO: Test took 1463ms.
[19:21:54.640] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:55.146] <TB3> INFO: Expecting 2560 events.
[19:21:56.102] <TB3> INFO: 2560 events read in total (241ms).
[19:21:56.102] <TB3> INFO: Test took 1463ms.
[19:21:56.104] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:56.611] <TB3> INFO: Expecting 2560 events.
[19:21:57.567] <TB3> INFO: 2560 events read in total (242ms).
[19:21:57.567] <TB3> INFO: Test took 1463ms.
[19:21:57.568] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:58.075] <TB3> INFO: Expecting 2560 events.
[19:21:59.031] <TB3> INFO: 2560 events read in total (241ms).
[19:21:59.031] <TB3> INFO: Test took 1463ms.
[19:21:59.033] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:21:59.540] <TB3> INFO: Expecting 2560 events.
[19:22:00.496] <TB3> INFO: 2560 events read in total (242ms).
[19:22:00.496] <TB3> INFO: Test took 1463ms.
[19:22:00.497] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:22:01.004] <TB3> INFO: Expecting 2560 events.
[19:22:01.960] <TB3> INFO: 2560 events read in total (241ms).
[19:22:01.960] <TB3> INFO: Test took 1463ms.
[19:22:01.962] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:22:02.469] <TB3> INFO: Expecting 2560 events.
[19:22:03.424] <TB3> INFO: 2560 events read in total (241ms).
[19:22:03.425] <TB3> INFO: Test took 1463ms.
[19:22:03.426] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:22:03.933] <TB3> INFO: Expecting 2560 events.
[19:22:04.889] <TB3> INFO: 2560 events read in total (241ms).
[19:22:04.889] <TB3> INFO: Test took 1463ms.
[19:22:04.891] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[19:22:05.398] <TB3> INFO: Expecting 2560 events.
[19:22:06.354] <TB3> INFO: 2560 events read in total (241ms).
[19:22:06.354] <TB3> INFO: Test took 1463ms.
[19:22:07.042] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 487 seconds
[19:22:07.042] <TB3> INFO: PH scale (per ROC): 79 82 91 94 77 80 83 80 80 80 80 86 84 84 80 69
[19:22:07.042] <TB3> INFO: PH offset (per ROC): 169 154 143 144 160 176 150 162 143 150 154 149 145 130 159 159
[19:22:07.045] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:22:07.045] <TB3> INFO: Decoding statistics:
[19:22:07.045] <TB3> INFO: General information:
[19:22:07.045] <TB3> INFO: 16bit words read: 66442
[19:22:07.045] <TB3> INFO: valid events total: 5120
[19:22:07.045] <TB3> INFO: empty events: 2619
[19:22:07.045] <TB3> INFO: valid events with pixels: 2501
[19:22:07.045] <TB3> INFO: valid pixel hits: 2501
[19:22:07.045] <TB3> INFO: Event errors: 0
[19:22:07.045] <TB3> INFO: start marker: 0
[19:22:07.045] <TB3> INFO: stop marker: 0
[19:22:07.045] <TB3> INFO: overflow: 0
[19:22:07.045] <TB3> INFO: invalid 5bit words: 0
[19:22:07.045] <TB3> INFO: invalid XOR eye diagram: 0
[19:22:07.045] <TB3> INFO: TBM errors: 0
[19:22:07.045] <TB3> INFO: flawed TBM headers: 0
[19:22:07.045] <TB3> INFO: flawed TBM trailers: 0
[19:22:07.045] <TB3> INFO: event ID mismatches: 0
[19:22:07.045] <TB3> INFO: ROC errors: 0
[19:22:07.045] <TB3> INFO: missing ROC header(s): 0
[19:22:07.045] <TB3> INFO: misplaced readback start: 0
[19:22:07.045] <TB3> INFO: Pixel decoding errors: 0
[19:22:07.045] <TB3> INFO: pixel data incomplete: 0
[19:22:07.045] <TB3> INFO: pixel address: 0
[19:22:07.045] <TB3> INFO: pulse height fill bit: 0
[19:22:07.045] <TB3> INFO: buffer corruption: 0
[19:22:07.207] <TB3> INFO: ######################################################################
[19:22:07.207] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[19:22:07.207] <TB3> INFO: ######################################################################
[19:22:07.216] <TB3> INFO: scanning low vcal = 10
[19:22:07.516] <TB3> INFO: Expecting 41600 events.
[19:22:11.277] <TB3> INFO: 41600 events read in total (3047ms).
[19:22:11.277] <TB3> INFO: Test took 4061ms.
[19:22:11.279] <TB3> INFO: scanning low vcal = 20
[19:22:11.785] <TB3> INFO: Expecting 41600 events.
[19:22:15.519] <TB3> INFO: 41600 events read in total (3019ms).
[19:22:15.520] <TB3> INFO: Test took 4241ms.
[19:22:15.521] <TB3> INFO: scanning low vcal = 30
[19:22:16.028] <TB3> INFO: Expecting 41600 events.
[19:22:19.780] <TB3> INFO: 41600 events read in total (3037ms).
[19:22:19.780] <TB3> INFO: Test took 4259ms.
[19:22:19.782] <TB3> INFO: scanning low vcal = 40
[19:22:20.283] <TB3> INFO: Expecting 41600 events.
[19:22:24.518] <TB3> INFO: 41600 events read in total (3520ms).
[19:22:24.519] <TB3> INFO: Test took 4737ms.
[19:22:24.521] <TB3> INFO: scanning low vcal = 50
[19:22:24.971] <TB3> INFO: Expecting 41600 events.
[19:22:29.204] <TB3> INFO: 41600 events read in total (3518ms).
[19:22:29.205] <TB3> INFO: Test took 4684ms.
[19:22:29.207] <TB3> INFO: scanning low vcal = 60
[19:22:29.659] <TB3> INFO: Expecting 41600 events.
[19:22:33.936] <TB3> INFO: 41600 events read in total (3562ms).
[19:22:33.937] <TB3> INFO: Test took 4730ms.
[19:22:33.939] <TB3> INFO: scanning low vcal = 70
[19:22:34.391] <TB3> INFO: Expecting 41600 events.
[19:22:38.680] <TB3> INFO: 41600 events read in total (3574ms).
[19:22:38.681] <TB3> INFO: Test took 4742ms.
[19:22:38.684] <TB3> INFO: scanning low vcal = 80
[19:22:39.135] <TB3> INFO: Expecting 41600 events.
[19:22:43.445] <TB3> INFO: 41600 events read in total (3595ms).
[19:22:43.446] <TB3> INFO: Test took 4762ms.
[19:22:43.453] <TB3> INFO: scanning low vcal = 90
[19:22:43.900] <TB3> INFO: Expecting 41600 events.
[19:22:48.186] <TB3> INFO: 41600 events read in total (3571ms).
[19:22:48.187] <TB3> INFO: Test took 4734ms.
[19:22:48.190] <TB3> INFO: scanning low vcal = 100
[19:22:48.642] <TB3> INFO: Expecting 41600 events.
[19:22:53.058] <TB3> INFO: 41600 events read in total (3701ms).
[19:22:53.058] <TB3> INFO: Test took 4868ms.
[19:22:53.061] <TB3> INFO: scanning low vcal = 110
[19:22:53.514] <TB3> INFO: Expecting 41600 events.
[19:22:57.830] <TB3> INFO: 41600 events read in total (3601ms).
[19:22:57.831] <TB3> INFO: Test took 4770ms.
[19:22:57.834] <TB3> INFO: scanning low vcal = 120
[19:22:58.285] <TB3> INFO: Expecting 41600 events.
[19:23:02.532] <TB3> INFO: 41600 events read in total (3532ms).
[19:23:02.533] <TB3> INFO: Test took 4699ms.
[19:23:02.545] <TB3> INFO: scanning low vcal = 130
[19:23:02.987] <TB3> INFO: Expecting 41600 events.
[19:23:07.336] <TB3> INFO: 41600 events read in total (3634ms).
[19:23:07.337] <TB3> INFO: Test took 4792ms.
[19:23:07.339] <TB3> INFO: scanning low vcal = 140
[19:23:07.790] <TB3> INFO: Expecting 41600 events.
[19:23:12.148] <TB3> INFO: 41600 events read in total (3643ms).
[19:23:12.149] <TB3> INFO: Test took 4810ms.
[19:23:12.152] <TB3> INFO: scanning low vcal = 150
[19:23:12.604] <TB3> INFO: Expecting 41600 events.
[19:23:16.902] <TB3> INFO: 41600 events read in total (3583ms).
[19:23:16.903] <TB3> INFO: Test took 4751ms.
[19:23:16.906] <TB3> INFO: scanning low vcal = 160
[19:23:17.358] <TB3> INFO: Expecting 41600 events.
[19:23:21.686] <TB3> INFO: 41600 events read in total (3613ms).
[19:23:21.687] <TB3> INFO: Test took 4781ms.
[19:23:21.689] <TB3> INFO: scanning low vcal = 170
[19:23:22.142] <TB3> INFO: Expecting 41600 events.
[19:23:26.486] <TB3> INFO: 41600 events read in total (3629ms).
[19:23:26.487] <TB3> INFO: Test took 4798ms.
[19:23:26.496] <TB3> INFO: scanning low vcal = 180
[19:23:26.942] <TB3> INFO: Expecting 41600 events.
[19:23:31.268] <TB3> INFO: 41600 events read in total (3611ms).
[19:23:31.269] <TB3> INFO: Test took 4773ms.
[19:23:31.271] <TB3> INFO: scanning low vcal = 190
[19:23:31.724] <TB3> INFO: Expecting 41600 events.
[19:23:35.965] <TB3> INFO: 41600 events read in total (3526ms).
[19:23:35.966] <TB3> INFO: Test took 4695ms.
[19:23:35.968] <TB3> INFO: scanning low vcal = 200
[19:23:36.421] <TB3> INFO: Expecting 41600 events.
[19:23:40.735] <TB3> INFO: 41600 events read in total (3599ms).
[19:23:40.735] <TB3> INFO: Test took 4767ms.
[19:23:40.738] <TB3> INFO: scanning low vcal = 210
[19:23:41.190] <TB3> INFO: Expecting 41600 events.
[19:23:45.503] <TB3> INFO: 41600 events read in total (3598ms).
[19:23:45.504] <TB3> INFO: Test took 4766ms.
[19:23:45.506] <TB3> INFO: scanning low vcal = 220
[19:23:45.959] <TB3> INFO: Expecting 41600 events.
[19:23:50.217] <TB3> INFO: 41600 events read in total (3543ms).
[19:23:50.218] <TB3> INFO: Test took 4712ms.
[19:23:50.221] <TB3> INFO: scanning low vcal = 230
[19:23:50.672] <TB3> INFO: Expecting 41600 events.
[19:23:54.919] <TB3> INFO: 41600 events read in total (3532ms).
[19:23:54.920] <TB3> INFO: Test took 4699ms.
[19:23:54.923] <TB3> INFO: scanning low vcal = 240
[19:23:55.374] <TB3> INFO: Expecting 41600 events.
[19:23:59.703] <TB3> INFO: 41600 events read in total (3614ms).
[19:23:59.704] <TB3> INFO: Test took 4781ms.
[19:23:59.706] <TB3> INFO: scanning low vcal = 250
[19:24:00.159] <TB3> INFO: Expecting 41600 events.
[19:24:04.462] <TB3> INFO: 41600 events read in total (3588ms).
[19:24:04.463] <TB3> INFO: Test took 4757ms.
[19:24:04.467] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[19:24:04.918] <TB3> INFO: Expecting 41600 events.
[19:24:09.239] <TB3> INFO: 41600 events read in total (3606ms).
[19:24:09.240] <TB3> INFO: Test took 4773ms.
[19:24:09.242] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[19:24:09.694] <TB3> INFO: Expecting 41600 events.
[19:24:13.908] <TB3> INFO: 41600 events read in total (3499ms).
[19:24:13.909] <TB3> INFO: Test took 4667ms.
[19:24:13.912] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[19:24:14.364] <TB3> INFO: Expecting 41600 events.
[19:24:18.680] <TB3> INFO: 41600 events read in total (3601ms).
[19:24:18.681] <TB3> INFO: Test took 4769ms.
[19:24:18.684] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[19:24:19.134] <TB3> INFO: Expecting 41600 events.
[19:24:23.392] <TB3> INFO: 41600 events read in total (3543ms).
[19:24:23.393] <TB3> INFO: Test took 4709ms.
[19:24:23.400] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[19:24:23.848] <TB3> INFO: Expecting 41600 events.
[19:24:28.120] <TB3> INFO: 41600 events read in total (3557ms).
[19:24:28.121] <TB3> INFO: Test took 4721ms.
[19:24:28.533] <TB3> INFO: PixTestGainPedestal::measure() done
[19:24:59.536] <TB3> INFO: PixTestGainPedestal::fit() done
[19:24:59.536] <TB3> INFO: non-linearity mean: 0.957 0.954 0.946 0.955 0.963 0.965 0.954 0.948 0.952 0.962 0.953 0.954 0.955 0.955 0.959 0.953
[19:24:59.536] <TB3> INFO: non-linearity RMS: 0.006 0.006 0.006 0.006 0.006 0.004 0.004 0.007 0.006 0.004 0.006 0.006 0.005 0.005 0.005 0.007
[19:24:59.537] <TB3> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[19:24:59.553] <TB3> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[19:24:59.570] <TB3> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[19:24:59.587] <TB3> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[19:24:59.604] <TB3> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[19:24:59.621] <TB3> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[19:24:59.637] <TB3> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[19:24:59.654] <TB3> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[19:24:59.671] <TB3> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[19:24:59.687] <TB3> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[19:24:59.704] <TB3> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[19:24:59.721] <TB3> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[19:24:59.737] <TB3> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[19:24:59.754] <TB3> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[19:24:59.771] <TB3> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[19:24:59.787] <TB3> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[19:24:59.804] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 172 seconds
[19:24:59.804] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:24:59.804] <TB3> INFO: Decoding statistics:
[19:24:59.804] <TB3> INFO: General information:
[19:24:59.804] <TB3> INFO: 16bit words read: 2328990
[19:24:59.804] <TB3> INFO: valid events total: 83200
[19:24:59.804] <TB3> INFO: empty events: 0
[19:24:59.804] <TB3> INFO: valid events with pixels: 83200
[19:24:59.804] <TB3> INFO: valid pixel hits: 665295
[19:24:59.804] <TB3> INFO: Event errors: 0
[19:24:59.804] <TB3> INFO: start marker: 0
[19:24:59.804] <TB3> INFO: stop marker: 0
[19:24:59.804] <TB3> INFO: overflow: 0
[19:24:59.804] <TB3> INFO: invalid 5bit words: 0
[19:24:59.804] <TB3> INFO: invalid XOR eye diagram: 0
[19:24:59.804] <TB3> INFO: TBM errors: 0
[19:24:59.804] <TB3> INFO: flawed TBM headers: 0
[19:24:59.804] <TB3> INFO: flawed TBM trailers: 0
[19:24:59.804] <TB3> INFO: event ID mismatches: 0
[19:24:59.804] <TB3> INFO: ROC errors: 0
[19:24:59.804] <TB3> INFO: missing ROC header(s): 0
[19:24:59.804] <TB3> INFO: misplaced readback start: 0
[19:24:59.804] <TB3> INFO: Pixel decoding errors: 0
[19:24:59.804] <TB3> INFO: pixel data incomplete: 0
[19:24:59.804] <TB3> INFO: pixel address: 0
[19:24:59.804] <TB3> INFO: pulse height fill bit: 0
[19:24:59.804] <TB3> INFO: buffer corruption: 0
[19:24:59.810] <TB3> INFO: readReadbackCal: /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C15.dat
[19:24:59.811] <TB3> INFO: ######################################################################
[19:24:59.811] <TB3> INFO: PixTestReadback::doTest()
[19:24:59.811] <TB3> INFO: ######################################################################
[19:24:59.812] <TB3> INFO: PixTestReadback::RES sent once
[19:25:10.964] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C0.dat
[19:25:10.964] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C1.dat
[19:25:10.965] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C2.dat
[19:25:10.965] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C3.dat
[19:25:10.965] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C4.dat
[19:25:10.965] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C5.dat
[19:25:10.965] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C6.dat
[19:25:10.965] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C7.dat
[19:25:10.965] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C8.dat
[19:25:10.965] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C9.dat
[19:25:10.965] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C10.dat
[19:25:10.965] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C11.dat
[19:25:10.965] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C12.dat
[19:25:10.965] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C13.dat
[19:25:10.965] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C14.dat
[19:25:10.966] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C15.dat
[19:25:10.993] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[19:25:10.993] <TB3> INFO: PixTestReadback::RES sent once
[19:25:22.117] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C0.dat
[19:25:22.117] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C1.dat
[19:25:22.117] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C2.dat
[19:25:22.117] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C3.dat
[19:25:22.117] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C4.dat
[19:25:22.117] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C5.dat
[19:25:22.117] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C6.dat
[19:25:22.117] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C7.dat
[19:25:22.117] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C8.dat
[19:25:22.117] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C9.dat
[19:25:22.117] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C10.dat
[19:25:22.118] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C11.dat
[19:25:22.118] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C12.dat
[19:25:22.118] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C13.dat
[19:25:22.118] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C14.dat
[19:25:22.118] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C15.dat
[19:25:22.145] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[19:25:22.145] <TB3> INFO: PixTestReadback::RES sent once
[19:25:30.703] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[19:25:30.703] <TB3> INFO: Vbg will be calibrated using Vd calibration
[19:25:30.703] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 149.3calibrated Vbg = 1.2214 :::*/*/*/*/
[19:25:30.703] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 150.8calibrated Vbg = 1.21994 :::*/*/*/*/
[19:25:30.703] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 151.8calibrated Vbg = 1.22717 :::*/*/*/*/
[19:25:30.703] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 148.8calibrated Vbg = 1.23592 :::*/*/*/*/
[19:25:30.703] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 158.6calibrated Vbg = 1.23394 :::*/*/*/*/
[19:25:30.703] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 161calibrated Vbg = 1.23786 :::*/*/*/*/
[19:25:30.703] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 157.8calibrated Vbg = 1.23435 :::*/*/*/*/
[19:25:30.703] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 163.6calibrated Vbg = 1.23646 :::*/*/*/*/
[19:25:30.703] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 154.4calibrated Vbg = 1.23562 :::*/*/*/*/
[19:25:30.703] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 160.5calibrated Vbg = 1.24033 :::*/*/*/*/
[19:25:30.703] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 154.6calibrated Vbg = 1.23599 :::*/*/*/*/
[19:25:30.703] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 155.7calibrated Vbg = 1.23543 :::*/*/*/*/
[19:25:30.703] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 160.3calibrated Vbg = 1.22423 :::*/*/*/*/
[19:25:30.703] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 148.6calibrated Vbg = 1.21965 :::*/*/*/*/
[19:25:30.703] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 154.8calibrated Vbg = 1.21968 :::*/*/*/*/
[19:25:30.703] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 153.3calibrated Vbg = 1.22269 :::*/*/*/*/
[19:25:30.706] <TB3> INFO: PixTestReadback::RES sent once
[19:28:24.368] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C0.dat
[19:28:24.368] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C1.dat
[19:28:24.368] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C2.dat
[19:28:24.368] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C3.dat
[19:28:24.368] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C4.dat
[19:28:24.368] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C5.dat
[19:28:24.368] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C6.dat
[19:28:24.368] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C7.dat
[19:28:24.368] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C8.dat
[19:28:24.368] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C9.dat
[19:28:24.369] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C10.dat
[19:28:24.369] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C11.dat
[19:28:24.369] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C12.dat
[19:28:24.369] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C13.dat
[19:28:24.369] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C14.dat
[19:28:24.369] <TB3> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3021_FullQualification_2015-10-29_13h48m_1446122896//002_FulltestPxar_m20//readbackCal_C15.dat
[19:28:24.395] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[19:28:24.396] <TB3> INFO: PixTestReadback::doTest() done
[19:28:24.396] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[19:28:24.396] <TB3> INFO: Decoding statistics:
[19:28:24.396] <TB3> INFO: General information:
[19:28:24.396] <TB3> INFO: 16bit words read: 768
[19:28:24.396] <TB3> INFO: valid events total: 64
[19:28:24.396] <TB3> INFO: empty events: 64
[19:28:24.396] <TB3> INFO: valid events with pixels: 0
[19:28:24.396] <TB3> INFO: valid pixel hits: 0
[19:28:24.396] <TB3> INFO: Event errors: 0
[19:28:24.396] <TB3> INFO: start marker: 0
[19:28:24.396] <TB3> INFO: stop marker: 0
[19:28:24.396] <TB3> INFO: overflow: 0
[19:28:24.396] <TB3> INFO: invalid 5bit words: 0
[19:28:24.396] <TB3> INFO: invalid XOR eye diagram: 0
[19:28:24.396] <TB3> INFO: TBM errors: 0
[19:28:24.396] <TB3> INFO: flawed TBM headers: 0
[19:28:24.396] <TB3> INFO: flawed TBM trailers: 0
[19:28:24.396] <TB3> INFO: event ID mismatches: 0
[19:28:24.396] <TB3> INFO: ROC errors: 0
[19:28:24.396] <TB3> INFO: missing ROC header(s): 0
[19:28:24.396] <TB3> INFO: misplaced readback start: 0
[19:28:24.396] <TB3> INFO: Pixel decoding errors: 0
[19:28:24.396] <TB3> INFO: pixel data incomplete: 0
[19:28:24.396] <TB3> INFO: pixel address: 0
[19:28:24.396] <TB3> INFO: pulse height fill bit: 0
[19:28:24.396] <TB3> INFO: buffer corruption: 0
[19:28:24.409] <TB3> INFO: Decoding statistics:
[19:28:24.409] <TB3> INFO: General information:
[19:28:24.409] <TB3> INFO: 16bit words read: 2396200
[19:28:24.409] <TB3> INFO: valid events total: 88384
[19:28:24.409] <TB3> INFO: empty events: 2683
[19:28:24.409] <TB3> INFO: valid events with pixels: 85701
[19:28:24.409] <TB3> INFO: valid pixel hits: 667796
[19:28:24.409] <TB3> INFO: Event errors: 0
[19:28:24.409] <TB3> INFO: start marker: 0
[19:28:24.409] <TB3> INFO: stop marker: 0
[19:28:24.409] <TB3> INFO: overflow: 0
[19:28:24.409] <TB3> INFO: invalid 5bit words: 0
[19:28:24.409] <TB3> INFO: invalid XOR eye diagram: 0
[19:28:24.409] <TB3> INFO: TBM errors: 0
[19:28:24.409] <TB3> INFO: flawed TBM headers: 0
[19:28:24.409] <TB3> INFO: flawed TBM trailers: 0
[19:28:24.409] <TB3> INFO: event ID mismatches: 0
[19:28:24.409] <TB3> INFO: ROC errors: 0
[19:28:24.409] <TB3> INFO: missing ROC header(s): 0
[19:28:24.409] <TB3> INFO: misplaced readback start: 0
[19:28:24.409] <TB3> INFO: Pixel decoding errors: 0
[19:28:24.409] <TB3> INFO: pixel data incomplete: 0
[19:28:24.409] <TB3> INFO: pixel address: 0
[19:28:24.409] <TB3> INFO: pulse height fill bit: 0
[19:28:24.409] <TB3> INFO: buffer corruption: 0
[19:28:24.409] <TB3> INFO: enter test to run
[19:28:24.409] <TB3> INFO: test: exit no parameter change
[19:28:24.677] <TB3> QUIET: Connection to board 137 closed.
[19:28:24.757] <TB3> INFO: pXar: this is the end, my friend