Test Date: 2016-05-04 09:11
Analysis date: 2016-05-26 16:53
Logfile
LogfileView
[07:21:48.594] <TB1> INFO: *** Welcome to pxar ***
[07:21:48.594] <TB1> INFO: *** Today: 2016/05/04
[07:21:48.673] <TB1> INFO: *** Version: 9751-dirty
[07:21:48.673] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters_C15.dat
[07:21:48.674] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//tbmParameters_C0b.dat
[07:21:48.674] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//defaultMaskFile.dat
[07:21:48.674] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//trimParameters_C15.dat
[07:21:48.747] <TB1> INFO: clk: 4
[07:21:48.747] <TB1> INFO: ctr: 4
[07:21:48.747] <TB1> INFO: sda: 19
[07:21:48.747] <TB1> INFO: tin: 9
[07:21:48.747] <TB1> INFO: level: 15
[07:21:48.747] <TB1> INFO: triggerdelay: 0
[07:21:48.747] <TB1> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[07:21:48.747] <TB1> INFO: Log level: INFO
[07:21:48.753] <TB1> INFO: Found DTB DTB_WXBYFL
[07:21:48.764] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[07:21:48.765] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[07:21:48.767] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[07:21:50.303] <TB1> INFO: DUT info:
[07:21:50.303] <TB1> INFO: The DUT currently contains the following objects:
[07:21:50.303] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[07:21:50.303] <TB1> INFO: TBM Core alpha (0): 7 registers set
[07:21:50.303] <TB1> INFO: TBM Core beta (1): 7 registers set
[07:21:50.304] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:21:50.304] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:50.304] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:50.304] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:50.304] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:50.304] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:50.304] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:50.304] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:50.304] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:50.304] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:50.304] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:50.304] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:50.304] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:50.304] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:50.304] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:50.304] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:50.304] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:21:50.705] <TB1> INFO: enter 'restricted' command line mode
[07:21:50.705] <TB1> INFO: enter test to run
[07:21:50.705] <TB1> INFO: test: pretest no parameter change
[07:21:50.705] <TB1> INFO: running: pretest
[07:21:50.711] <TB1> INFO: ----------------------------------------------------------------------
[07:21:50.711] <TB1> INFO: PixTestPretest::programROC()
[07:21:50.711] <TB1> INFO: ----------------------------------------------------------------------
[07:22:08.725] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[07:22:08.725] <TB1> INFO: IA differences per ROC: 19.3 20.1 19.3 20.9 17.7 16.9 16.9 17.7 20.1 19.3 20.1 20.1 16.9 16.9 19.3 17.7
[07:22:08.818] <TB1> INFO: enter test to run
[07:22:08.818] <TB1> INFO: test: pretest no parameter change
[07:22:08.818] <TB1> INFO: running: pretest
[07:22:08.819] <TB1> INFO: ----------------------------------------------------------------------
[07:22:08.819] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[07:22:08.819] <TB1> INFO: ----------------------------------------------------------------------
[07:22:30.081] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 384.3 mA = 24.0187 mA/ROC
[07:22:30.081] <TB1> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 19.3 19.3 18.5 19.3 19.3 19.3 20.1 19.3 19.3 19.3 18.5 19.3 19.3
[07:22:30.111] <TB1> INFO: enter test to run
[07:22:30.111] <TB1> INFO: test: pretest no parameter change
[07:22:30.111] <TB1> INFO: running: pretest
[07:22:30.111] <TB1> INFO: ----------------------------------------------------------------------
[07:22:30.111] <TB1> INFO: PixTestPretest::findTiming()
[07:22:30.111] <TB1> INFO: ----------------------------------------------------------------------
[07:22:30.111] <TB1> INFO: PixTestCmd::init()
[07:22:31.071] <TB1> WARNING: Not unmasking DUT, not setting Calibrate bits!

[07:23:59.198] <TB1> INFO: TBM phases: 160MHz: 0, 400MHz: 4, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[07:23:59.198] <TB1> INFO: (success/tries = 100/100), width = 4
[07:23:59.199] <TB1> INFO: enter test to run
[07:23:59.199] <TB1> INFO: test: pretest no parameter change
[07:23:59.199] <TB1> INFO: running: pretest
[07:23:59.200] <TB1> INFO: ----------------------------------------------------------------------
[07:23:59.200] <TB1> INFO: PixTestPretest::findWorkingPixel()
[07:23:59.200] <TB1> INFO: ----------------------------------------------------------------------
[07:23:59.294] <TB1> INFO: Expecting 231680 events.
[07:24:05.068] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L497> Channel 0 Number of ROCs (1) != Token Chain Length (4)

[07:24:05.152] <TB1> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)

[07:24:09.479] <TB1> INFO: 231680 events read in total (9629ms).
[07:24:09.483] <TB1> INFO: Test took 10278ms.
[07:24:09.713] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[07:24:09.786] <TB1> INFO: enter test to run
[07:24:09.786] <TB1> INFO: test: pretest no parameter change
[07:24:09.786] <TB1> INFO: running: pretest
[07:24:09.787] <TB1> INFO: ----------------------------------------------------------------------
[07:24:09.787] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[07:24:09.787] <TB1> INFO: ----------------------------------------------------------------------
[07:24:09.878] <TB1> INFO: Expecting 231680 events.
[07:24:20.104] <TB1> INFO: 231680 events read in total (9670ms).
[07:24:20.107] <TB1> INFO: Test took 10317ms.
[07:24:20.346] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[07:24:20.346] <TB1> INFO: CalDel: 129 161 152 144 153 141 150 143 159 144 154 134 141 135 143 142
[07:24:20.346] <TB1> INFO: VthrComp: 51 51 52 51 51 51 51 51 51 51 51 51 51 51 51 51
[07:24:20.386] <TB1> INFO: enter test to run
[07:24:20.386] <TB1> INFO: test: pretest no parameter change
[07:24:20.386] <TB1> INFO: running: pretest
[07:24:20.386] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters_C0.dat
[07:24:20.387] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters_C1.dat
[07:24:20.387] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters_C2.dat
[07:24:20.387] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters_C3.dat
[07:24:20.387] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters_C4.dat
[07:24:20.387] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters_C5.dat
[07:24:20.387] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters_C6.dat
[07:24:20.387] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters_C7.dat
[07:24:20.387] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters_C8.dat
[07:24:20.387] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters_C9.dat
[07:24:20.387] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters_C10.dat
[07:24:20.387] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters_C11.dat
[07:24:20.387] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters_C12.dat
[07:24:20.388] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters_C13.dat
[07:24:20.388] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters_C14.dat
[07:24:20.388] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters_C15.dat
[07:24:20.388] <TB1> INFO: enter test to run
[07:24:20.388] <TB1> INFO: test: fulltest no parameter change
[07:24:20.388] <TB1> INFO: running: fulltest
[07:24:20.388] <TB1> INFO: ######################################################################
[07:24:20.388] <TB1> INFO: PixTestFullTest::doTest()
[07:24:20.388] <TB1> INFO: ######################################################################
[07:24:20.389] <TB1> INFO: ######################################################################
[07:24:20.389] <TB1> INFO: PixTestAlive::doTest()
[07:24:20.389] <TB1> INFO: ######################################################################
[07:24:20.390] <TB1> INFO: ----------------------------------------------------------------------
[07:24:20.390] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:24:20.390] <TB1> INFO: ----------------------------------------------------------------------
[07:24:20.660] <TB1> INFO: Expecting 41600 events.
[07:24:24.395] <TB1> INFO: 41600 events read in total (3179ms).
[07:24:24.396] <TB1> INFO: Test took 4004ms.
[07:24:24.627] <TB1> INFO: PixTestAlive::aliveTest() done
[07:24:24.627] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:24:24.628] <TB1> INFO: ----------------------------------------------------------------------
[07:24:24.628] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:24:24.628] <TB1> INFO: ----------------------------------------------------------------------
[07:24:24.890] <TB1> INFO: Expecting 41600 events.
[07:24:27.825] <TB1> INFO: 41600 events read in total (2378ms).
[07:24:27.825] <TB1> INFO: Test took 3196ms.
[07:24:27.826] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[07:24:28.077] <TB1> INFO: PixTestAlive::maskTest() done
[07:24:28.077] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:24:28.078] <TB1> INFO: ----------------------------------------------------------------------
[07:24:28.078] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:24:28.078] <TB1> INFO: ----------------------------------------------------------------------
[07:24:28.349] <TB1> INFO: Expecting 41600 events.
[07:24:32.179] <TB1> INFO: 41600 events read in total (3273ms).
[07:24:32.179] <TB1> INFO: Test took 4099ms.
[07:24:32.405] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[07:24:32.405] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:24:32.405] <TB1> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[07:24:32.405] <TB1> INFO: Decoding statistics:
[07:24:32.405] <TB1> INFO: General information:
[07:24:32.405] <TB1> INFO: 16bit words read: 0
[07:24:32.405] <TB1> INFO: valid events total: 0
[07:24:32.405] <TB1> INFO: empty events: 0
[07:24:32.405] <TB1> INFO: valid events with pixels: 0
[07:24:32.405] <TB1> INFO: valid pixel hits: 0
[07:24:32.405] <TB1> INFO: Event errors: 0
[07:24:32.405] <TB1> INFO: start marker: 0
[07:24:32.405] <TB1> INFO: stop marker: 0
[07:24:32.405] <TB1> INFO: overflow: 0
[07:24:32.405] <TB1> INFO: invalid 5bit words: 0
[07:24:32.405] <TB1> INFO: invalid XOR eye diagram: 0
[07:24:32.405] <TB1> INFO: frame (failed synchr.): 0
[07:24:32.405] <TB1> INFO: idle data (no TBM trl): 0
[07:24:32.405] <TB1> INFO: no data (only TBM hdr): 0
[07:24:32.405] <TB1> INFO: TBM errors: 0
[07:24:32.405] <TB1> INFO: flawed TBM headers: 0
[07:24:32.405] <TB1> INFO: flawed TBM trailers: 0
[07:24:32.405] <TB1> INFO: event ID mismatches: 0
[07:24:32.405] <TB1> INFO: ROC errors: 0
[07:24:32.405] <TB1> INFO: missing ROC header(s): 0
[07:24:32.405] <TB1> INFO: misplaced readback start: 0
[07:24:32.405] <TB1> INFO: Pixel decoding errors: 0
[07:24:32.405] <TB1> INFO: pixel data incomplete: 0
[07:24:32.405] <TB1> INFO: pixel address: 0
[07:24:32.405] <TB1> INFO: pulse height fill bit: 0
[07:24:32.405] <TB1> INFO: buffer corruption: 0
[07:24:32.419] <TB1> INFO: ######################################################################
[07:24:32.419] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[07:24:32.419] <TB1> INFO: ######################################################################
[07:24:32.421] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[07:24:32.432] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[07:24:32.432] <TB1> INFO: run 1 of 1
[07:24:32.691] <TB1> INFO: Expecting 3120000 events.
[07:25:13.558] <TB1> INFO: 845570 events read in total (40311ms).
[07:25:53.333] <TB1> INFO: 1681940 events read in total (80086ms).
[07:26:33.731] <TB1> INFO: 2531765 events read in total (120484ms).
[07:27:02.135] <TB1> INFO: 3120000 events read in total (148888ms).
[07:27:02.199] <TB1> INFO: Test took 149767ms.
[07:27:28.619] <TB1> INFO: PixTestBBMap::doTest() done, duration: 176 seconds
[07:27:28.619] <TB1> INFO: number of dead bumps (per ROC): 4 1 0 2 0 0 0 1 5 1 1 1 7 3 1 0
[07:27:28.619] <TB1> INFO: separation cut (per ROC): 85 93 97 84 71 72 88 79 101 94 84 102 82 98 82 91
[07:27:28.619] <TB1> INFO: Decoding statistics:
[07:27:28.619] <TB1> INFO: General information:
[07:27:28.619] <TB1> INFO: 16bit words read: 0
[07:27:28.619] <TB1> INFO: valid events total: 0
[07:27:28.619] <TB1> INFO: empty events: 0
[07:27:28.620] <TB1> INFO: valid events with pixels: 0
[07:27:28.620] <TB1> INFO: valid pixel hits: 0
[07:27:28.620] <TB1> INFO: Event errors: 0
[07:27:28.620] <TB1> INFO: start marker: 0
[07:27:28.620] <TB1> INFO: stop marker: 0
[07:27:28.620] <TB1> INFO: overflow: 0
[07:27:28.620] <TB1> INFO: invalid 5bit words: 0
[07:27:28.620] <TB1> INFO: invalid XOR eye diagram: 0
[07:27:28.620] <TB1> INFO: frame (failed synchr.): 0
[07:27:28.620] <TB1> INFO: idle data (no TBM trl): 0
[07:27:28.620] <TB1> INFO: no data (only TBM hdr): 0
[07:27:28.620] <TB1> INFO: TBM errors: 0
[07:27:28.620] <TB1> INFO: flawed TBM headers: 0
[07:27:28.620] <TB1> INFO: flawed TBM trailers: 0
[07:27:28.620] <TB1> INFO: event ID mismatches: 0
[07:27:28.620] <TB1> INFO: ROC errors: 0
[07:27:28.620] <TB1> INFO: missing ROC header(s): 0
[07:27:28.620] <TB1> INFO: misplaced readback start: 0
[07:27:28.620] <TB1> INFO: Pixel decoding errors: 0
[07:27:28.620] <TB1> INFO: pixel data incomplete: 0
[07:27:28.620] <TB1> INFO: pixel address: 0
[07:27:28.620] <TB1> INFO: pulse height fill bit: 0
[07:27:28.620] <TB1> INFO: buffer corruption: 0
[07:27:28.691] <TB1> INFO: ######################################################################
[07:27:28.691] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[07:27:28.691] <TB1> INFO: ######################################################################
[07:27:28.692] <TB1> INFO: ----------------------------------------------------------------------
[07:27:28.692] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[07:27:28.692] <TB1> INFO: ----------------------------------------------------------------------
[07:27:28.692] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[07:27:28.700] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[07:27:28.700] <TB1> INFO: run 1 of 1
[07:27:28.977] <TB1> INFO: Expecting 26208000 events.
[07:28:02.813] <TB1> INFO: 905800 events read in total (33280ms).
[07:28:35.939] <TB1> INFO: 1798550 events read in total (66406ms).
[07:29:08.717] <TB1> INFO: 2687750 events read in total (99184ms).
[07:29:41.803] <TB1> INFO: 3575200 events read in total (132270ms).
[07:30:14.518] <TB1> INFO: 4459500 events read in total (164985ms).
[07:30:47.468] <TB1> INFO: 5344150 events read in total (197935ms).
[07:31:10.374] <TB1> WARNING: Channel 2 ROC 1: Readback start marker after 9 readouts!

[07:31:10.374] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:31:10.374] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2cb 8040 4831 4831 4831 4831 e022 c000

[07:31:10.374] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2c5 80c0 4810 4810 4810 14a 26c0 4810 e022 c000

[07:31:10.374] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2c6 8000 4830 4830 4830 14a 26ae 4810 e022 c000

[07:31:10.374] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2c7 8040 4830 4830 4830 4830 e022 c000

[07:31:10.374] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2c8 80b1 4830 4832 4830 14a 26c0 4810 e022 c000

[07:31:10.374] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2c9 80c0 4831 4831 4831 14a 26c0 4811 e022 c000

[07:31:10.374] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2ca 8000 4810 4830 4830 14a 26c0 4810 e022 c000

[07:31:10.374] <TB1> WARNING: Channel 2 ROC 1: Readback start marker after 7 readouts!

[07:31:10.374] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:31:10.374] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d2 8000 4830 4830 4811 14a 26c0 4831 e022 c000

[07:31:10.374] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2cc 80b1 4810 4810 4810 14a 26c0 4810 e022 c000

[07:31:10.374] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2cd 80c0 4810 4810 4810 14a 26c0 4810 e022 c000

[07:31:10.374] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2ce 8000 4810 4810 4810 14a 26af 4810 e022 c000

[07:31:10.374] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2cf 8040 4813 4813 4813 14a 26c0 4813 e022 c000

[07:31:10.374] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d0 80b1 4811 4831 4831 4831 e022 c000

[07:31:10.374] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2d1 80c0 4810 4810 4810 4810 e022 c000

[07:31:20.423] <TB1> INFO: 6229400 events read in total (230890ms).
[07:31:43.871] <TB1> ERROR: <datapipe.cc/CheckEventID:L459> Channel 2 Event ID mismatch: local ID (9) != TBM ID (10)

[07:31:43.871] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:31:43.871] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a20d 80c0 4811 212 22a8 4831 212 22ac 4811 212 22c8 4811 212 22a5 e022 c000

[07:31:43.871] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a206 8000 4830 212 22a7 4810 212 22aa 4810 212 22c7 4810 212 22a5 e022 c000

[07:31:43.871] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a207 8040 4830 212 22a8 4830 212 22ab 4830 212 22c7 4810 212 22a5 e022 c000

[07:31:43.871] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a208 80b1 4810 212 22a9 4830 212 22ad 4810 212 22c7 4830 212 22a5 e022 c000

[07:31:43.871] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a20a 8000 4831 212 22a7 4c31 212 22ab 4831 212 22c7 4831 212 22a5 e022 c000

[07:31:43.871] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a20b 8040 4811 212 22a8 4811 212 22ac 4831 212 22c6 4831 212 22a5 e022 c000

[07:31:43.871] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a20c 80b1 4831 212 22a8 4831 212 22ac 4831 212 22c7 4811 212 22a5 e022 c000

[07:31:43.871] <TB1> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!

[07:31:43.871] <TB1> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

[07:31:43.871] <TB1> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[07:31:43.871] <TB1> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[07:31:43.871] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:31:43.871] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a212 8000 4830 212 22a8 4830 212 22ad 4831 212 22c7 4831 212 22a5 e022 c000

[07:31:43.871] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a20c 80b1 4831 212 22a8 4831 212 22ac 4831 212 22c7 4811 212 22a5 e022 c000

[07:31:43.871] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a20d 80c0 4811 212 22a8 4831 212 22ac 4811 212 22c8 4811 212 22a5 e022 c000

[07:31:43.871] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a20e 8000 4831 212 22a9 4811 212 22ab 4831 212 22c7 4811 212 22a4 e022 c000

[07:31:43.871] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a20f 8040 4813 212 22a8 4833 212 22ad 4813 212 22c6 4813 212 22a5 e022 c000

[07:31:43.871] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a210 80b1 4831 212 22a8 4811 212 22ad 4831 212 22c7 4831 212 22a6 e022 c000

[07:31:43.871] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a211 80c0 4830 212 22a7 4830 212 22aa 4830 212 22c6 4810 212 22a4 e022 c000

[07:31:53.359] <TB1> INFO: 7112350 events read in total (263826ms).
[07:32:26.271] <TB1> INFO: 7996500 events read in total (296738ms).
[07:32:59.116] <TB1> INFO: 8881600 events read in total (329583ms).
[07:33:31.862] <TB1> INFO: 9768450 events read in total (362329ms).
[07:34:04.403] <TB1> INFO: 10652200 events read in total (394870ms).
[07:34:22.719] <TB1> WARNING: Channel 0 ROC 0: Readback start marker after 4 readouts!

[07:34:22.719] <TB1> WARNING: Channel 2 ROC 0: Readback start marker after 4 readouts!

[07:34:22.719] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:34:22.719] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e6 8000 4c10 302 2aad 4c00 302 2ac0 4c10 302 2a87 4810 302 2ac5 e022 c000

[07:34:22.719] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e0 80b1 4c10 302 2aad 4c00 302 2aae 4c00 302 2a86 4800 302 2ac6 e022 c000

[07:34:22.719] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e1 80c0 4c00 302 2aad 4c10 302 2aae 4c00 302 2a86 4c10 302 2ac5 e022 c000

[07:34:22.719] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e2 8000 4800 302 2aac 4c10 302 2aaf 4c11 302 2a87 4c11 302 2ac5 e022 c000

[07:34:22.719] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e3 8040 4c12 302 2aae 4c11 302 2ac0 4800 302 2a86 4c01 302 2ac5 e022 c000

[07:34:22.719] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e4 80b1 4800 302 2aae 4c10 302 2ac1 4c10 302 2a87 4810 302 2ac5 e022 c000

[07:34:22.719] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e5 80c0 4810 302 2aad 4c10 302 2aaf 4810 302 2a87 4c10 302 2ac5 e022 c000

[07:34:22.719] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:34:22.719] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2e6 8000 4830 302 2aa5 4810 302 2aad 4830 302 2ac9 4810 302 2aa5 e022 c000

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2e0 80b1 4811 302 2aa6 4831 302 2aaf 4811 302 2ac9 4811 302 2aa5 e022 c000

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2e1 80c0 4830 302 2aa5 4830 302 2aad 4830 302 2ac9 4830 302 2aa5 e022 c000

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2e2 8000 4810 302 2aa6 4830 302 2aad 4811 302 2ac9 4831 302 2aa5 e022 c000

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2e3 8040 4832 302 2aa6 4831 302 2aad 4810 302 2ac9 4811 302 2aa5 e022 c000

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2e4 80b1 4810 302 2aa6 4830 302 2aad 4830 302 2aca 4810 302 2aa6 e022 c000

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2e5 80c0 4810 302 2aa6 4830 302 2aaf 4810 302 2ac9 4830 302 2aa6 e022 c000

[07:34:22.720] <TB1> WARNING: Channel 0 ROC 0: Readback start marker after 12 readouts!

[07:34:22.720] <TB1> WARNING: Channel 2 ROC 0: Readback start marker after 12 readouts!

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f2 8000 4c10 302 2aae 4c10 302 2aad 4c11 302 2a86 4811 302 2ac5 e022 c000

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ec 80b1 4c11 302 2aad 4811 302 2ac0 4c11 302 2a87 4c01 302 2ac5 e022 c000

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ed 80c0 4c00 302 2ac0 4c10 302 2aad 4c00 302 2a87 4800 302 2ac5 e022 c000

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ee 8000 4c11 302 2aac 4c01 302 2ac0 4c01 302 2a86 4801 302 2ac5 e022 c000

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ef 8040 4c13 302 2aad 4c13 302 2aae 4c13 302 2a87 4803 302 2ac5 e022 c000

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f0 80b1 4c10 302 2aae 4c10 302 2aae 4c10 302 2a87 4c10 302 2ac5 e022 c000

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f1 80c0 4810 302 2ac0 4c10 302 2aaf 4c10 302 2a86 4c10 302 2ac5 e022 c000

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2f2 8000 4830 302 2aa5 4830 302 2aae 4831 302 2acc 4811 302 2aa5 e022 c000

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2ec 80b1 4831 302 2aa5 4811 302 2aae 4831 302 2ac9 4811 302 2aa6 e022 c000

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2ed 80c0 4810 302 2aa6 4830 302 2aad 4810 302 2aca 4810 302 2aa5 e022 c000

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2ee 8000 4831 302 2aa6 4811 302 2aaf 4811 302 2aca 4811 302 2aa7 e022 c000

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2ef 8040 4833 302 2aa6 4833 302 2aad 4833 302 2ac9 4813 302 2aa4 e022 c000

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2f0 80b1 4831 302 2aa6 4831 302 2aad 4831 302 2ac9 4831 302 2aa5 e022 c000

[07:34:22.720] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2f1 80c0 4810 302 2aa5 4830 302 2aaf 4830 302 2acc 4830 302 2aa5 e022 c000

[07:34:37.220] <TB1> INFO: 11533600 events read in total (427687ms).
[07:35:10.300] <TB1> INFO: 12417150 events read in total (460767ms).
[07:35:43.075] <TB1> INFO: 13297750 events read in total (493542ms).
[07:36:15.911] <TB1> INFO: 14172500 events read in total (526378ms).
[07:36:48.752] <TB1> INFO: 15043100 events read in total (559219ms).
[07:37:21.441] <TB1> INFO: 15915400 events read in total (591908ms).
[07:37:54.291] <TB1> INFO: 16786200 events read in total (624758ms).
[07:38:27.126] <TB1> INFO: 17655550 events read in total (657593ms).
[07:38:59.967] <TB1> INFO: 18525000 events read in total (690434ms).
[07:39:32.635] <TB1> INFO: 19394450 events read in total (723102ms).
[07:39:41.853] <TB1> ERROR: <datapipe.cc/CheckEventID:L459> Channel 2 Event ID mismatch: local ID (125) != TBM ID (126)

[07:39:41.853] <TB1> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!

[07:39:41.853] <TB1> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

[07:39:41.853] <TB1> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[07:39:41.853] <TB1> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[07:39:41.853] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:39:41.853] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a282 8000 4810 4810 4811 4831 e022 c000

[07:39:41.853] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a27b 8040 4830 4830 4830 64c 20c5 4810 e022 c000

[07:39:41.853] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a27c 80b1 4831 4831 4831 4831 e022 c000

[07:39:41.853] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a27e 8000 4810 4810 4810 4810 e022 c000

[07:39:41.853] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a27f 8040 4813 4813 4813 4813 e022 c000

[07:39:41.853] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a280 80b1 4811 4811 4811 4811 e022 c000

[07:39:41.853] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a281 80c0 4810 4810 4810 4810 e022 c000

[07:39:51.648] <TB1> WARNING: Channel 2 ROC 3: Readback start marker after 2 readouts!

[07:39:51.648] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:39:51.648] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2b4 80b1 4830 658 2aac 4830 658 2aaf 4830 658 2ac9 4830 658 2aad e022 c000

[07:39:51.648] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2ae 8000 4831 658 2aac 4831 658 2aaf 4831 658 2aca 4831 658 2aad e022 c000

[07:39:51.648] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2af 8040 4833 658 2aac 4833 658 2aaf 4833 658 2acc 4813 658 2aad e022 c000

[07:39:51.648] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2b0 80b1 4831 658 2aac 4831 658 2aaf 4831 658 2aca 4831 658 2aad e022 c000

[07:39:51.648] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2b1 80c0 4810 658 2aac 4810 658 2aaf 4830 658 2acb 4832 658 2aac e022 c000

[07:39:51.648] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2b2 8000 4830 658 2aac 4810 658 2aaf 4831 658 2acc 4811 658 2aac e022 c000

[07:39:51.648] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2b3 8040 4810 658 2aac 4831 658 2aad 4830 658 2acc 4831 658 2aad e022 c000

[07:39:51.648] <TB1> WARNING: Channel 2 ROC 3: Readback start marker after 14 readouts!

[07:39:51.648] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:39:51.648] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2c2 8000 4830 658 2aaa 4810 658 2aaf 4811 658 2ac9 4831 658 2aad e022 c000

[07:39:51.648] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2bc 80b1 4830 658 2aac 4830 658 2aad 4810 658 2acc 4810 658 2aad e022 c000

[07:39:51.648] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2bd 80c0 4831 658 2aac 4831 658 2aaf 4831 658 2acc 4831 658 2aad e022 c000

[07:39:51.648] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2be 8000 4830 658 2aaa 4810 658 2aae 4830 658 2aca 4830 658 2aac e022 c000

[07:39:51.648] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2bf 8040 4832 658 2aad 4832 658 2aaf 4832 658 2acc 4832 658 2aad e022 c000

[07:39:51.648] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2c0 80b1 4831 658 2aac 4831 658 2aad 4831 658 2aca 4831 658 2aad e022 c000

[07:39:51.648] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2c1 80c0 4830 658 2aac 4830 658 2ac0 4810 658 2acc 4810 658 2aad e022 c000

[07:40:05.116] <TB1> INFO: 20264650 events read in total (755583ms).
[07:40:37.902] <TB1> INFO: 21130300 events read in total (788369ms).
[07:41:10.976] <TB1> INFO: 22000150 events read in total (821443ms).
[07:41:43.511] <TB1> INFO: 22870950 events read in total (853978ms).
[07:42:16.073] <TB1> INFO: 23742900 events read in total (886540ms).
[07:42:45.097] <TB1> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (112) != TBM ID (113)

[07:42:45.097] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:42:45.097] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a074 80b1 4c00 4c00 4c00 4c00 e022 c000

[07:42:45.097] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06d 80c0 4800 4800 4800 4800 e022 c000

[07:42:45.097] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06e 8000 4800 4800 4800 4810 e022 c000

[07:42:45.097] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a06f 8040 4c02 4c02 4c02 4c02 e022 c000

[07:42:45.097] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a071 80c0 4c00 4c00 4c00 4c10 e022 c000

[07:42:45.097] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a072 8000 4c10 4c10 4c11 4c11 e022 c000

[07:42:45.097] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a073 8040 4c00 4c01 4c00 4c01 e022 c000

[07:42:45.098] <TB1> WARNING: Channel 0 ROC 0: Readback start marker after 15 readouts!

[07:42:45.098] <TB1> WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!

[07:42:45.098] <TB1> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

[07:42:45.098] <TB1> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[07:42:45.098] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:42:45.098] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a082 8000 4c00 4c00 4c01 4c01 e022 c000

[07:42:45.098] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07c 80b1 4c10 4c00 4c00 4c10 e022 c000

[07:42:45.098] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07d 80c0 4c00 4c00 4c00 4c00 e022 c000

[07:42:45.098] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07e 8000 4c00 4c00 4c00 4c00 e022 c000

[07:42:45.098] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a07f 8040 4c02 4c02 4c02 4c12 e022 c000

[07:42:45.098] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a080 80b1 4c00 4c00 4c00 4c10 e022 c000

[07:42:45.098] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a081 80c0 4c00 4c00 4c00 4c00 e022 c000

[07:42:48.579] <TB1> INFO: 24612950 events read in total (919046ms).
[07:43:14.321] <TB1> ERROR: <datapipe.cc/CheckEventID:L459> Channel 2 Event ID mismatch: local ID (126) != TBM ID (127)

[07:43:14.322] <TB1> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!

[07:43:14.322] <TB1> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

[07:43:14.322] <TB1> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[07:43:14.322] <TB1> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[07:43:14.322] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:43:14.322] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a282 8000 4810 860 24c2 4830 860 24ca 4811 860 24e3 4831 860 24c0 e022 c000

[07:43:14.322] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a27b 8040 4810 860 24c4 4830 860 24ca 4810 860 24e3 4830 860 24c0 e022 c000

[07:43:14.322] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a27c 80b1 4810 860 24c4 4810 860 24ca 4810 860 24e2 4810 860 24c0 e022 c000

[07:43:14.322] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a27d 80c0 4830 860 24c2 4810 860 24ca 4830 860 24e2 4830 860 24c1 e022 c000

[07:43:14.322] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a27f 8040 4812 860 24c4 4832 860 24ca 4812 860 24e2 4812 860 24c1 e022 c000

[07:43:14.322] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a280 80b1 4831 860 24c4 4831 860 24c9 4811 860 24e1 4811 860 24c1 e022 c000

[07:43:14.322] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a281 80c0 4810 860 24c4 4830 860 24c9 4810 860 24e3 4830 860 24c1 e022 c000

[07:43:21.629] <TB1> INFO: 25485350 events read in total (952096ms).
[07:43:48.316] <TB1> INFO: 26208000 events read in total (978783ms).
[07:43:48.345] <TB1> INFO: Test took 979645ms.
[07:43:48.597] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:43:50.078] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:43:51.509] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:43:52.912] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:43:54.326] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:43:55.970] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:43:57.393] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:43:58.849] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:44:00.477] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:44:01.998] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:44:03.376] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:44:04.916] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:44:06.688] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:44:08.103] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:44:09.545] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:44:10.966] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[07:44:12.357] <TB1> INFO: PixTestScurves::scurves() done
[07:44:12.357] <TB1> INFO: Vcal mean: 89.77 89.91 102.97 85.31 77.84 79.79 94.80 92.97 104.58 95.84 81.09 100.06 86.49 96.93 88.27 90.86
[07:44:12.357] <TB1> INFO: Vcal RMS: 5.45 5.99 6.06 5.26 4.67 4.03 5.98 5.29 6.14 5.49 4.65 5.68 5.20 5.70 5.08 5.80
[07:44:12.357] <TB1> INFO: PixTestScurves::fullTest() done, duration: 1003 seconds
[07:44:12.357] <TB1> INFO: Decoding statistics:
[07:44:12.357] <TB1> INFO: General information:
[07:44:12.357] <TB1> INFO: 16bit words read: 0
[07:44:12.357] <TB1> INFO: valid events total: 0
[07:44:12.357] <TB1> INFO: empty events: 0
[07:44:12.357] <TB1> INFO: valid events with pixels: 0
[07:44:12.357] <TB1> INFO: valid pixel hits: 0
[07:44:12.357] <TB1> INFO: Event errors: 0
[07:44:12.357] <TB1> INFO: start marker: 0
[07:44:12.357] <TB1> INFO: stop marker: 0
[07:44:12.357] <TB1> INFO: overflow: 0
[07:44:12.357] <TB1> INFO: invalid 5bit words: 0
[07:44:12.357] <TB1> INFO: invalid XOR eye diagram: 0
[07:44:12.357] <TB1> INFO: frame (failed synchr.): 0
[07:44:12.357] <TB1> INFO: idle data (no TBM trl): 0
[07:44:12.357] <TB1> INFO: no data (only TBM hdr): 0
[07:44:12.357] <TB1> INFO: TBM errors: 0
[07:44:12.357] <TB1> INFO: flawed TBM headers: 0
[07:44:12.357] <TB1> INFO: flawed TBM trailers: 0
[07:44:12.357] <TB1> INFO: event ID mismatches: 0
[07:44:12.357] <TB1> INFO: ROC errors: 0
[07:44:12.357] <TB1> INFO: missing ROC header(s): 0
[07:44:12.357] <TB1> INFO: misplaced readback start: 0
[07:44:12.357] <TB1> INFO: Pixel decoding errors: 0
[07:44:12.357] <TB1> INFO: pixel data incomplete: 0
[07:44:12.357] <TB1> INFO: pixel address: 0
[07:44:12.357] <TB1> INFO: pulse height fill bit: 0
[07:44:12.357] <TB1> INFO: buffer corruption: 0
[07:44:12.426] <TB1> INFO: ######################################################################
[07:44:12.426] <TB1> INFO: PixTestTrim::doTest()
[07:44:12.426] <TB1> INFO: ######################################################################
[07:44:12.427] <TB1> INFO: ----------------------------------------------------------------------
[07:44:12.427] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[07:44:12.427] <TB1> INFO: ----------------------------------------------------------------------
[07:44:12.505] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[07:44:12.505] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[07:44:12.513] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[07:44:12.513] <TB1> INFO: run 1 of 1
[07:44:12.772] <TB1> INFO: Expecting 6281600 events.
[07:44:51.669] <TB1> WARNING: Channel 2 ROC 3: Readback start marker after 6 readouts!

[07:44:51.670] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:44:51.670] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a258 80b1 4810 4810 4810 4810 e022 c000

[07:44:51.670] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a252 8000 4810 4810 4811 4811 e022 c000

[07:44:51.670] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a253 8040 4810 4811 4810 4811 e022 c000

[07:44:51.670] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a254 80b1 4810 4810 4810 4810 e022 c000

[07:44:51.670] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a255 80c0 4810 4810 4810 4832 e022 c000

[07:44:51.670] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a256 8000 4810 4810 4810 4810 e022 c000

[07:44:51.670] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a257 8040 4810 4810 4810 4810 e022 c000

[07:44:51.670] <TB1> WARNING: Channel 2 ROC 3: Readback start marker after 10 readouts!

[07:44:51.670] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:44:51.670] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a262 8000 4810 4810 4811 4831 e022 c000

[07:44:51.670] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a25c 80b1 4831 4831 4831 4831 e022 c000

[07:44:51.670] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a25d 80c0 4810 4810 4810 4810 e022 c000

[07:44:51.670] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a25e 8000 4810 4810 4810 4810 e022 c000

[07:44:51.670] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a25f 8040 4812 4812 4812 4812 e022 c000

[07:44:51.670] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a260 80b1 4831 4831 4811 4811 e022 c000

[07:44:51.670] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a261 80c0 4810 4810 4810 4810 e022 c000

[07:44:52.172] <TB1> INFO: 1065480 events read in total (38840ms).
[07:45:30.686] <TB1> INFO: 2125000 events read in total (77354ms).
[07:45:49.090] <TB1> WARNING: Channel 2 ROC 0: Readback start marker after 3 readouts!

[07:45:49.090] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:45:49.090] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a275 80c0 4810 4810 4810 4810 e022 c000

[07:45:49.090] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a26f 8040 4832 4832 4832 4832 e022 c000

[07:45:49.090] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a270 80b1 4811 4811 4811 4831 e022 c000

[07:45:49.091] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a271 80c0 4810 4810 4810 4830 e022 c000

[07:45:49.091] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a272 8000 4832 4810 4811 4811 e022 c000

[07:45:49.091] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a273 8040 4810 4811 4810 4811 e022 c000

[07:45:49.091] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a274 80b1 4810 4810 4810 4810 e022 c000

[07:45:49.091] <TB1> WARNING: Channel 2 ROC 0: Readback start marker after 13 readouts!

[07:45:49.091] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:45:49.091] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a282 8000 4810 4810 4811 4811 e022 c000

[07:45:49.091] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a27c 80b1 4810 4810 4810 4810 e022 c000

[07:45:49.091] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a27d 80c0 4810 4810 4810 4810 e022 c000

[07:45:49.091] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a27e 8000 4810 4810 4810 4810 e022 c000

[07:45:49.091] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a27f 8040 4813 4813 4813 4813 e022 c000

[07:45:49.091] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a280 80b1 4811 4811 4811 4811 e022 c000

[07:45:49.091] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a281 80c0 4810 4810 4810 4810 e022 c000

[07:46:09.600] <TB1> INFO: 3178350 events read in total (116269ms).
[07:46:48.618] <TB1> INFO: 4240700 events read in total (155286ms).
[07:47:27.893] <TB1> INFO: 5308150 events read in total (194561ms).
[07:48:03.836] <TB1> INFO: 6281600 events read in total (230504ms).
[07:48:03.878] <TB1> INFO: Test took 231365ms.
[07:48:25.607] <TB1> INFO: ROC 0 VthrComp = 96
[07:48:25.607] <TB1> INFO: ROC 1 VthrComp = 94
[07:48:25.607] <TB1> INFO: ROC 2 VthrComp = 101
[07:48:25.607] <TB1> INFO: ROC 3 VthrComp = 88
[07:48:25.607] <TB1> INFO: ROC 4 VthrComp = 79
[07:48:25.607] <TB1> INFO: ROC 5 VthrComp = 84
[07:48:25.607] <TB1> INFO: ROC 6 VthrComp = 92
[07:48:25.607] <TB1> INFO: ROC 7 VthrComp = 93
[07:48:25.607] <TB1> INFO: ROC 8 VthrComp = 100
[07:48:25.607] <TB1> INFO: ROC 9 VthrComp = 99
[07:48:25.608] <TB1> INFO: ROC 10 VthrComp = 85
[07:48:25.608] <TB1> INFO: ROC 11 VthrComp = 102
[07:48:25.608] <TB1> INFO: ROC 12 VthrComp = 86
[07:48:25.608] <TB1> INFO: ROC 13 VthrComp = 97
[07:48:25.608] <TB1> INFO: ROC 14 VthrComp = 92
[07:48:25.608] <TB1> INFO: ROC 15 VthrComp = 93
[07:48:25.608] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[07:48:25.608] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[07:48:25.619] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[07:48:25.619] <TB1> INFO: run 1 of 1
[07:48:25.919] <TB1> INFO: Expecting 6281600 events.
[07:49:01.328] <TB1> INFO: 756810 events read in total (34852ms).
[07:49:36.107] <TB1> INFO: 1511630 events read in total (69631ms).
[07:50:11.172] <TB1> INFO: 2267070 events read in total (104696ms).
[07:50:46.287] <TB1> INFO: 3022780 events read in total (139811ms).
[07:51:21.237] <TB1> INFO: 3772920 events read in total (174761ms).
[07:51:55.997] <TB1> INFO: 4519920 events read in total (209521ms).
[07:52:08.022] <TB1> WARNING: Channel 0 ROC 0: Readback start marker after 5 readouts!

[07:52:08.022] <TB1> WARNING: Channel 2 ROC 0: Readback start marker after 5 readouts!

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a097 8040 4c10 65c 24ac 4c10 65c 24cd 4c10 65c 248d 4c10 65c 24c9 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a091 80c0 4c10 65c 24ac 4c00 65c 24ca 4c00 65c 248c 4c10 65c 24c9 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a092 8000 4c10 65c 24ac 4c10 65c 24c9 4c11 65c 248d 4c11 65c 24c8 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a093 8040 4c10 65c 24aa 4c01 65c 24c9 4c00 65c 248d 4c11 65c 24c9 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a094 80b1 4c12 65c 24ac 4c10 65c 24ca 4c10 65c 248f 4c10 65c 24c9 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a095 80c0 4810 65c 24aa 4c10 65c 24c9 4c00 65c 248d 4c10 65c 24c7 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a096 8000 4810 65c 24ac 4c00 65c 24c9 4c10 65c 248d 4c10 65c 24c8 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a297 8040 4830 65c 24c4 4830 65c 24c1 4830 65c 24cf 4810 65c 24c0 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a291 80c0 4830 65c 24c3 4810 65c 24c0 4810 65c 24e0 4810 65c 24c1 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a292 8000 4830 65c 24c4 4810 65c 24c1 4831 65c 24cf 4831 65c 24c1 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a293 8040 4830 65c 24c3 4811 65c 24c1 4830 65c 24cf 4831 65c 24c1 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a294 80b1 4812 65c 24c4 4830 65c 24c1 4830 65c 24e0 4830 65c 24c1 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a295 80c0 4810 65c 24c5 4830 65c 24c1 4830 65c 24e0 4830 65c 24c1 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a296 8000 4810 65c 24c4 4830 65c 24c0 4830 65c 24cf 4830 65c 24c2 e022 c000

[07:52:08.022] <TB1> WARNING: Channel 0 ROC 0: Readback start marker after 11 readouts!

[07:52:08.022] <TB1> WARNING: Channel 2 ROC 0: Readback start marker after 11 readouts!

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a2 8000 4c10 65c 24ac 4c10 65c 24c9 4c11 65c 248d 4c11 65c 24c9 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09c 80b1 4c11 65c 24aa 4c11 65c 24c9 4c11 65c 248d 4c11 65c 24c9 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09d 80c0 4811 65c 24ac 4c01 65c 24cc 4c11 65c 248e 4c11 65c 24c7 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09e 8000 4c10 65c 24ac 4810 65c 24c9 4c10 65c 248d 4810 65c 24c8 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a09f 8040 4c13 65c 24ac 4c13 65c 24ca 4c13 65c 248e 4813 65c 24c9 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a0 80b1 4c10 65c 24ac 4c10 65c 24c9 4c10 65c 248d 4c10 65c 24c9 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0a1 80c0 4c10 65c 24ac 4c10 65c 24c8 4c00 65c 248d 4810 65c 24c9 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2a2 8000 4830 65c 24c3 4830 65c 24c2 4831 65c 24e0 4831 65c 24c1 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a29c 80b1 4831 65c 24c2 4831 65c 24c1 4811 65c 24e0 4831 65c 24c1 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a29d 80c0 4811 65c 24c4 4811 65c 24c1 4831 65c 24e0 4811 65c 24c1 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a29e 8000 4830 65c 24c4 4810 65c 24c2 4830 65c 24cf 4810 65c 24c2 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a29f 8040 4833 65c 24c4 4833 65c 24c2 4833 65c 24e1 4813 65c 24c1 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2a0 80b1 4811 65c 24c2 4831 65c 24c2 4831 65c 24cf 4831 65c 24c1 e022 c000

[07:52:08.022] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2a1 80c0 4830 65c 24c5 4810 65c 24c1 4810 65c 24e1 4810 65c 24c1 e022 c000

[07:52:30.493] <TB1> INFO: 5265100 events read in total (244017ms).
[07:53:05.579] <TB1> INFO: 6010440 events read in total (279103ms).
[07:53:18.731] <TB1> INFO: 6281600 events read in total (292255ms).
[07:53:18.788] <TB1> INFO: Test took 293168ms.
[07:53:45.444] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 56.9652 for pixel 24/79 mean/min/max = 44.8392/31.9907/57.6877
[07:53:45.444] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 60.9458 for pixel 24/10 mean/min/max = 46.2387/31.5304/60.9471
[07:53:45.444] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 62.4489 for pixel 2/24 mean/min/max = 47.2234/31.9816/62.4653
[07:53:45.445] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 59.0739 for pixel 0/38 mean/min/max = 46.0563/32.9682/59.1444
[07:53:45.445] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 60.1308 for pixel 0/48 mean/min/max = 47.2795/34.3744/60.1845
[07:53:45.445] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 56.6175 for pixel 5/48 mean/min/max = 44.629/32.6293/56.6287
[07:53:45.445] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 62.8381 for pixel 11/79 mean/min/max = 47.8066/32.5871/63.0261
[07:53:45.446] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 59.2142 for pixel 0/3 mean/min/max = 45.7103/31.8249/59.5957
[07:53:45.446] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 63.0687 for pixel 6/79 mean/min/max = 47.143/31.1055/63.1804
[07:53:45.446] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 59.1599 for pixel 0/14 mean/min/max = 45.3377/31.511/59.1643
[07:53:45.446] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 58.2894 for pixel 6/21 mean/min/max = 45.1696/31.8241/58.5152
[07:53:45.447] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 58.5995 for pixel 11/74 mean/min/max = 45.3579/31.9626/58.7532
[07:53:45.447] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 58.7402 for pixel 20/78 mean/min/max = 45.757/32.4652/59.0487
[07:53:45.447] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 60.2515 for pixel 18/56 mean/min/max = 46.1173/31.9398/60.2948
[07:53:45.447] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 57.7101 for pixel 14/11 mean/min/max = 45.3217/32.787/57.8563
[07:53:45.448] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 60.3524 for pixel 0/66 mean/min/max = 46.1368/31.8726/60.4011
[07:53:45.448] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[07:53:45.536] <TB1> INFO: Expecting 514560 events.
[07:53:58.363] <TB1> INFO: 514560 events read in total (12271ms).
[07:53:58.368] <TB1> INFO: Expecting 514560 events.
[07:54:11.197] <TB1> INFO: 514560 events read in total (12410ms).
[07:54:11.206] <TB1> INFO: Expecting 514560 events.
[07:54:24.140] <TB1> INFO: 514560 events read in total (12525ms).
[07:54:24.150] <TB1> INFO: Expecting 514560 events.
[07:54:36.940] <TB1> INFO: 514560 events read in total (12384ms).
[07:54:36.952] <TB1> INFO: Expecting 514560 events.
[07:54:49.723] <TB1> INFO: 514560 events read in total (12357ms).
[07:54:49.739] <TB1> INFO: Expecting 514560 events.
[07:55:02.595] <TB1> INFO: 514560 events read in total (12451ms).
[07:55:02.610] <TB1> INFO: Expecting 514560 events.
[07:55:15.365] <TB1> INFO: 514560 events read in total (12343ms).
[07:55:15.381] <TB1> INFO: Expecting 514560 events.
[07:55:28.092] <TB1> INFO: 514560 events read in total (12297ms).
[07:55:28.119] <TB1> INFO: Expecting 514560 events.
[07:55:40.915] <TB1> INFO: 514560 events read in total (12393ms).
[07:55:40.937] <TB1> INFO: Expecting 514560 events.
[07:55:53.697] <TB1> INFO: 514560 events read in total (12356ms).
[07:55:53.723] <TB1> INFO: Expecting 514560 events.
[07:56:06.645] <TB1> INFO: 514560 events read in total (12519ms).
[07:56:06.670] <TB1> INFO: Expecting 514560 events.
[07:56:19.577] <TB1> INFO: 514560 events read in total (12505ms).
[07:56:19.608] <TB1> INFO: Expecting 514560 events.
[07:56:27.484] <TB1> ERROR: <datapipe.cc/CheckEventID:L459> Channel 2 Event ID mismatch: local ID (15) != TBM ID (16)

[07:56:27.484] <TB1> WARNING: Channel 2 ROC 0: Readback start marker after 31 readouts!

[07:56:27.484] <TB1> WARNING: Channel 2 ROC 1: Readback start marker after 31 readouts!

[07:56:27.484] <TB1> WARNING: Channel 2 ROC 2: Readback start marker after 31 readouts!

[07:56:27.484] <TB1> WARNING: Channel 2 ROC 3: Readback start marker after 31 readouts!

[07:56:32.491] <TB1> INFO: 514560 events read in total (12481ms).
[07:56:32.523] <TB1> INFO: Expecting 514560 events.
[07:56:45.303] <TB1> INFO: 514560 events read in total (12378ms).
[07:56:45.340] <TB1> INFO: Expecting 514560 events.
[07:56:58.227] <TB1> INFO: 514560 events read in total (12485ms).
[07:56:58.263] <TB1> INFO: Expecting 514560 events.
[07:57:10.941] <TB1> INFO: 514560 events read in total (12277ms).
[07:57:10.974] <TB1> INFO: Test took 205526ms.
[07:57:11.887] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[07:57:11.895] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[07:57:11.896] <TB1> INFO: run 1 of 1
[07:57:12.165] <TB1> INFO: Expecting 6281600 events.
[07:57:47.410] <TB1> INFO: 741890 events read in total (34688ms).
[07:58:22.134] <TB1> INFO: 1482340 events read in total (69412ms).
[07:58:56.478] <TB1> INFO: 2223260 events read in total (103756ms).
[07:59:31.257] <TB1> INFO: 2964460 events read in total (138535ms).
[08:00:05.949] <TB1> INFO: 3699220 events read in total (173227ms).
[08:00:40.677] <TB1> INFO: 4430750 events read in total (207955ms).
[08:00:54.810] <TB1> WARNING: Channel 2 ROC 1: Readback start marker after 2 readouts!

[08:00:54.810] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[08:00:54.810] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a294 80b1 4810 603 22c0 4810 603 22c6 4830 603 22c8 4830 603 22c8 e022 c000

[08:00:54.810] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a28e 8000 4811 603 22c0 4831 603 22c5 4811 603 22c7 4831 603 22c7 e022 c000

[08:00:54.810] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a28f 8040 4813 603 22c0 4813 603 22c5 4833 603 22c7 4833 603 22c7 e022 c000

[08:00:54.810] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a290 80b1 4831 603 22c0 4831 603 22c7 4831 603 22c7 4831 603 22c8 e022 c000

[08:00:54.810] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a291 80c0 4830 603 22c0 4832 603 22c5 4810 603 22c7 4810 603 22c7 e022 c000

[08:00:54.810] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a292 8000 4810 603 22c0 4810 603 22c5 4831 603 22c7 4811 603 22c8 e022 c000

[08:00:54.810] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a293 8040 4830 603 22c0 4811 603 22c6 4810 603 22c7 4831 603 22c8 e022 c000

[08:00:54.810] <TB1> WARNING: Channel 2 ROC 1: Readback start marker after 14 readouts!

[08:00:54.810] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[08:00:54.810] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2a2 8000 4830 603 22c0 4830 603 22c5 4831 603 22c7 4831 603 22c8 e022 c000

[08:00:54.810] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a29c 80b1 4831 603 22c0 4831 603 22c5 4831 603 22c7 4811 603 22c8 e022 c000

[08:00:54.810] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a29d 80c0 4810 603 22c0 4810 603 22c5 4830 603 22c7 4830 603 22c6 e022 c000

[08:00:54.810] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a29e 8000 4831 603 22c0 4831 603 22c7 4831 603 22c7 4831 603 22c8 e022 c000

[08:00:54.810] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a29f 8040 4812 603 22c0 4812 603 22c5 4812 603 22c8 4832 603 22c8 e022 c000

[08:00:54.810] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2a0 80b1 4831 603 22c0 4811 603 22c5 4831 603 22c7 4831 603 22c8 e022 c000

[08:00:54.810] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2a1 80c0 4830 603 22c0 4830 603 22c5 4810 603 22c7 4830 603 22c7 e022 c000

[08:01:15.346] <TB1> INFO: 5160120 events read in total (242624ms).
[08:01:49.810] <TB1> INFO: 5888850 events read in total (277088ms).
[08:02:08.492] <TB1> INFO: 6281600 events read in total (295770ms).
[08:02:08.549] <TB1> INFO: Test took 296653ms.
[08:02:32.922] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.023456 .. 255.000000
[08:02:32.997] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[08:02:33.005] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[08:02:33.005] <TB1> INFO: run 1 of 1
[08:02:33.265] <TB1> INFO: Expecting 10649600 events.
[08:03:08.329] <TB1> INFO: 712300 events read in total (34508ms).
[08:03:43.453] <TB1> INFO: 1424570 events read in total (69632ms).
[08:04:17.773] <TB1> INFO: 2136930 events read in total (103952ms).
[08:04:52.178] <TB1> INFO: 2849610 events read in total (138357ms).
[08:05:26.708] <TB1> INFO: 3562170 events read in total (172887ms).
[08:06:00.682] <TB1> INFO: 4274680 events read in total (206861ms).
[08:06:34.850] <TB1> INFO: 4987200 events read in total (241029ms).
[08:07:09.401] <TB1> INFO: 5699740 events read in total (275580ms).
[08:07:44.193] <TB1> INFO: 6411880 events read in total (310372ms).
[08:08:18.372] <TB1> INFO: 7123190 events read in total (344551ms).
[08:08:47.421] <TB1> WARNING: Channel 0 ROC 0: Readback start marker after 7 readouts!

[08:08:47.421] <TB1> WARNING: Channel 2 ROC 0: Readback start marker after 7 readouts!

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e9 80c0 4811 60d 22c0 4c01 60d 22c0 4c11 60d 22a1 4c11 60d 22c1 e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e3 8040 4c10 60d 22c0 4c11 60d 22ae 4810 60d 22a0 4c11 60d 22c1 e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e4 80b1 4c10 60d 22c0 4c10 60d 22c0 4c10 60d 22a1 4810 60d 22c2 e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e5 80c0 4c10 60d 22c0 4c10 60d 22c0 4810 60d 22a0 4c10 60d 22c1 e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e6 8000 4c12 60d 22c0 4c10 60d 22af 4810 60d 22a1 4c10 60d 22c2 e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e7 8040 4c10 60d 22c2 4c10 60d 22ae 4c10 60d 228f 4c00 60d 22c2 e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e8 80b1 4c10 60d 22c0 4c00 60d 22ae 4c10 60d 22a1 4c10 60d 22c1 e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2e9 80c0 4811 60d 22ac 4831 60d 22c1 4831 60d 22c4 4811 60d 22ad e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2e3 8040 4830 60d 22aa 4831 60d 22c2 4810 60d 22c4 4831 60d 22ae e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2e4 80b1 4810 60d 22aa 4810 60d 22c2 4810 60d 22c4 4810 60d 22ae e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2e5 80c0 4830 60d 22ac 4810 60d 22c1 4830 60d 22c4 4830 60d 22ae e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2e6 8000 4812 60d 22ac 4810 60d 22c1 4830 60d 22c4 4830 60d 22ad e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2e7 8040 4830 60d 22aa 4810 60d 22c2 4830 60d 22c3 4830 60d 22ad e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2e8 80b1 4830 60d 22ac 4830 60d 22c1 4830 60d 22c2 4830 60d 22ac e022 c000

[08:08:47.422] <TB1> WARNING: Channel 0 ROC 0: Readback start marker after 9 readouts!

[08:08:47.422] <TB1> WARNING: Channel 2 ROC 0: Readback start marker after 9 readouts!

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f2 8000 4c10 60d 22c0 4c10 60d 22af 4c11 60d 22a1 4c11 60d 22c3 e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ec 80b1 4c11 60d 22c0 4c11 60d 22ad 4c11 60d 22a1 4c11 60d 22c2 e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ed 80c0 4c11 60d 22c0 4c11 60d 22ad 4c11 60d 22a0 4c11 60d 22c1 e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ee 8000 4c11 60d 22c0 4c11 60d 22af 4c11 60d 22a2 4c11 60d 22c1 e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0ef 8040 4803 60d 22c0 4c13 60d 22c0 4c13 60d 22a1 4c13 60d 22c1 e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f0 80b1 4c00 60d 22c0 4810 60d 22c0 4810 60d 22a2 4810 60d 22c1 e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0f1 80c0 4c10 60d 22c0 4c10 60d 22af 4c10 60d 22a2 4810 60d 22c2 e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2f2 8000 4830 60d 22ac 4830 60d 22c1 4831 60d 22c4 4831 60d 22ad e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2ec 80b1 4831 60d 22aa 4811 60d 22c2 4831 60d 22c2 4831 60d 22af e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2ed 80c0 4831 60d 22ac 4831 60d 22c2 4811 60d 22c4 4831 60d 22ad e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2ee 8000 4831 60d 22ac 4831 60d 22c1 4831 60d 22c3 4811 60d 22ae e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2ef 8040 4813 60d 22ac 4833 60d 22c1 4813 60d 22c4 4833 60d 22ae e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2f0 80b1 4811 60d 22ac 4831 60d 22c2 4831 60d 22c4 4831 60d 22ad e022 c000

[08:08:47.422] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a2f1 80c0 4830 60d 22ac 4830 60d 22c1 4830 60d 22c3 4830 60d 22ad e022 c000

[08:08:52.690] <TB1> INFO: 7834600 events read in total (378869ms).
[08:09:27.418] <TB1> INFO: 8545150 events read in total (413597ms).
[08:09:57.999] <TB1> ERROR: <datapipe.cc/CheckEventID:L459> Channel 2 Event ID mismatch: local ID (67) != TBM ID (68)

[08:09:57.999] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[08:09:57.999] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a247 8040 4830 709 28a8 4830 709 28ad 4830 709 28c8 4810 709 28a4 e022 c000

[08:09:57.999] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a240 80b1 4831 709 28a8 4831 709 28ad 4831 709 28c7 4811 709 28a3 e022 c000

[08:09:57.999] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a241 80c0 4830 709 28a9 4830 709 28ad 4830 709 28c8 4810 709 28a5 e022 c000

[08:09:57.999] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a242 8000 4830 709 28a8 4830 709 28ad 4831 709 28c8 4831 709 28a4 e022 c000

[08:09:57.999] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a244 80b1 4810 709 28a9 4830 709 28ad 4830 709 28c7 4830 709 28a4 e022 c000

[08:09:57.999] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a245 80c0 4830 709 28a8 4810 709 28ad 4810 709 28c7 4810 709 28a4 e022 c000

[08:09:57.999] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a246 8000 4830 709 28a8 4830 709 28ad 4810 709 28c6 4830 709 28a4 e022 c000

[08:09:57.999] <TB1> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!

[08:09:57.999] <TB1> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

[08:09:57.999] <TB1> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[08:09:57.999] <TB1> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[08:09:57.999] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[08:09:57.999] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a252 8000 4830 709 28a8 4810 709 28ad 4831 709 28c8 4831 709 28a4 e022 c000

[08:09:57.999] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a24c 80b1 4810 709 28a9 4830 709 28ad 4830 709 28c8 4830 709 28a4 e022 c000

[08:09:57.999] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a24d 80c0 4831 709 28a8 4831 709 28ad 4831 709 28c7 4831 709 28a4 e022 c000

[08:09:57.999] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a24e 8000 4831 709 28a8 4831 709 28ad 4831 709 28c8 4831 709 28a3 e022 c000

[08:09:57.999] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a24f 8040 4832 709 28a9 4812 709 28ad 4832 709 28c7 4832 709 28a4 e022 c000

[08:09:57.999] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a250 80b1 4831 709 28a8 4831 709 28ad 4811 709 28c7 4831 709 28a3 e022 c000

[08:09:57.999] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a251 80c0 4810 709 28a9 4830 709 28ad 4830 709 28c9 4830 709 28a4 e022 c000

[08:10:01.948] <TB1> INFO: 9255990 events read in total (448127ms).
[08:10:36.318] <TB1> INFO: 9966750 events read in total (482497ms).
[08:11:09.392] <TB1> INFO: 10649600 events read in total (515571ms).
[08:11:09.510] <TB1> INFO: Test took 516505ms.
[08:11:42.134] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 13.767092 .. 47.644670
[08:11:42.209] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 3 .. 57 (-1/-1) hits flags = 528 (plus default)
[08:11:42.217] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[08:11:42.217] <TB1> INFO: run 1 of 1
[08:11:42.491] <TB1> INFO: Expecting 2288000 events.
[08:12:21.271] <TB1> INFO: 933420 events read in total (38224ms).
[08:12:59.691] <TB1> INFO: 1865200 events read in total (76644ms).
[08:13:16.733] <TB1> INFO: 2288000 events read in total (93686ms).
[08:13:16.748] <TB1> INFO: Test took 94531ms.
[08:13:31.629] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 19.911016 .. 45.955408
[08:13:31.704] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 9 .. 55 (-1/-1) hits flags = 528 (plus default)
[08:13:31.711] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[08:13:31.711] <TB1> INFO: run 1 of 1
[08:13:31.970] <TB1> INFO: Expecting 1955200 events.
[08:14:10.364] <TB1> INFO: 917420 events read in total (37838ms).
[08:14:42.917] <TB1> WARNING: Channel 2 ROC 0: Readback start marker after 10 readouts!

[08:14:42.917] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[08:14:42.917] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a28c 80b1 4831 6c3 22a8 4831 6c3 22ad 4831 6c3 22c6 4831 6c3 228d e022 c000

[08:14:42.917] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a286 8000 4830 6c3 22a8 4830 6c3 22ad 4830 6c3 22c6 4830 6c3 228d e022 c000

[08:14:42.917] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a287 8040 4810 6c3 22a8 4830 6c3 22ad 4830 6c3 22c7 4830 6c3 228e e022 c000

[08:14:42.917] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a288 80b1 4830 6c3 22a8 4830 6c3 22ac 4810 6c3 22c6 4810 6c3 228e e022 c000

[08:14:42.917] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a289 80c0 4832 6c3 22a8 4810 6c3 22ac 4830 6c3 22c6 4830 6c3 228d e022 c000

[08:14:42.917] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a28a 8000 4811 6c3 22a8 4811 6c3 22ad 4831 6c3 22c7 4831 6c3 228d e022 c000

[08:14:42.917] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a28b 8040 4830 6c3 22a8 4830 6c3 22ad 4830 6c3 22c7 4830 6c3 228d e022 c000

[08:14:42.917] <TB1> WARNING: Channel 2 ROC 0: Readback start marker after 6 readouts!

[08:14:42.917] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[08:14:42.917] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a292 8000 4830 6c3 22a8 4810 6c3 22ad 4811 6c3 22c7 4831 6c3 228d e022 c000

[08:14:42.917] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a28c 80b1 4831 6c3 22a8 4831 6c3 22ad 4831 6c3 22c6 4831 6c3 228d e022 c000

[08:14:42.917] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a28d 80c0 4811 6c3 22a8 4831 6c3 22ac 4831 6c3 22c7 4831 6c3 228e e022 c000

[08:14:42.917] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a28e 8000 4831 6c3 22a8 4831 6c3 22ad 4831 6c3 22c7 4831 6c3 228f e022 c000

[08:14:42.917] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a28f 8040 4813 6c3 22a8 4833 6c3 22ad 4833 6c3 22c7 4813 6c3 228d e022 c000

[08:14:42.917] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a290 80b1 4811 6c3 22a8 4831 6c3 22ad 4831 6c3 22c6 4831 6c3 228d e022 c000

[08:14:42.917] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a291 80c0 4830 6c3 22a8 4810 6c3 22ad 4830 6c3 22c7 4830 6c3 228c e022 c000

[08:14:48.538] <TB1> INFO: 1832940 events read in total (76012ms).
[08:14:53.846] <TB1> INFO: 1955200 events read in total (81320ms).
[08:14:53.857] <TB1> INFO: Test took 82145ms.
[08:15:07.361] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 2.896144 .. 44.963100
[08:15:07.438] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 2 .. 54 (-1/-1) hits flags = 528 (plus default)
[08:15:07.446] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[08:15:07.446] <TB1> INFO: run 1 of 1
[08:15:07.709] <TB1> INFO: Expecting 2204800 events.
[08:15:46.217] <TB1> INFO: 963620 events read in total (37952ms).
[08:16:24.513] <TB1> INFO: 1926570 events read in total (76248ms).
[08:16:35.793] <TB1> INFO: 2204800 events read in total (87528ms).
[08:16:35.804] <TB1> INFO: Test took 88358ms.
[08:16:49.887] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[08:16:49.887] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[08:16:49.895] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[08:16:49.895] <TB1> INFO: run 1 of 1
[08:16:50.157] <TB1> INFO: Expecting 1705600 events.
[08:17:28.062] <TB1> INFO: 878590 events read in total (37349ms).
[08:18:03.136] <TB1> INFO: 1705600 events read in total (72423ms).
[08:18:03.147] <TB1> INFO: Test took 73252ms.
[08:18:17.015] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:18:17.015] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:18:17.015] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:18:17.015] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:18:17.015] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:18:17.015] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:18:17.016] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:18:17.016] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:18:17.016] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:18:17.016] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:18:17.016] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:18:17.016] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:18:17.016] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:18:17.016] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:18:17.016] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:18:17.017] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:18:17.017] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//trimParameters35_C0.dat
[08:18:17.023] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//trimParameters35_C1.dat
[08:18:17.030] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//trimParameters35_C2.dat
[08:18:17.037] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//trimParameters35_C3.dat
[08:18:17.044] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//trimParameters35_C4.dat
[08:18:17.050] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//trimParameters35_C5.dat
[08:18:17.057] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//trimParameters35_C6.dat
[08:18:17.064] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//trimParameters35_C7.dat
[08:18:17.070] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//trimParameters35_C8.dat
[08:18:17.077] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//trimParameters35_C9.dat
[08:18:17.083] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//trimParameters35_C10.dat
[08:18:17.090] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//trimParameters35_C11.dat
[08:18:17.096] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//trimParameters35_C12.dat
[08:18:17.103] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//trimParameters35_C13.dat
[08:18:17.109] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//trimParameters35_C14.dat
[08:18:17.115] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//trimParameters35_C15.dat
[08:18:17.122] <TB1> INFO: PixTestTrim::trimTest() done
[08:18:17.122] <TB1> INFO: vtrim: 96 118 118 103 99 86 96 80 110 96 96 99 102 103 93 91
[08:18:17.122] <TB1> INFO: vthrcomp: 96 94 101 88 79 84 92 93 100 99 85 102 86 97 92 93
[08:18:17.122] <TB1> INFO: vcal mean: 34.94 34.94 34.97 35.00 35.00 35.02 34.99 35.02 34.95 34.95 34.96 35.01 34.96 34.97 34.99 34.95
[08:18:17.122] <TB1> INFO: vcal RMS: 0.85 1.00 0.93 0.86 0.84 0.83 0.91 0.87 1.02 0.87 0.86 0.85 0.86 0.90 0.83 0.88
[08:18:17.122] <TB1> INFO: bits mean: 9.12 9.69 9.42 9.29 8.58 9.60 8.69 9.31 9.26 9.72 9.69 9.47 9.51 9.49 9.38 8.83
[08:18:17.122] <TB1> INFO: bits RMS: 2.92 2.60 2.62 2.63 2.61 2.60 2.81 2.77 2.81 2.70 2.72 2.70 2.64 2.65 2.67 2.95
[08:18:17.128] <TB1> INFO: ----------------------------------------------------------------------
[08:18:17.128] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[08:18:17.128] <TB1> INFO: ----------------------------------------------------------------------
[08:18:17.129] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[08:18:17.137] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[08:18:17.138] <TB1> INFO: run 1 of 1
[08:18:17.401] <TB1> INFO: Expecting 4160000 events.
[08:18:59.610] <TB1> INFO: 933060 events read in total (41653ms).
[08:19:29.170] <TB1> WARNING: Channel 2 ROC 2: Readback start marker after 4 readouts!

[08:19:29.170] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[08:19:29.170] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a216 8000 4810 282 2aa7 4810 282 2ac3 4830 282 2ace 4830 282 2aa9 e022 c000

[08:19:29.170] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a210 80b1 4811 4c31 282 2ac4 4831 282 2acd 4831 282 2aa7 fff 2fff e022 c000

[08:19:29.170] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a211 80c0 4830 4830 282 2ac5 4830 282 2acd 4830 282 2aa9 fff 2fff e022 c000

[08:19:29.170] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a212 8000 4810 282 2aa8 4830 282 2ac4 4831 282 2acd 4831 282 2aa9 e022 c000

[08:19:29.170] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a213 8040 4830 282 2aa8 4811 282 2ac4 4832 282 2acd 4831 282 2aa9 e022 c000

[08:19:29.170] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a214 80b1 4830 4830 282 2ac4 4830 282 2ace 4830 282 2aa9 fff 2fff e022 c000

[08:19:29.170] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a215 80c0 4830 4830 282 2ac5 4810 282 2ace 4810 282 2aa9 fff 2fff e022 c000

[08:19:29.170] <TB1> WARNING: Channel 2 ROC 2: Readback start marker after 12 readouts!

[08:19:29.170] <TB1> ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:

[08:19:29.171] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a222 8000 4810 282 2aa9 4c10 282 2ac5 4831 282 2acf 4811 282 2aa9 e022 c000

[08:19:29.171] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a21c 80b1 4831 282 2aa8 4811 282 2ac4 4831 282 2ace 4831 282 2aa9 e022 c000

[08:19:29.171] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a21d 80c0 4831 282 2aa8 4831 282 2ac4 4831 282 2acf 4831 282 2aa8 e022 c000

[08:19:29.171] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a21e 8000 4831 282 2aa8 4811 282 2ac5 4811 282 2acd 4831 282 2aa8 e022 c000

[08:19:29.171] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a21f 8040 4813 282 2aa9 4813 282 2ac5 4833 282 2ace 4813 282 2aa9 e022 c000

[08:19:29.171] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a220 80b1 4831 282 2aa7 4831 282 2ac5 4831 282 2ace 4831 282 2aa9 e022 c000

[08:19:29.171] <TB1> ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a221 80c0 4830 4830 282 2ac5 4830 282 2ae0 4830 282 2aa9 fff 2fff e022 c000

[08:19:41.341] <TB1> INFO: 1860240 events read in total (83384ms).
[08:20:22.509] <TB1> INFO: 2778940 events read in total (124552ms).
[08:21:03.870] <TB1> INFO: 3695660 events read in total (165913ms).
[08:21:24.670] <TB1> INFO: 4160000 events read in total (186713ms).
[08:21:24.707] <TB1> INFO: Test took 187569ms.
[08:21:55.467] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 182 (-1/-1) hits flags = 528 (plus default)
[08:21:55.475] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[08:21:55.475] <TB1> INFO: run 1 of 1
[08:21:55.736] <TB1> INFO: Expecting 3806400 events.
[08:22:37.892] <TB1> INFO: 932075 events read in total (41600ms).
[08:23:19.151] <TB1> INFO: 1858280 events read in total (82861ms).
[08:24:00.012] <TB1> INFO: 2774760 events read in total (123720ms).
[08:24:41.899] <TB1> INFO: 3691055 events read in total (165607ms).
[08:24:47.861] <TB1> INFO: 3806400 events read in total (171569ms).
[08:24:47.892] <TB1> INFO: Test took 172418ms.
[08:25:16.971] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 169 (-1/-1) hits flags = 528 (plus default)
[08:25:16.980] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[08:25:16.980] <TB1> INFO: run 1 of 1
[08:25:17.247] <TB1> INFO: Expecting 3536000 events.
[08:25:59.962] <TB1> INFO: 960945 events read in total (42158ms).
[08:26:41.858] <TB1> INFO: 1913710 events read in total (84054ms).
[08:27:23.602] <TB1> INFO: 2856005 events read in total (125799ms).
[08:27:53.533] <TB1> INFO: 3536000 events read in total (155729ms).
[08:27:53.575] <TB1> INFO: Test took 156596ms.
[08:28:19.398] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 167 (-1/-1) hits flags = 528 (plus default)
[08:28:19.407] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[08:28:19.407] <TB1> INFO: run 1 of 1
[08:28:19.692] <TB1> INFO: Expecting 3494400 events.
[08:29:02.501] <TB1> INFO: 965095 events read in total (42253ms).
[08:29:44.297] <TB1> INFO: 1921775 events read in total (84049ms).
[08:30:26.184] <TB1> INFO: 2868315 events read in total (125937ms).
[08:30:53.852] <TB1> INFO: 3494400 events read in total (153604ms).
[08:30:53.894] <TB1> INFO: Test took 154487ms.
[08:31:21.215] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 162 (-1/-1) hits flags = 528 (plus default)
[08:31:21.224] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[08:31:21.224] <TB1> INFO: run 1 of 1
[08:31:21.516] <TB1> INFO: Expecting 3390400 events.
[08:32:04.550] <TB1> INFO: 977955 events read in total (42478ms).
[08:32:46.695] <TB1> INFO: 1946065 events read in total (84623ms).
[08:33:28.570] <TB1> INFO: 2904155 events read in total (126499ms).
[08:33:50.227] <TB1> INFO: 3390400 events read in total (148155ms).
[08:33:50.264] <TB1> INFO: Test took 149040ms.
[08:34:17.448] <TB1> INFO: PixTestTrim::trimBitTest() done
[08:34:17.449] <TB1> INFO: PixTestTrim::doTest() done, duration: 3005 seconds
[08:34:17.449] <TB1> INFO: Decoding statistics:
[08:34:17.449] <TB1> INFO: General information:
[08:34:17.449] <TB1> INFO: 16bit words read: 0
[08:34:17.449] <TB1> INFO: valid events total: 0
[08:34:17.449] <TB1> INFO: empty events: 0
[08:34:17.449] <TB1> INFO: valid events with pixels: 0
[08:34:17.449] <TB1> INFO: valid pixel hits: 0
[08:34:17.449] <TB1> INFO: Event errors: 0
[08:34:17.449] <TB1> INFO: start marker: 0
[08:34:17.449] <TB1> INFO: stop marker: 0
[08:34:17.449] <TB1> INFO: overflow: 0
[08:34:17.449] <TB1> INFO: invalid 5bit words: 0
[08:34:17.449] <TB1> INFO: invalid XOR eye diagram: 0
[08:34:17.449] <TB1> INFO: frame (failed synchr.): 0
[08:34:17.449] <TB1> INFO: idle data (no TBM trl): 0
[08:34:17.449] <TB1> INFO: no data (only TBM hdr): 0
[08:34:17.449] <TB1> INFO: TBM errors: 0
[08:34:17.449] <TB1> INFO: flawed TBM headers: 0
[08:34:17.449] <TB1> INFO: flawed TBM trailers: 0
[08:34:17.449] <TB1> INFO: event ID mismatches: 0
[08:34:17.449] <TB1> INFO: ROC errors: 0
[08:34:17.449] <TB1> INFO: missing ROC header(s): 0
[08:34:17.449] <TB1> INFO: misplaced readback start: 0
[08:34:17.449] <TB1> INFO: Pixel decoding errors: 0
[08:34:17.449] <TB1> INFO: pixel data incomplete: 0
[08:34:17.449] <TB1> INFO: pixel address: 0
[08:34:17.449] <TB1> INFO: pulse height fill bit: 0
[08:34:17.449] <TB1> INFO: buffer corruption: 0
[08:34:18.233] <TB1> INFO: ######################################################################
[08:34:18.233] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[08:34:18.233] <TB1> INFO: ######################################################################
[08:34:18.516] <TB1> INFO: Expecting 41600 events.
[08:34:22.370] <TB1> INFO: 41600 events read in total (3297ms).
[08:34:22.371] <TB1> INFO: Test took 4137ms.
[08:34:22.829] <TB1> INFO: Expecting 41600 events.
[08:34:26.813] <TB1> INFO: 41600 events read in total (3428ms).
[08:34:26.814] <TB1> INFO: Test took 4247ms.
[08:34:27.127] <TB1> INFO: Expecting 41600 events.
[08:34:30.936] <TB1> INFO: 41600 events read in total (3253ms).
[08:34:30.936] <TB1> INFO: Test took 4097ms.
[08:34:31.191] <TB1> INFO: Expecting 2560 events.
[08:34:32.044] <TB1> INFO: 2560 events read in total (297ms).
[08:34:32.044] <TB1> INFO: Test took 1095ms.
[08:34:32.349] <TB1> INFO: Expecting 2560 events.
[08:34:33.204] <TB1> INFO: 2560 events read in total (299ms).
[08:34:33.204] <TB1> INFO: Test took 1159ms.
[08:34:33.509] <TB1> INFO: Expecting 2560 events.
[08:34:34.362] <TB1> INFO: 2560 events read in total (297ms).
[08:34:34.362] <TB1> INFO: Test took 1158ms.
[08:34:34.666] <TB1> INFO: Expecting 2560 events.
[08:34:35.519] <TB1> INFO: 2560 events read in total (296ms).
[08:34:35.520] <TB1> INFO: Test took 1158ms.
[08:34:35.824] <TB1> INFO: Expecting 2560 events.
[08:34:36.677] <TB1> INFO: 2560 events read in total (296ms).
[08:34:36.678] <TB1> INFO: Test took 1158ms.
[08:34:36.983] <TB1> INFO: Expecting 2560 events.
[08:34:37.837] <TB1> INFO: 2560 events read in total (298ms).
[08:34:37.838] <TB1> INFO: Test took 1160ms.
[08:34:38.143] <TB1> INFO: Expecting 2560 events.
[08:34:38.996] <TB1> INFO: 2560 events read in total (297ms).
[08:34:38.997] <TB1> INFO: Test took 1159ms.
[08:34:39.301] <TB1> INFO: Expecting 2560 events.
[08:34:40.155] <TB1> INFO: 2560 events read in total (298ms).
[08:34:40.155] <TB1> INFO: Test took 1158ms.
[08:34:40.460] <TB1> INFO: Expecting 2560 events.
[08:34:41.315] <TB1> INFO: 2560 events read in total (298ms).
[08:34:41.315] <TB1> INFO: Test took 1159ms.
[08:34:41.620] <TB1> INFO: Expecting 2560 events.
[08:34:42.474] <TB1> INFO: 2560 events read in total (298ms).
[08:34:42.475] <TB1> INFO: Test took 1159ms.
[08:34:42.780] <TB1> INFO: Expecting 2560 events.
[08:34:43.638] <TB1> INFO: 2560 events read in total (302ms).
[08:34:43.638] <TB1> INFO: Test took 1163ms.
[08:34:43.943] <TB1> INFO: Expecting 2560 events.
[08:34:44.798] <TB1> INFO: 2560 events read in total (299ms).
[08:34:44.798] <TB1> INFO: Test took 1160ms.
[08:34:45.102] <TB1> INFO: Expecting 2560 events.
[08:34:45.954] <TB1> INFO: 2560 events read in total (296ms).
[08:34:45.954] <TB1> INFO: Test took 1156ms.
[08:34:46.259] <TB1> INFO: Expecting 2560 events.
[08:34:47.112] <TB1> INFO: 2560 events read in total (297ms).
[08:34:47.112] <TB1> INFO: Test took 1158ms.
[08:34:47.417] <TB1> INFO: Expecting 2560 events.
[08:34:48.269] <TB1> INFO: 2560 events read in total (296ms).
[08:34:48.269] <TB1> INFO: Test took 1156ms.
[08:34:48.574] <TB1> INFO: Expecting 2560 events.
[08:34:49.429] <TB1> INFO: 2560 events read in total (299ms).
[08:34:49.429] <TB1> INFO: Test took 1159ms.
[08:34:49.432] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:34:49.734] <TB1> INFO: Expecting 655360 events.
[08:35:06.521] <TB1> INFO: 655360 events read in total (16230ms).
[08:35:06.531] <TB1> INFO: Expecting 655360 events.
[08:35:23.064] <TB1> INFO: 655360 events read in total (16131ms).
[08:35:23.081] <TB1> INFO: Expecting 655360 events.
[08:35:39.706] <TB1> INFO: 655360 events read in total (16223ms).
[08:35:39.724] <TB1> INFO: Expecting 655360 events.
[08:35:56.269] <TB1> INFO: 655360 events read in total (16143ms).
[08:35:56.291] <TB1> INFO: Expecting 655360 events.
[08:36:12.957] <TB1> INFO: 655360 events read in total (16265ms).
[08:36:12.978] <TB1> INFO: Expecting 655360 events.
[08:36:29.490] <TB1> INFO: 655360 events read in total (16110ms).
[08:36:29.516] <TB1> INFO: Expecting 655360 events.
[08:36:46.060] <TB1> INFO: 655360 events read in total (16142ms).
[08:36:46.102] <TB1> INFO: Expecting 655360 events.
[08:37:02.960] <TB1> INFO: 655360 events read in total (16456ms).
[08:37:02.999] <TB1> INFO: Expecting 655360 events.
[08:37:19.844] <TB1> INFO: 655360 events read in total (16443ms).
[08:37:19.886] <TB1> INFO: Expecting 655360 events.
[08:37:36.603] <TB1> INFO: 655360 events read in total (16315ms).
[08:37:36.650] <TB1> INFO: Expecting 655360 events.
[08:37:53.459] <TB1> INFO: 655360 events read in total (16407ms).
[08:37:53.503] <TB1> INFO: Expecting 655360 events.
[08:38:10.330] <TB1> INFO: 655360 events read in total (16426ms).
[08:38:10.375] <TB1> INFO: Expecting 655360 events.
[08:38:27.187] <TB1> INFO: 655360 events read in total (16410ms).
[08:38:27.241] <TB1> INFO: Expecting 655360 events.
[08:38:44.033] <TB1> INFO: 655360 events read in total (16391ms).
[08:38:44.087] <TB1> INFO: Expecting 655360 events.
[08:38:59.762] <TB1> WARNING: Channel 2 ROC 0: Readback start marker after 3 readouts!

[08:38:59.763] <TB1> WARNING: Channel 2 ROC 0: Readback start marker after 13 readouts!

[08:39:00.966] <TB1> INFO: 655360 events read in total (16478ms).
[08:39:01.031] <TB1> INFO: Expecting 655360 events.
[08:39:17.820] <TB1> INFO: 655360 events read in total (16387ms).
[08:39:17.891] <TB1> INFO: Test took 268459ms.
[08:39:17.982] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:18.210] <TB1> INFO: Expecting 655360 events.
[08:39:35.273] <TB1> INFO: 655360 events read in total (16507ms).
[08:39:35.282] <TB1> INFO: Expecting 655360 events.
[08:39:51.982] <TB1> INFO: 655360 events read in total (16298ms).
[08:39:51.995] <TB1> INFO: Expecting 655360 events.
[08:40:08.770] <TB1> INFO: 655360 events read in total (16374ms).
[08:40:08.785] <TB1> INFO: Expecting 655360 events.
[08:40:25.525] <TB1> INFO: 655360 events read in total (16338ms).
[08:40:25.544] <TB1> INFO: Expecting 655360 events.
[08:40:42.329] <TB1> INFO: 655360 events read in total (16383ms).
[08:40:42.351] <TB1> INFO: Expecting 655360 events.
[08:40:59.032] <TB1> INFO: 655360 events read in total (16279ms).
[08:40:59.058] <TB1> INFO: Expecting 655360 events.
[08:41:15.758] <TB1> INFO: 655360 events read in total (16300ms).
[08:41:15.791] <TB1> INFO: Expecting 655360 events.
[08:41:32.517] <TB1> INFO: 655360 events read in total (16325ms).
[08:41:32.555] <TB1> INFO: Expecting 655360 events.
[08:41:49.265] <TB1> INFO: 655360 events read in total (16308ms).
[08:41:49.307] <TB1> INFO: Expecting 655360 events.
[08:42:05.928] <TB1> INFO: 655360 events read in total (16219ms).
[08:42:05.967] <TB1> INFO: Expecting 655360 events.
[08:42:22.732] <TB1> INFO: 655360 events read in total (16364ms).
[08:42:22.776] <TB1> INFO: Expecting 655360 events.
[08:42:39.541] <TB1> INFO: 655360 events read in total (16364ms).
[08:42:39.587] <TB1> INFO: Expecting 655360 events.
[08:42:56.283] <TB1> INFO: 655360 events read in total (16294ms).
[08:42:56.341] <TB1> INFO: Expecting 655360 events.
[08:43:12.989] <TB1> INFO: 655360 events read in total (16246ms).
[08:43:13.044] <TB1> INFO: Expecting 655360 events.
[08:43:29.878] <TB1> INFO: 655360 events read in total (16432ms).
[08:43:29.935] <TB1> INFO: Expecting 655360 events.
[08:43:46.632] <TB1> INFO: 655360 events read in total (16295ms).
[08:43:46.694] <TB1> INFO: Test took 268712ms.
[08:43:46.883] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:46.890] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:46.897] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:46.904] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[08:43:46.910] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:46.917] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:46.924] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:46.931] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:46.937] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:46.944] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:46.951] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:46.958] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:46.965] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:46.972] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:46.978] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:46.985] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:46.992] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[08:43:47.027] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:43:47.058] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:43:47.058] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:43:47.059] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:43:47.059] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:43:47.059] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:43:47.059] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:43:47.059] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:43:47.059] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:43:47.059] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:43:47.059] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:43:47.059] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:43:47.059] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:43:47.059] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:43:47.059] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:43:47.060] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:43:47.321] <TB1> INFO: Expecting 41600 events.
[08:43:50.809] <TB1> INFO: 41600 events read in total (2932ms).
[08:43:50.810] <TB1> INFO: Test took 3748ms.
[08:43:51.261] <TB1> INFO: Expecting 41600 events.
[08:43:54.742] <TB1> INFO: 41600 events read in total (2925ms).
[08:43:54.742] <TB1> INFO: Test took 3754ms.
[08:43:55.183] <TB1> INFO: Expecting 41600 events.
[08:43:58.646] <TB1> INFO: 41600 events read in total (2907ms).
[08:43:58.647] <TB1> INFO: Test took 3730ms.
[08:43:58.829] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:43:58.917] <TB1> INFO: Expecting 2560 events.
[08:43:59.769] <TB1> INFO: 2560 events read in total (296ms).
[08:43:59.769] <TB1> INFO: Test took 940ms.
[08:43:59.777] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:00.075] <TB1> INFO: Expecting 2560 events.
[08:44:00.929] <TB1> INFO: 2560 events read in total (298ms).
[08:44:00.929] <TB1> INFO: Test took 1152ms.
[08:44:00.931] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:01.235] <TB1> INFO: Expecting 2560 events.
[08:44:02.089] <TB1> INFO: 2560 events read in total (298ms).
[08:44:02.090] <TB1> INFO: Test took 1159ms.
[08:44:02.092] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:02.394] <TB1> INFO: Expecting 2560 events.
[08:44:03.250] <TB1> INFO: 2560 events read in total (299ms).
[08:44:03.250] <TB1> INFO: Test took 1158ms.
[08:44:03.252] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:03.559] <TB1> INFO: Expecting 2560 events.
[08:44:04.417] <TB1> INFO: 2560 events read in total (302ms).
[08:44:04.418] <TB1> INFO: Test took 1166ms.
[08:44:04.419] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:04.723] <TB1> INFO: Expecting 2560 events.
[08:44:05.578] <TB1> INFO: 2560 events read in total (299ms).
[08:44:05.578] <TB1> INFO: Test took 1159ms.
[08:44:05.579] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:05.884] <TB1> INFO: Expecting 2560 events.
[08:44:06.737] <TB1> INFO: 2560 events read in total (297ms).
[08:44:06.738] <TB1> INFO: Test took 1159ms.
[08:44:06.739] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:07.043] <TB1> INFO: Expecting 2560 events.
[08:44:07.897] <TB1> INFO: 2560 events read in total (297ms).
[08:44:07.898] <TB1> INFO: Test took 1159ms.
[08:44:07.899] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:08.202] <TB1> INFO: Expecting 2560 events.
[08:44:09.054] <TB1> INFO: 2560 events read in total (295ms).
[08:44:09.055] <TB1> INFO: Test took 1156ms.
[08:44:09.057] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:09.360] <TB1> INFO: Expecting 2560 events.
[08:44:10.220] <TB1> INFO: 2560 events read in total (304ms).
[08:44:10.220] <TB1> INFO: Test took 1164ms.
[08:44:10.222] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:10.525] <TB1> INFO: Expecting 2560 events.
[08:44:11.378] <TB1> INFO: 2560 events read in total (297ms).
[08:44:11.378] <TB1> INFO: Test took 1156ms.
[08:44:11.380] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:11.683] <TB1> INFO: Expecting 2560 events.
[08:44:12.537] <TB1> INFO: 2560 events read in total (298ms).
[08:44:12.538] <TB1> INFO: Test took 1158ms.
[08:44:12.540] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:12.843] <TB1> INFO: Expecting 2560 events.
[08:44:13.701] <TB1> INFO: 2560 events read in total (302ms).
[08:44:13.701] <TB1> INFO: Test took 1161ms.
[08:44:13.703] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:14.007] <TB1> INFO: Expecting 2560 events.
[08:44:14.862] <TB1> INFO: 2560 events read in total (299ms).
[08:44:14.862] <TB1> INFO: Test took 1159ms.
[08:44:14.864] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:15.167] <TB1> INFO: Expecting 2560 events.
[08:44:16.022] <TB1> INFO: 2560 events read in total (298ms).
[08:44:16.023] <TB1> INFO: Test took 1160ms.
[08:44:16.024] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:16.328] <TB1> INFO: Expecting 2560 events.
[08:44:17.181] <TB1> INFO: 2560 events read in total (297ms).
[08:44:17.181] <TB1> INFO: Test took 1157ms.
[08:44:17.183] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:17.487] <TB1> INFO: Expecting 2560 events.
[08:44:18.346] <TB1> INFO: 2560 events read in total (303ms).
[08:44:18.346] <TB1> INFO: Test took 1163ms.
[08:44:18.348] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:18.652] <TB1> INFO: Expecting 2560 events.
[08:44:19.505] <TB1> INFO: 2560 events read in total (296ms).
[08:44:19.505] <TB1> INFO: Test took 1157ms.
[08:44:19.507] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:19.811] <TB1> INFO: Expecting 2560 events.
[08:44:20.668] <TB1> INFO: 2560 events read in total (301ms).
[08:44:20.668] <TB1> INFO: Test took 1162ms.
[08:44:20.670] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:20.974] <TB1> INFO: Expecting 2560 events.
[08:44:21.829] <TB1> INFO: 2560 events read in total (299ms).
[08:44:21.830] <TB1> INFO: Test took 1160ms.
[08:44:21.832] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:22.136] <TB1> INFO: Expecting 2560 events.
[08:44:22.989] <TB1> INFO: 2560 events read in total (297ms).
[08:44:22.990] <TB1> INFO: Test took 1159ms.
[08:44:22.992] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:23.296] <TB1> INFO: Expecting 2560 events.
[08:44:24.151] <TB1> INFO: 2560 events read in total (299ms).
[08:44:24.151] <TB1> INFO: Test took 1160ms.
[08:44:24.153] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:24.457] <TB1> INFO: Expecting 2560 events.
[08:44:25.310] <TB1> INFO: 2560 events read in total (297ms).
[08:44:25.310] <TB1> INFO: Test took 1157ms.
[08:44:25.312] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:25.615] <TB1> INFO: Expecting 2560 events.
[08:44:26.469] <TB1> INFO: 2560 events read in total (298ms).
[08:44:26.469] <TB1> INFO: Test took 1158ms.
[08:44:26.471] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:26.776] <TB1> INFO: Expecting 2560 events.
[08:44:27.630] <TB1> INFO: 2560 events read in total (298ms).
[08:44:27.630] <TB1> INFO: Test took 1159ms.
[08:44:27.631] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:27.935] <TB1> INFO: Expecting 2560 events.
[08:44:28.789] <TB1> INFO: 2560 events read in total (297ms).
[08:44:28.789] <TB1> INFO: Test took 1158ms.
[08:44:28.791] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:29.095] <TB1> INFO: Expecting 2560 events.
[08:44:29.948] <TB1> INFO: 2560 events read in total (297ms).
[08:44:29.948] <TB1> INFO: Test took 1157ms.
[08:44:29.949] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:30.254] <TB1> INFO: Expecting 2560 events.
[08:44:31.107] <TB1> INFO: 2560 events read in total (297ms).
[08:44:31.107] <TB1> INFO: Test took 1158ms.
[08:44:31.108] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:31.412] <TB1> INFO: Expecting 2560 events.
[08:44:32.266] <TB1> INFO: 2560 events read in total (298ms).
[08:44:32.267] <TB1> INFO: Test took 1159ms.
[08:44:32.269] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:32.571] <TB1> INFO: Expecting 2560 events.
[08:44:33.426] <TB1> INFO: 2560 events read in total (298ms).
[08:44:33.427] <TB1> INFO: Test took 1158ms.
[08:44:33.428] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:33.733] <TB1> INFO: Expecting 2560 events.
[08:44:34.586] <TB1> INFO: 2560 events read in total (297ms).
[08:44:34.587] <TB1> INFO: Test took 1159ms.
[08:44:34.588] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:44:34.893] <TB1> INFO: Expecting 2560 events.
[08:44:35.747] <TB1> INFO: 2560 events read in total (298ms).
[08:44:35.747] <TB1> INFO: Test took 1159ms.
[08:44:36.211] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 617 seconds
[08:44:36.211] <TB1> INFO: PH scale (per ROC): 92 85 76 81 87 81 75 79 68 85 82 76 79 80 80 82
[08:44:36.211] <TB1> INFO: PH offset (per ROC): 159 158 176 157 151 162 182 166 165 155 150 166 164 176 176 160
[08:44:36.218] <TB1> INFO: Decoding statistics:
[08:44:36.218] <TB1> INFO: General information:
[08:44:36.219] <TB1> INFO: 16bit words read: 91940
[08:44:36.219] <TB1> INFO: valid events total: 10240
[08:44:36.219] <TB1> INFO: empty events: 7735
[08:44:36.219] <TB1> INFO: valid events with pixels: 2505
[08:44:36.219] <TB1> INFO: valid pixel hits: 2505
[08:44:36.219] <TB1> INFO: Event errors: 0
[08:44:36.219] <TB1> INFO: start marker: 0
[08:44:36.219] <TB1> INFO: stop marker: 0
[08:44:36.219] <TB1> INFO: overflow: 0
[08:44:36.219] <TB1> INFO: invalid 5bit words: 0
[08:44:36.219] <TB1> INFO: invalid XOR eye diagram: 0
[08:44:36.219] <TB1> INFO: frame (failed synchr.): 0
[08:44:36.219] <TB1> INFO: idle data (no TBM trl): 0
[08:44:36.219] <TB1> INFO: no data (only TBM hdr): 0
[08:44:36.219] <TB1> INFO: TBM errors: 0
[08:44:36.219] <TB1> INFO: flawed TBM headers: 0
[08:44:36.219] <TB1> INFO: flawed TBM trailers: 0
[08:44:36.219] <TB1> INFO: event ID mismatches: 0
[08:44:36.219] <TB1> INFO: ROC errors: 0
[08:44:36.219] <TB1> INFO: missing ROC header(s): 0
[08:44:36.219] <TB1> INFO: misplaced readback start: 0
[08:44:36.219] <TB1> INFO: Pixel decoding errors: 0
[08:44:36.219] <TB1> INFO: pixel data incomplete: 0
[08:44:36.219] <TB1> INFO: pixel address: 0
[08:44:36.219] <TB1> INFO: pulse height fill bit: 0
[08:44:36.219] <TB1> INFO: buffer corruption: 0
[08:44:36.408] <TB1> INFO: ######################################################################
[08:44:36.408] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[08:44:36.408] <TB1> INFO: ######################################################################
[08:44:36.417] <TB1> INFO: scanning low vcal = 10
[08:44:36.682] <TB1> INFO: Expecting 41600 events.
[08:44:40.215] <TB1> INFO: 41600 events read in total (2977ms).
[08:44:40.216] <TB1> INFO: Test took 3799ms.
[08:44:40.217] <TB1> INFO: scanning low vcal = 20
[08:44:40.520] <TB1> INFO: Expecting 41600 events.
[08:44:44.076] <TB1> INFO: 41600 events read in total (3000ms).
[08:44:44.076] <TB1> INFO: Test took 3859ms.
[08:44:44.078] <TB1> INFO: scanning low vcal = 30
[08:44:44.381] <TB1> INFO: Expecting 41600 events.
[08:44:47.929] <TB1> INFO: 41600 events read in total (2992ms).
[08:44:47.930] <TB1> INFO: Test took 3852ms.
[08:44:47.931] <TB1> INFO: scanning low vcal = 40
[08:44:48.224] <TB1> INFO: Expecting 41600 events.
[08:44:52.438] <TB1> INFO: 41600 events read in total (3658ms).
[08:44:52.438] <TB1> INFO: Test took 4507ms.
[08:44:52.441] <TB1> INFO: scanning low vcal = 50
[08:44:52.736] <TB1> INFO: Expecting 41600 events.
[08:44:56.933] <TB1> INFO: 41600 events read in total (3640ms).
[08:44:56.934] <TB1> INFO: Test took 4493ms.
[08:44:56.937] <TB1> INFO: scanning low vcal = 60
[08:44:57.198] <TB1> INFO: Expecting 41600 events.
[08:45:01.384] <TB1> INFO: 41600 events read in total (3630ms).
[08:45:01.384] <TB1> INFO: Test took 4447ms.
[08:45:01.387] <TB1> INFO: scanning low vcal = 70
[08:45:01.658] <TB1> INFO: Expecting 41600 events.
[08:45:05.850] <TB1> INFO: 41600 events read in total (3636ms).
[08:45:05.851] <TB1> INFO: Test took 4464ms.
[08:45:05.853] <TB1> INFO: scanning low vcal = 80
[08:45:06.122] <TB1> INFO: Expecting 41600 events.
[08:45:10.348] <TB1> INFO: 41600 events read in total (3670ms).
[08:45:10.349] <TB1> INFO: Test took 4496ms.
[08:45:10.351] <TB1> INFO: scanning low vcal = 90
[08:45:10.623] <TB1> INFO: Expecting 41600 events.
[08:45:14.825] <TB1> INFO: 41600 events read in total (3646ms).
[08:45:14.825] <TB1> INFO: Test took 4474ms.
[08:45:14.828] <TB1> INFO: scanning low vcal = 100
[08:45:15.091] <TB1> INFO: Expecting 41600 events.
[08:45:19.296] <TB1> INFO: 41600 events read in total (3649ms).
[08:45:19.296] <TB1> INFO: Test took 4468ms.
[08:45:19.298] <TB1> INFO: scanning low vcal = 110
[08:45:19.568] <TB1> INFO: Expecting 41600 events.
[08:45:23.771] <TB1> INFO: 41600 events read in total (3647ms).
[08:45:23.772] <TB1> INFO: Test took 4473ms.
[08:45:23.774] <TB1> INFO: scanning low vcal = 120
[08:45:24.033] <TB1> INFO: Expecting 41600 events.
[08:45:28.263] <TB1> INFO: 41600 events read in total (3673ms).
[08:45:28.263] <TB1> INFO: Test took 4489ms.
[08:45:28.266] <TB1> INFO: scanning low vcal = 130
[08:45:28.538] <TB1> INFO: Expecting 41600 events.
[08:45:32.732] <TB1> INFO: 41600 events read in total (3638ms).
[08:45:32.732] <TB1> INFO: Test took 4466ms.
[08:45:32.735] <TB1> INFO: scanning low vcal = 140
[08:45:33.007] <TB1> INFO: Expecting 41600 events.
[08:45:37.210] <TB1> INFO: 41600 events read in total (3647ms).
[08:45:37.210] <TB1> INFO: Test took 4475ms.
[08:45:37.213] <TB1> INFO: scanning low vcal = 150
[08:45:37.479] <TB1> INFO: Expecting 41600 events.
[08:45:41.699] <TB1> INFO: 41600 events read in total (3661ms).
[08:45:41.699] <TB1> INFO: Test took 4486ms.
[08:45:41.702] <TB1> INFO: scanning low vcal = 160
[08:45:41.966] <TB1> INFO: Expecting 41600 events.
[08:45:46.172] <TB1> INFO: 41600 events read in total (3650ms).
[08:45:46.172] <TB1> INFO: Test took 4470ms.
[08:45:46.175] <TB1> INFO: scanning low vcal = 170
[08:45:46.443] <TB1> INFO: Expecting 41600 events.
[08:45:50.638] <TB1> INFO: 41600 events read in total (3639ms).
[08:45:50.639] <TB1> INFO: Test took 4464ms.
[08:45:50.643] <TB1> INFO: scanning low vcal = 180
[08:45:50.918] <TB1> INFO: Expecting 41600 events.
[08:45:55.118] <TB1> INFO: 41600 events read in total (3643ms).
[08:45:55.118] <TB1> INFO: Test took 4475ms.
[08:45:55.121] <TB1> INFO: scanning low vcal = 190
[08:45:55.389] <TB1> INFO: Expecting 41600 events.
[08:45:59.604] <TB1> INFO: 41600 events read in total (3659ms).
[08:45:59.605] <TB1> INFO: Test took 4484ms.
[08:45:59.607] <TB1> INFO: scanning low vcal = 200
[08:45:59.875] <TB1> INFO: Expecting 41600 events.
[08:46:04.087] <TB1> INFO: 41600 events read in total (3656ms).
[08:46:04.088] <TB1> INFO: Test took 4481ms.
[08:46:04.090] <TB1> INFO: scanning low vcal = 210
[08:46:04.360] <TB1> INFO: Expecting 41600 events.
[08:46:08.560] <TB1> INFO: 41600 events read in total (3644ms).
[08:46:08.561] <TB1> INFO: Test took 4471ms.
[08:46:08.563] <TB1> INFO: scanning low vcal = 220
[08:46:08.832] <TB1> INFO: Expecting 41600 events.
[08:46:13.032] <TB1> INFO: 41600 events read in total (3644ms).
[08:46:13.032] <TB1> INFO: Test took 4468ms.
[08:46:13.035] <TB1> INFO: scanning low vcal = 230
[08:46:13.300] <TB1> INFO: Expecting 41600 events.
[08:46:17.524] <TB1> INFO: 41600 events read in total (3668ms).
[08:46:17.525] <TB1> INFO: Test took 4490ms.
[08:46:17.528] <TB1> INFO: scanning low vcal = 240
[08:46:17.794] <TB1> INFO: Expecting 41600 events.
[08:46:22.002] <TB1> INFO: 41600 events read in total (3652ms).
[08:46:22.002] <TB1> INFO: Test took 4474ms.
[08:46:22.005] <TB1> INFO: scanning low vcal = 250
[08:46:22.274] <TB1> INFO: Expecting 41600 events.
[08:46:26.484] <TB1> INFO: 41600 events read in total (3654ms).
[08:46:26.485] <TB1> INFO: Test took 4480ms.
[08:46:26.489] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[08:46:26.759] <TB1> INFO: Expecting 41600 events.
[08:46:30.987] <TB1> INFO: 41600 events read in total (3672ms).
[08:46:30.988] <TB1> INFO: Test took 4499ms.
[08:46:30.991] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[08:46:31.250] <TB1> INFO: Expecting 41600 events.
[08:46:35.494] <TB1> INFO: 41600 events read in total (3688ms).
[08:46:35.495] <TB1> INFO: Test took 4504ms.
[08:46:35.498] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[08:46:35.765] <TB1> INFO: Expecting 41600 events.
[08:46:39.975] <TB1> INFO: 41600 events read in total (3653ms).
[08:46:39.976] <TB1> INFO: Test took 4478ms.
[08:46:39.980] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[08:46:40.247] <TB1> INFO: Expecting 41600 events.
[08:46:44.453] <TB1> INFO: 41600 events read in total (3650ms).
[08:46:44.454] <TB1> INFO: Test took 4474ms.
[08:46:44.456] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[08:46:44.724] <TB1> INFO: Expecting 41600 events.
[08:46:48.945] <TB1> INFO: 41600 events read in total (3665ms).
[08:46:48.946] <TB1> INFO: Test took 4490ms.
[08:46:49.411] <TB1> INFO: PixTestGainPedestal::measure() done
[08:47:22.171] <TB1> INFO: PixTestGainPedestal::fit() done
[08:47:22.171] <TB1> INFO: non-linearity mean: 0.959 0.950 0.956 0.952 0.957 0.956 0.956 0.962 0.958 0.957 0.956 0.956 0.958 0.961 0.957 0.956
[08:47:22.171] <TB1> INFO: non-linearity RMS: 0.006 0.006 0.006 0.007 0.005 0.006 0.006 0.006 0.006 0.006 0.007 0.006 0.006 0.005 0.006 0.006
[08:47:22.171] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[08:47:22.198] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[08:47:22.224] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[08:47:22.250] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[08:47:22.270] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[08:47:22.293] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[08:47:22.321] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[08:47:22.349] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[08:47:22.377] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[08:47:22.405] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[08:47:22.432] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[08:47:22.460] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[08:47:22.488] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[08:47:22.515] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[08:47:22.540] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[08:47:22.562] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[08:47:22.591] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 166 seconds
[08:47:22.591] <TB1> INFO: Decoding statistics:
[08:47:22.591] <TB1> INFO: General information:
[08:47:22.591] <TB1> INFO: 16bit words read: 2662400
[08:47:22.591] <TB1> INFO: valid events total: 166400
[08:47:22.591] <TB1> INFO: empty events: 0
[08:47:22.591] <TB1> INFO: valid events with pixels: 166400
[08:47:22.591] <TB1> INFO: valid pixel hits: 665593
[08:47:22.591] <TB1> INFO: Event errors: 0
[08:47:22.591] <TB1> INFO: start marker: 0
[08:47:22.591] <TB1> INFO: stop marker: 0
[08:47:22.591] <TB1> INFO: overflow: 0
[08:47:22.591] <TB1> INFO: invalid 5bit words: 0
[08:47:22.591] <TB1> INFO: invalid XOR eye diagram: 0
[08:47:22.591] <TB1> INFO: frame (failed synchr.): 0
[08:47:22.591] <TB1> INFO: idle data (no TBM trl): 0
[08:47:22.591] <TB1> INFO: no data (only TBM hdr): 0
[08:47:22.591] <TB1> INFO: TBM errors: 0
[08:47:22.591] <TB1> INFO: flawed TBM headers: 0
[08:47:22.591] <TB1> INFO: flawed TBM trailers: 0
[08:47:22.591] <TB1> INFO: event ID mismatches: 0
[08:47:22.591] <TB1> INFO: ROC errors: 0
[08:47:22.591] <TB1> INFO: missing ROC header(s): 0
[08:47:22.591] <TB1> INFO: misplaced readback start: 0
[08:47:22.591] <TB1> INFO: Pixel decoding errors: 0
[08:47:22.591] <TB1> INFO: pixel data incomplete: 0
[08:47:22.591] <TB1> INFO: pixel address: 0
[08:47:22.591] <TB1> INFO: pulse height fill bit: 0
[08:47:22.591] <TB1> INFO: buffer corruption: 0
[08:47:22.601] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C15.dat
[08:47:22.602] <TB1> INFO: ######################################################################
[08:47:22.602] <TB1> INFO: PixTestReadback::doTest()
[08:47:22.602] <TB1> INFO: ######################################################################
[08:47:22.602] <TB1> INFO: ----------------------------------------------------------------------
[08:47:22.602] <TB1> INFO: PixTestReadback::CalibrateVd()
[08:47:22.602] <TB1> INFO: ----------------------------------------------------------------------
[08:47:32.058] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C0.dat
[08:47:32.058] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C1.dat
[08:47:32.058] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C2.dat
[08:47:32.059] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C3.dat
[08:47:32.059] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C4.dat
[08:47:32.059] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C5.dat
[08:47:32.059] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C6.dat
[08:47:32.059] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C7.dat
[08:47:32.059] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C8.dat
[08:47:32.059] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C9.dat
[08:47:32.059] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C10.dat
[08:47:32.059] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C11.dat
[08:47:32.059] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C12.dat
[08:47:32.059] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C13.dat
[08:47:32.059] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C14.dat
[08:47:32.059] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C15.dat
[08:47:32.083] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[08:47:32.083] <TB1> INFO: ----------------------------------------------------------------------
[08:47:32.083] <TB1> INFO: PixTestReadback::CalibrateVa()
[08:47:32.083] <TB1> INFO: ----------------------------------------------------------------------
[08:47:42.485] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C0.dat
[08:47:42.486] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C1.dat
[08:47:42.486] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C2.dat
[08:47:42.486] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C3.dat
[08:47:42.486] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C4.dat
[08:47:42.486] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C5.dat
[08:47:42.486] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C6.dat
[08:47:42.486] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C7.dat
[08:47:42.486] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C8.dat
[08:47:42.486] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C9.dat
[08:47:42.486] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C10.dat
[08:47:42.487] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C11.dat
[08:47:42.487] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C12.dat
[08:47:42.487] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C13.dat
[08:47:42.487] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C14.dat
[08:47:42.487] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C15.dat
[08:47:42.512] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[08:47:42.512] <TB1> INFO: ----------------------------------------------------------------------
[08:47:42.512] <TB1> INFO: PixTestReadback::readbackVbg()
[08:47:42.512] <TB1> INFO: ----------------------------------------------------------------------
[08:47:49.820] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[08:47:49.820] <TB1> INFO: ----------------------------------------------------------------------
[08:47:49.820] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[08:47:49.820] <TB1> INFO: ----------------------------------------------------------------------
[08:47:49.820] <TB1> INFO: Vbg will be calibrated using Vd calibration
[08:47:49.820] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 161.4calibrated Vbg = 1.16858 :::*/*/*/*/
[08:47:49.820] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 143.7calibrated Vbg = 1.1749 :::*/*/*/*/
[08:47:49.820] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 157.9calibrated Vbg = 1.17479 :::*/*/*/*/
[08:47:49.820] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 157calibrated Vbg = 1.17652 :::*/*/*/*/
[08:47:49.820] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 151.4calibrated Vbg = 1.18237 :::*/*/*/*/
[08:47:49.820] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 136.9calibrated Vbg = 1.18345 :::*/*/*/*/
[08:47:49.820] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 157calibrated Vbg = 1.18649 :::*/*/*/*/
[08:47:49.820] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 147.7calibrated Vbg = 1.19116 :::*/*/*/*/
[08:47:49.820] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 152.3calibrated Vbg = 1.18933 :::*/*/*/*/
[08:47:49.820] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 160.7calibrated Vbg = 1.1944 :::*/*/*/*/
[08:47:49.820] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 156.2calibrated Vbg = 1.18681 :::*/*/*/*/
[08:47:49.820] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 161calibrated Vbg = 1.1807 :::*/*/*/*/
[08:47:49.820] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 155.7calibrated Vbg = 1.17781 :::*/*/*/*/
[08:47:49.820] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 157.6calibrated Vbg = 1.17879 :::*/*/*/*/
[08:47:49.820] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 156.2calibrated Vbg = 1.1814 :::*/*/*/*/
[08:47:49.820] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 159.2calibrated Vbg = 1.19043 :::*/*/*/*/
[08:47:49.823] <TB1> INFO: ----------------------------------------------------------------------
[08:47:49.823] <TB1> INFO: PixTestReadback::CalibrateIa()
[08:47:49.823] <TB1> INFO: ----------------------------------------------------------------------
[08:50:24.997] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C0.dat
[08:50:24.997] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C1.dat
[08:50:24.997] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C2.dat
[08:50:24.997] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C3.dat
[08:50:24.997] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C4.dat
[08:50:24.997] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C5.dat
[08:50:24.997] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C6.dat
[08:50:24.997] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C7.dat
[08:50:24.997] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C8.dat
[08:50:24.998] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C9.dat
[08:50:24.998] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C10.dat
[08:50:24.998] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C11.dat
[08:50:24.998] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C12.dat
[08:50:24.998] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C13.dat
[08:50:24.998] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C14.dat
[08:50:24.998] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2223_FullQualification_2016-05-04_09h11m_1462345901//000_FulltestPxar_m20//readbackCal_C15.dat
[08:50:25.026] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[08:50:25.027] <TB1> INFO: PixTestReadback::doTest() done
[08:50:25.027] <TB1> INFO: Decoding statistics:
[08:50:25.027] <TB1> INFO: General information:
[08:50:25.027] <TB1> INFO: 16bit words read: 1024
[08:50:25.027] <TB1> INFO: valid events total: 128
[08:50:25.027] <TB1> INFO: empty events: 128
[08:50:25.027] <TB1> INFO: valid events with pixels: 0
[08:50:25.027] <TB1> INFO: valid pixel hits: 0
[08:50:25.027] <TB1> INFO: Event errors: 0
[08:50:25.027] <TB1> INFO: start marker: 0
[08:50:25.027] <TB1> INFO: stop marker: 0
[08:50:25.027] <TB1> INFO: overflow: 0
[08:50:25.027] <TB1> INFO: invalid 5bit words: 0
[08:50:25.027] <TB1> INFO: invalid XOR eye diagram: 0
[08:50:25.027] <TB1> INFO: frame (failed synchr.): 0
[08:50:25.027] <TB1> INFO: idle data (no TBM trl): 0
[08:50:25.027] <TB1> INFO: no data (only TBM hdr): 0
[08:50:25.027] <TB1> INFO: TBM errors: 0
[08:50:25.027] <TB1> INFO: flawed TBM headers: 0
[08:50:25.027] <TB1> INFO: flawed TBM trailers: 0
[08:50:25.027] <TB1> INFO: event ID mismatches: 0
[08:50:25.027] <TB1> INFO: ROC errors: 0
[08:50:25.027] <TB1> INFO: missing ROC header(s): 0
[08:50:25.027] <TB1> INFO: misplaced readback start: 0
[08:50:25.027] <TB1> INFO: Pixel decoding errors: 0
[08:50:25.027] <TB1> INFO: pixel data incomplete: 0
[08:50:25.027] <TB1> INFO: pixel address: 0
[08:50:25.027] <TB1> INFO: pulse height fill bit: 0
[08:50:25.027] <TB1> INFO: buffer corruption: 0
[08:50:25.049] <TB1> INFO: Decoding statistics:
[08:50:25.049] <TB1> INFO: General information:
[08:50:25.049] <TB1> INFO: 16bit words read: 2755364
[08:50:25.049] <TB1> INFO: valid events total: 176768
[08:50:25.049] <TB1> INFO: empty events: 7863
[08:50:25.049] <TB1> INFO: valid events with pixels: 168905
[08:50:25.049] <TB1> INFO: valid pixel hits: 668098
[08:50:25.049] <TB1> INFO: Event errors: 0
[08:50:25.049] <TB1> INFO: start marker: 0
[08:50:25.049] <TB1> INFO: stop marker: 0
[08:50:25.049] <TB1> INFO: overflow: 0
[08:50:25.049] <TB1> INFO: invalid 5bit words: 0
[08:50:25.049] <TB1> INFO: invalid XOR eye diagram: 0
[08:50:25.049] <TB1> INFO: frame (failed synchr.): 0
[08:50:25.049] <TB1> INFO: idle data (no TBM trl): 0
[08:50:25.049] <TB1> INFO: no data (only TBM hdr): 0
[08:50:25.049] <TB1> INFO: TBM errors: 0
[08:50:25.049] <TB1> INFO: flawed TBM headers: 0
[08:50:25.049] <TB1> INFO: flawed TBM trailers: 0
[08:50:25.049] <TB1> INFO: event ID mismatches: 0
[08:50:25.049] <TB1> INFO: ROC errors: 0
[08:50:25.049] <TB1> INFO: missing ROC header(s): 0
[08:50:25.049] <TB1> INFO: misplaced readback start: 0
[08:50:25.049] <TB1> INFO: Pixel decoding errors: 0
[08:50:25.049] <TB1> INFO: pixel data incomplete: 0
[08:50:25.049] <TB1> INFO: pixel address: 0
[08:50:25.049] <TB1> INFO: pulse height fill bit: 0
[08:50:25.049] <TB1> INFO: buffer corruption: 0
[08:50:25.049] <TB1> INFO: enter test to run
[08:50:25.049] <TB1> INFO: test: exit no parameter change
[08:50:25.231] <TB1> QUIET: Connection to board 153 closed.
[08:50:25.311] <TB1> INFO: pXar: this is the end, my friend
MoReWeb-v1.0.0-5-g82d9ff6 on branch psi46master