[10:00:37.654] <TB1> INFO: *** Welcome to pxar ***
[10:00:37.654] <TB1> INFO: *** Today: 2016/06/15
[10:00:37.744] <TB1> INFO: *** Version: 9751-dirty
[10:00:37.744] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C15.dat
[10:00:37.745] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//tbmParameters_C0b.dat
[10:00:37.745] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//defaultMaskFile.dat
[10:00:37.745] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters_C15.dat
[10:00:37.821] <TB1> INFO: clk: 4
[10:00:37.821] <TB1> INFO: ctr: 4
[10:00:37.821] <TB1> INFO: sda: 19
[10:00:37.821] <TB1> INFO: tin: 9
[10:00:37.821] <TB1> INFO: level: 15
[10:00:37.821] <TB1> INFO: triggerdelay: 0
[10:00:37.821] <TB1> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[10:00:37.821] <TB1> INFO: Log level: INFO
[10:00:37.828] <TB1> INFO: Found DTB DTB_WXBYFL
[10:00:37.839] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[10:00:37.841] <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:
------------------------------------------------------
[10:00:37.842] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[10:00:39.395] <TB1> INFO: DUT info:
[10:00:39.395] <TB1> INFO: The DUT currently contains the following objects:
[10:00:39.395] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[10:00:39.395] <TB1> INFO: TBM Core alpha (0): 7 registers set
[10:00:39.395] <TB1> INFO: TBM Core beta (1): 7 registers set
[10:00:39.395] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:00:39.395] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:39.395] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:39.395] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:39.395] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:39.395] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:39.396] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:39.396] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:39.396] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:39.396] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:39.396] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:39.396] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:39.396] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:39.396] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:39.396] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:39.397] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:39.397] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:00:39.798] <TB1> INFO: enter 'restricted' command line mode
[10:00:39.798] <TB1> INFO: enter test to run
[10:00:39.798] <TB1> INFO: test: pretest no parameter change
[10:00:39.798] <TB1> INFO: running: pretest
[10:00:39.801] <TB1> INFO: ----------------------------------------------------------------------
[10:00:39.801] <TB1> INFO: PixTestPretest::programROC()
[10:00:39.801] <TB1> INFO: ----------------------------------------------------------------------
[10:00:57.816] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[10:00:57.816] <TB1> INFO: IA differences per ROC: 19.3 17.7 20.1 20.1 17.7 19.3 19.3 20.9 17.7 20.1 19.3 20.1 20.9 16.9 19.3 16.9
[10:00:57.918] <TB1> INFO: enter test to run
[10:00:57.918] <TB1> INFO: test: pretest no parameter change
[10:00:57.918] <TB1> INFO: running: pretest
[10:00:57.919] <TB1> INFO: ----------------------------------------------------------------------
[10:00:57.919] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[10:00:57.919] <TB1> INFO: ----------------------------------------------------------------------
[10:01:19.183] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 392.3 mA = 24.5187 mA/ROC
[10:01:19.183] <TB1> INFO: i(loss) [mA/ROC]: 20.1 20.1 20.1 20.1 20.1 20.9 19.3 19.3 19.3 20.1 20.1 20.1 20.1 18.5 19.3 20.1
[10:01:19.216] <TB1> INFO: enter test to run
[10:01:19.216] <TB1> INFO: test: pretest no parameter change
[10:01:19.216] <TB1> INFO: running: pretest
[10:01:19.216] <TB1> INFO: ----------------------------------------------------------------------
[10:01:19.216] <TB1> INFO: PixTestPretest::findTiming()
[10:01:19.216] <TB1> INFO: ----------------------------------------------------------------------
[10:01:19.217] <TB1> INFO: PixTestCmd::init()
[10:01:20.046] <TB1> WARNING: Not unmasking DUT, not setting Calibrate bits!
[10:02:46.892] <TB1> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[10:02:46.892] <TB1> INFO: (success/tries = 100/100), width = 4
[10:02:46.893] <TB1> INFO: enter test to run
[10:02:46.893] <TB1> INFO: test: pretest no parameter change
[10:02:46.893] <TB1> INFO: running: pretest
[10:02:46.894] <TB1> INFO: ----------------------------------------------------------------------
[10:02:46.894] <TB1> INFO: PixTestPretest::findWorkingPixel()
[10:02:46.894] <TB1> INFO: ----------------------------------------------------------------------
[10:02:46.984] <TB1> INFO: Expecting 231680 events.
[10:02:52.759] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L497> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[10:02:52.843] <TB1> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[10:02:57.226] <TB1> INFO: 231680 events read in total (9686ms).
[10:02:57.230] <TB1> INFO: Test took 10333ms.
[10:02:57.470] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:02:57.545] <TB1> INFO: enter test to run
[10:02:57.545] <TB1> INFO: test: pretest no parameter change
[10:02:57.545] <TB1> INFO: running: pretest
[10:02:57.546] <TB1> INFO: ----------------------------------------------------------------------
[10:02:57.546] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[10:02:57.546] <TB1> INFO: ----------------------------------------------------------------------
[10:02:57.638] <TB1> INFO: Expecting 231680 events.
[10:03:08.037] <TB1> INFO: 231680 events read in total (9843ms).
[10:03:08.041] <TB1> INFO: Test took 10491ms.
[10:03:08.269] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[10:03:08.269] <TB1> INFO: CalDel: 131 115 138 162 127 113 132 123 150 118 142 142 137 133 114 113
[10:03:08.269] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 52 51 51 51 51 51 53
[10:03:08.303] <TB1> INFO: enter test to run
[10:03:08.303] <TB1> INFO: test: pretest no parameter change
[10:03:08.303] <TB1> INFO: running: pretest
[10:03:08.303] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C0.dat
[10:03:08.303] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C1.dat
[10:03:08.303] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C2.dat
[10:03:08.303] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C3.dat
[10:03:08.304] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C4.dat
[10:03:08.304] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C5.dat
[10:03:08.304] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C6.dat
[10:03:08.304] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C7.dat
[10:03:08.304] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C8.dat
[10:03:08.304] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C9.dat
[10:03:08.304] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C10.dat
[10:03:08.304] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C11.dat
[10:03:08.304] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C12.dat
[10:03:08.304] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C13.dat
[10:03:08.304] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C14.dat
[10:03:08.305] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters_C15.dat
[10:03:08.305] <TB1> INFO: enter test to run
[10:03:08.305] <TB1> INFO: test: fulltest no parameter change
[10:03:08.305] <TB1> INFO: running: fulltest
[10:03:08.305] <TB1> INFO: ######################################################################
[10:03:08.305] <TB1> INFO: PixTestFullTest::doTest()
[10:03:08.305] <TB1> INFO: ######################################################################
[10:03:08.306] <TB1> INFO: ######################################################################
[10:03:08.306] <TB1> INFO: PixTestAlive::doTest()
[10:03:08.306] <TB1> INFO: ######################################################################
[10:03:08.307] <TB1> INFO: ----------------------------------------------------------------------
[10:03:08.307] <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)
[10:03:08.307] <TB1> INFO: ----------------------------------------------------------------------
[10:03:08.571] <TB1> INFO: Expecting 41600 events.
[10:03:12.376] <TB1> INFO: 41600 events read in total (3249ms).
[10:03:12.377] <TB1> INFO: Test took 4069ms.
[10:03:12.609] <TB1> INFO: PixTestAlive::aliveTest() done
[10:03:12.609] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:03:12.610] <TB1> INFO: ----------------------------------------------------------------------
[10:03:12.610] <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)
[10:03:12.610] <TB1> INFO: ----------------------------------------------------------------------
[10:03:12.872] <TB1> INFO: Expecting 41600 events.
[10:03:15.826] <TB1> INFO: 41600 events read in total (2398ms).
[10:03:15.826] <TB1> INFO: Test took 3215ms.
[10:03:15.855] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:03:16.070] <TB1> INFO: PixTestAlive::maskTest() done
[10:03:16.070] <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
[10:03:16.071] <TB1> INFO: ----------------------------------------------------------------------
[10:03:16.071] <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)
[10:03:16.071] <TB1> INFO: ----------------------------------------------------------------------
[10:03:16.362] <TB1> INFO: Expecting 41600 events.
[10:03:20.184] <TB1> INFO: 41600 events read in total (3266ms).
[10:03:20.185] <TB1> INFO: Test took 4112ms.
[10:03:20.420] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[10:03:20.420] <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
[10:03:20.420] <TB1> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[10:03:20.420] <TB1> INFO: Decoding statistics:
[10:03:20.420] <TB1> INFO: General information:
[10:03:20.420] <TB1> INFO: 16bit words read: 0
[10:03:20.420] <TB1> INFO: valid events total: 0
[10:03:20.420] <TB1> INFO: empty events: 0
[10:03:20.420] <TB1> INFO: valid events with pixels: 0
[10:03:20.420] <TB1> INFO: valid pixel hits: 0
[10:03:20.420] <TB1> INFO: Event errors: 0
[10:03:20.420] <TB1> INFO: start marker: 0
[10:03:20.420] <TB1> INFO: stop marker: 0
[10:03:20.420] <TB1> INFO: overflow: 0
[10:03:20.420] <TB1> INFO: invalid 5bit words: 0
[10:03:20.420] <TB1> INFO: invalid XOR eye diagram: 0
[10:03:20.420] <TB1> INFO: frame (failed synchr.): 0
[10:03:20.420] <TB1> INFO: idle data (no TBM trl): 0
[10:03:20.420] <TB1> INFO: no data (only TBM hdr): 0
[10:03:20.420] <TB1> INFO: TBM errors: 0
[10:03:20.420] <TB1> INFO: flawed TBM headers: 0
[10:03:20.420] <TB1> INFO: flawed TBM trailers: 0
[10:03:20.420] <TB1> INFO: event ID mismatches: 0
[10:03:20.420] <TB1> INFO: ROC errors: 0
[10:03:20.420] <TB1> INFO: missing ROC header(s): 0
[10:03:20.420] <TB1> INFO: misplaced readback start: 0
[10:03:20.420] <TB1> INFO: Pixel decoding errors: 0
[10:03:20.420] <TB1> INFO: pixel data incomplete: 0
[10:03:20.420] <TB1> INFO: pixel address: 0
[10:03:20.420] <TB1> INFO: pulse height fill bit: 0
[10:03:20.420] <TB1> INFO: buffer corruption: 0
[10:03:20.426] <TB1> INFO: ######################################################################
[10:03:20.426] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:03:20.426] <TB1> INFO: ######################################################################
[10:03:20.429] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[10:03:20.440] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:03:20.440] <TB1> INFO: run 1 of 1
[10:03:20.705] <TB1> INFO: Expecting 3120000 events.
[10:04:01.837] <TB1> INFO: 863680 events read in total (40576ms).
[10:04:41.759] <TB1> INFO: 1717770 events read in total (80498ms).
[10:05:22.247] <TB1> INFO: 2578455 events read in total (120987ms).
[10:05:47.367] <TB1> INFO: 3120000 events read in total (146106ms).
[10:05:47.416] <TB1> INFO: Test took 146976ms.
[10:06:10.642] <TB1> INFO: PixTestBBMap::doTest() done, duration: 170 seconds
[10:06:10.642] <TB1> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 1 1 0 0 0 0 2
[10:06:10.642] <TB1> INFO: separation cut (per ROC): 96 101 98 94 91 107 82 94 82 111 91 95 101 92 103 106
[10:06:10.642] <TB1> INFO: Decoding statistics:
[10:06:10.642] <TB1> INFO: General information:
[10:06:10.642] <TB1> INFO: 16bit words read: 0
[10:06:10.642] <TB1> INFO: valid events total: 0
[10:06:10.642] <TB1> INFO: empty events: 0
[10:06:10.642] <TB1> INFO: valid events with pixels: 0
[10:06:10.642] <TB1> INFO: valid pixel hits: 0
[10:06:10.642] <TB1> INFO: Event errors: 0
[10:06:10.642] <TB1> INFO: start marker: 0
[10:06:10.642] <TB1> INFO: stop marker: 0
[10:06:10.642] <TB1> INFO: overflow: 0
[10:06:10.642] <TB1> INFO: invalid 5bit words: 0
[10:06:10.642] <TB1> INFO: invalid XOR eye diagram: 0
[10:06:10.642] <TB1> INFO: frame (failed synchr.): 0
[10:06:10.642] <TB1> INFO: idle data (no TBM trl): 0
[10:06:10.642] <TB1> INFO: no data (only TBM hdr): 0
[10:06:10.642] <TB1> INFO: TBM errors: 0
[10:06:10.642] <TB1> INFO: flawed TBM headers: 0
[10:06:10.642] <TB1> INFO: flawed TBM trailers: 0
[10:06:10.642] <TB1> INFO: event ID mismatches: 0
[10:06:10.642] <TB1> INFO: ROC errors: 0
[10:06:10.642] <TB1> INFO: missing ROC header(s): 0
[10:06:10.642] <TB1> INFO: misplaced readback start: 0
[10:06:10.642] <TB1> INFO: Pixel decoding errors: 0
[10:06:10.642] <TB1> INFO: pixel data incomplete: 0
[10:06:10.642] <TB1> INFO: pixel address: 0
[10:06:10.643] <TB1> INFO: pulse height fill bit: 0
[10:06:10.643] <TB1> INFO: buffer corruption: 0
[10:06:10.713] <TB1> INFO: ######################################################################
[10:06:10.713] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:06:10.713] <TB1> INFO: ######################################################################
[10:06:10.713] <TB1> INFO: ----------------------------------------------------------------------
[10:06:10.713] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:06:10.713] <TB1> INFO: ----------------------------------------------------------------------
[10:06:10.713] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:06:10.721] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[10:06:10.721] <TB1> INFO: run 1 of 1
[10:06:10.982] <TB1> INFO: Expecting 26208000 events.
[10:06:45.151] <TB1> INFO: 935750 events read in total (33612ms).
[10:07:18.409] <TB1> INFO: 1855100 events read in total (66870ms).
[10:07:51.562] <TB1> INFO: 2774350 events read in total (100023ms).
[10:08:24.638] <TB1> INFO: 3691500 events read in total (133099ms).
[10:08:58.241] <TB1> INFO: 4608850 events read in total (166702ms).
[10:09:31.160] <TB1> INFO: 5523150 events read in total (199621ms).
[10:10:04.252] <TB1> INFO: 6440250 events read in total (232713ms).
[10:10:37.156] <TB1> INFO: 7355700 events read in total (265617ms).
[10:11:10.409] <TB1> INFO: 8270150 events read in total (298870ms).
[10:11:43.814] <TB1> INFO: 9186050 events read in total (332275ms).
[10:12:17.235] <TB1> INFO: 10100100 events read in total (365696ms).
[10:12:50.183] <TB1> INFO: 11012200 events read in total (398644ms).
[10:13:23.759] <TB1> INFO: 11927150 events read in total (432220ms).
[10:13:57.018] <TB1> INFO: 12840200 events read in total (465479ms).
[10:14:30.124] <TB1> INFO: 13745700 events read in total (498585ms).
[10:15:03.156] <TB1> INFO: 14647750 events read in total (531617ms).
[10:15:36.416] <TB1> INFO: 15549300 events read in total (564877ms).
[10:16:09.699] <TB1> INFO: 16448200 events read in total (598160ms).
[10:16:42.715] <TB1> INFO: 17348800 events read in total (631176ms).
[10:17:15.797] <TB1> INFO: 18250000 events read in total (664258ms).
[10:17:48.935] <TB1> INFO: 19147550 events read in total (697396ms).
[10:18:22.200] <TB1> INFO: 20045100 events read in total (730661ms).
[10:18:55.408] <TB1> INFO: 20943300 events read in total (763869ms).
[10:19:28.523] <TB1> INFO: 21843250 events read in total (796984ms).
[10:20:01.964] <TB1> INFO: 22741500 events read in total (830425ms).
[10:20:34.802] <TB1> INFO: 23642600 events read in total (863263ms).
[10:21:08.056] <TB1> INFO: 24543300 events read in total (896517ms).
[10:21:40.941] <TB1> INFO: 25444450 events read in total (929402ms).
[10:22:08.330] <TB1> INFO: 26208000 events read in total (956791ms).
[10:22:08.360] <TB1> INFO: Test took 957639ms.
[10:22:08.605] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:22:10.102] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:22:11.632] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:22:13.074] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:22:14.669] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:22:16.277] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:22:17.712] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:22:19.387] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:22:20.892] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:22:22.303] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:22:23.659] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:22:25.071] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:22:26.450] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:22:27.810] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:22:29.189] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:22:30.554] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[10:22:31.935] <TB1> INFO: PixTestScurves::scurves() done
[10:22:31.935] <TB1> INFO: Vcal mean: 92.05 102.76 101.54 94.02 87.96 104.75 82.09 97.39 89.02 110.97 90.79 88.96 100.05 92.34 96.26 105.49
[10:22:31.935] <TB1> INFO: Vcal RMS: 5.18 5.61 6.06 5.04 5.24 5.44 4.50 6.29 5.09 6.08 5.11 5.06 5.49 5.26 5.62 6.15
[10:22:31.935] <TB1> INFO: PixTestScurves::fullTest() done, duration: 981 seconds
[10:22:31.935] <TB1> INFO: Decoding statistics:
[10:22:31.935] <TB1> INFO: General information:
[10:22:31.935] <TB1> INFO: 16bit words read: 0
[10:22:31.935] <TB1> INFO: valid events total: 0
[10:22:31.935] <TB1> INFO: empty events: 0
[10:22:31.935] <TB1> INFO: valid events with pixels: 0
[10:22:31.935] <TB1> INFO: valid pixel hits: 0
[10:22:31.935] <TB1> INFO: Event errors: 0
[10:22:31.935] <TB1> INFO: start marker: 0
[10:22:31.935] <TB1> INFO: stop marker: 0
[10:22:31.935] <TB1> INFO: overflow: 0
[10:22:31.935] <TB1> INFO: invalid 5bit words: 0
[10:22:31.935] <TB1> INFO: invalid XOR eye diagram: 0
[10:22:31.935] <TB1> INFO: frame (failed synchr.): 0
[10:22:31.935] <TB1> INFO: idle data (no TBM trl): 0
[10:22:31.935] <TB1> INFO: no data (only TBM hdr): 0
[10:22:31.935] <TB1> INFO: TBM errors: 0
[10:22:31.935] <TB1> INFO: flawed TBM headers: 0
[10:22:31.935] <TB1> INFO: flawed TBM trailers: 0
[10:22:31.935] <TB1> INFO: event ID mismatches: 0
[10:22:31.935] <TB1> INFO: ROC errors: 0
[10:22:31.935] <TB1> INFO: missing ROC header(s): 0
[10:22:31.935] <TB1> INFO: misplaced readback start: 0
[10:22:31.935] <TB1> INFO: Pixel decoding errors: 0
[10:22:31.935] <TB1> INFO: pixel data incomplete: 0
[10:22:31.935] <TB1> INFO: pixel address: 0
[10:22:31.935] <TB1> INFO: pulse height fill bit: 0
[10:22:31.935] <TB1> INFO: buffer corruption: 0
[10:22:32.005] <TB1> INFO: ######################################################################
[10:22:32.005] <TB1> INFO: PixTestTrim::doTest()
[10:22:32.005] <TB1> INFO: ######################################################################
[10:22:32.006] <TB1> INFO: ----------------------------------------------------------------------
[10:22:32.006] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:22:32.006] <TB1> INFO: ----------------------------------------------------------------------
[10:22:32.086] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:22:32.086] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:22:32.094] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:22:32.094] <TB1> INFO: run 1 of 1
[10:22:32.356] <TB1> INFO: Expecting 6281600 events.
[10:23:12.904] <TB1> INFO: 1076350 events read in total (39990ms).
[10:23:52.436] <TB1> INFO: 2144610 events read in total (79522ms).
[10:24:31.449] <TB1> INFO: 3208140 events read in total (118535ms).
[10:25:10.449] <TB1> INFO: 4274190 events read in total (157535ms).
[10:25:49.279] <TB1> INFO: 5340940 events read in total (196365ms).
[10:26:23.480] <TB1> INFO: 6281600 events read in total (230566ms).
[10:26:23.515] <TB1> INFO: Test took 231421ms.
[10:26:44.042] <TB1> INFO: ROC 0 VthrComp = 99
[10:26:44.042] <TB1> INFO: ROC 1 VthrComp = 102
[10:26:44.042] <TB1> INFO: ROC 2 VthrComp = 100
[10:26:44.042] <TB1> INFO: ROC 3 VthrComp = 99
[10:26:44.042] <TB1> INFO: ROC 4 VthrComp = 93
[10:26:44.043] <TB1> INFO: ROC 5 VthrComp = 107
[10:26:44.043] <TB1> INFO: ROC 6 VthrComp = 84
[10:26:44.043] <TB1> INFO: ROC 7 VthrComp = 97
[10:26:44.043] <TB1> INFO: ROC 8 VthrComp = 91
[10:26:44.043] <TB1> INFO: ROC 9 VthrComp = 108
[10:26:44.043] <TB1> INFO: ROC 10 VthrComp = 97
[10:26:44.043] <TB1> INFO: ROC 11 VthrComp = 96
[10:26:44.043] <TB1> INFO: ROC 12 VthrComp = 103
[10:26:44.043] <TB1> INFO: ROC 13 VthrComp = 96
[10:26:44.043] <TB1> INFO: ROC 14 VthrComp = 100
[10:26:44.043] <TB1> INFO: ROC 15 VthrComp = 104
[10:26:44.044] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:26:44.044] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:26:44.051] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:26:44.051] <TB1> INFO: run 1 of 1
[10:26:44.312] <TB1> INFO: Expecting 6281600 events.
[10:27:20.001] <TB1> INFO: 754890 events read in total (35133ms).
[10:27:55.122] <TB1> INFO: 1508770 events read in total (70254ms).
[10:28:29.976] <TB1> INFO: 2263180 events read in total (105108ms).
[10:29:04.998] <TB1> INFO: 3018440 events read in total (140130ms).
[10:29:39.565] <TB1> INFO: 3768610 events read in total (174697ms).
[10:30:14.105] <TB1> INFO: 4515580 events read in total (209237ms).
[10:30:48.453] <TB1> INFO: 5261090 events read in total (243585ms).
[10:31:23.013] <TB1> INFO: 6006760 events read in total (278145ms).
[10:31:36.030] <TB1> INFO: 6281600 events read in total (291162ms).
[10:31:36.074] <TB1> INFO: Test took 292023ms.
[10:32:01.084] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 56.8047 for pixel 12/0 mean/min/max = 44.3589/31.5144/57.2034
[10:32:01.084] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 59.4938 for pixel 51/13 mean/min/max = 46.0361/32.4204/59.6517
[10:32:01.085] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 60.3781 for pixel 17/79 mean/min/max = 45.8662/31.3253/60.4071
[10:32:01.085] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 57.4232 for pixel 5/13 mean/min/max = 44.6768/31.9237/57.4299
[10:32:01.085] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 58.0761 for pixel 8/17 mean/min/max = 45.1336/31.8824/58.3848
[10:32:01.085] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 61.081 for pixel 6/6 mean/min/max = 47.4242/33.7636/61.0849
[10:32:01.086] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 57.6118 for pixel 24/13 mean/min/max = 45.1756/32.7361/57.6151
[10:32:01.086] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 61.8785 for pixel 2/78 mean/min/max = 46.5152/30.942/62.0884
[10:32:01.086] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 58.6566 for pixel 48/5 mean/min/max = 45.9163/33.126/58.7065
[10:32:01.086] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 65.2914 for pixel 8/79 mean/min/max = 49.7568/33.9313/65.5823
[10:32:01.087] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 57.5896 for pixel 41/2 mean/min/max = 44.6009/31.4725/57.7294
[10:32:01.087] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 55.9501 for pixel 51/70 mean/min/max = 43.9711/31.5534/56.3889
[10:32:01.087] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 59.7132 for pixel 2/21 mean/min/max = 46.039/32.3604/59.7177
[10:32:01.087] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 57.5731 for pixel 3/28 mean/min/max = 44.673/31.5723/57.7737
[10:32:01.088] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 59.3744 for pixel 17/78 mean/min/max = 45.3251/31.1023/59.548
[10:32:01.088] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 64.7996 for pixel 21/15 mean/min/max = 48.6256/32.2156/65.0356
[10:32:01.088] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:32:01.176] <TB1> INFO: Expecting 514560 events.
[10:32:14.323] <TB1> INFO: 514560 events read in total (12591ms).
[10:32:14.329] <TB1> INFO: Expecting 514560 events.
[10:32:26.954] <TB1> INFO: 514560 events read in total (12212ms).
[10:32:26.961] <TB1> INFO: Expecting 514560 events.
[10:32:39.552] <TB1> INFO: 514560 events read in total (12168ms).
[10:32:39.560] <TB1> INFO: Expecting 514560 events.
[10:32:52.226] <TB1> INFO: 514560 events read in total (12244ms).
[10:32:52.236] <TB1> INFO: Expecting 514560 events.
[10:33:05.048] <TB1> INFO: 514560 events read in total (12401ms).
[10:33:05.061] <TB1> INFO: Expecting 514560 events.
[10:33:17.878] <TB1> INFO: 514560 events read in total (12415ms).
[10:33:17.892] <TB1> INFO: Expecting 514560 events.
[10:33:30.776] <TB1> INFO: 514560 events read in total (12471ms).
[10:33:30.793] <TB1> INFO: Expecting 514560 events.
[10:33:43.646] <TB1> INFO: 514560 events read in total (12445ms).
[10:33:43.664] <TB1> INFO: Expecting 514560 events.
[10:33:56.541] <TB1> INFO: 514560 events read in total (12466ms).
[10:33:56.561] <TB1> INFO: Expecting 514560 events.
[10:34:09.481] <TB1> INFO: 514560 events read in total (12512ms).
[10:34:09.503] <TB1> INFO: Expecting 514560 events.
[10:34:22.389] <TB1> INFO: 514560 events read in total (12485ms).
[10:34:22.413] <TB1> INFO: Expecting 514560 events.
[10:34:35.274] <TB1> INFO: 514560 events read in total (12457ms).
[10:34:35.301] <TB1> INFO: Expecting 514560 events.
[10:34:48.264] <TB1> INFO: 514560 events read in total (12561ms).
[10:34:48.294] <TB1> INFO: Expecting 514560 events.
[10:35:01.129] <TB1> INFO: 514560 events read in total (12434ms).
[10:35:01.163] <TB1> INFO: Expecting 514560 events.
[10:35:14.006] <TB1> INFO: 514560 events read in total (12441ms).
[10:35:14.037] <TB1> INFO: Expecting 514560 events.
[10:35:26.924] <TB1> INFO: 514560 events read in total (12485ms).
[10:35:26.958] <TB1> INFO: Test took 205870ms.
[10:35:27.969] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:35:27.977] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:35:27.977] <TB1> INFO: run 1 of 1
[10:35:28.255] <TB1> INFO: Expecting 6281600 events.
[10:36:03.482] <TB1> INFO: 742040 events read in total (34671ms).
[10:36:37.625] <TB1> INFO: 1482340 events read in total (68814ms).
[10:37:12.096] <TB1> INFO: 2223590 events read in total (103285ms).
[10:37:46.858] <TB1> INFO: 2965470 events read in total (138047ms).
[10:38:21.577] <TB1> INFO: 3701980 events read in total (172766ms).
[10:38:55.972] <TB1> INFO: 4434220 events read in total (207161ms).
[10:39:30.411] <TB1> INFO: 5164940 events read in total (241600ms).
[10:40:04.673] <TB1> INFO: 5894880 events read in total (275862ms).
[10:40:23.042] <TB1> INFO: 6281600 events read in total (294231ms).
[10:40:23.085] <TB1> INFO: Test took 295108ms.
[10:40:46.850] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.077914 .. 255.000000
[10:40:46.925] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[10:40:46.933] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:40:46.934] <TB1> INFO: run 1 of 1
[10:40:47.194] <TB1> INFO: Expecting 10649600 events.
[10:41:22.146] <TB1> INFO: 712240 events read in total (34395ms).
[10:41:56.285] <TB1> INFO: 1424580 events read in total (68534ms).
[10:42:30.706] <TB1> INFO: 2136680 events read in total (102955ms).
[10:43:04.864] <TB1> INFO: 2848820 events read in total (137113ms).
[10:43:39.159] <TB1> INFO: 3561280 events read in total (171408ms).
[10:44:13.218] <TB1> INFO: 4273660 events read in total (205467ms).
[10:44:47.035] <TB1> INFO: 4985800 events read in total (239284ms).
[10:45:21.091] <TB1> INFO: 5698200 events read in total (273340ms).
[10:45:54.934] <TB1> INFO: 6410610 events read in total (307183ms).
[10:46:28.694] <TB1> INFO: 7121830 events read in total (340943ms).
[10:47:02.386] <TB1> INFO: 7833330 events read in total (374635ms).
[10:47:36.200] <TB1> INFO: 8544220 events read in total (408449ms).
[10:48:09.864] <TB1> INFO: 9255480 events read in total (442113ms).
[10:48:44.241] <TB1> INFO: 9966560 events read in total (476490ms).
[10:49:17.501] <TB1> INFO: 10649600 events read in total (509750ms).
[10:49:17.589] <TB1> INFO: Test took 510655ms.
[10:49:47.306] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 15.528752 .. 46.130376
[10:49:47.384] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 5 .. 56 (-1/-1) hits flags = 528 (plus default)
[10:49:47.395] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:49:47.395] <TB1> INFO: run 1 of 1
[10:49:47.670] <TB1> INFO: Expecting 2163200 events.
[10:50:25.470] <TB1> INFO: 929740 events read in total (37241ms).
[10:51:03.757] <TB1> INFO: 1857800 events read in total (75528ms).
[10:51:16.483] <TB1> INFO: 2163200 events read in total (88254ms).
[10:51:16.521] <TB1> INFO: Test took 89129ms.
[10:51:29.937] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 20.462024 .. 43.323298
[10:51:30.012] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 10 .. 53 (-1/-1) hits flags = 528 (plus default)
[10:51:30.020] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:51:30.021] <TB1> INFO: run 1 of 1
[10:51:30.288] <TB1> INFO: Expecting 1830400 events.
[10:52:09.240] <TB1> INFO: 927000 events read in total (38396ms).
[10:52:46.017] <TB1> INFO: 1830400 events read in total (75173ms).
[10:52:46.028] <TB1> INFO: Test took 76007ms.
[10:52:59.425] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 21.622309 .. 42.593802
[10:52:59.500] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 11 .. 52 (-1/-1) hits flags = 528 (plus default)
[10:52:59.508] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:52:59.508] <TB1> INFO: run 1 of 1
[10:52:59.772] <TB1> INFO: Expecting 1747200 events.
[10:53:38.206] <TB1> INFO: 928770 events read in total (37878ms).
[10:54:12.145] <TB1> INFO: 1747200 events read in total (71817ms).
[10:54:12.157] <TB1> INFO: Test took 72648ms.
[10:54:26.568] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[10:54:26.568] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[10:54:26.581] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[10:54:26.581] <TB1> INFO: run 1 of 1
[10:54:26.877] <TB1> INFO: Expecting 1705600 events.
[10:55:04.658] <TB1> INFO: 878860 events read in total (37225ms).
[10:55:40.487] <TB1> INFO: 1705600 events read in total (73054ms).
[10:55:40.506] <TB1> INFO: Test took 73926ms.
[10:55:53.819] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C0.dat
[10:55:53.819] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C1.dat
[10:55:53.819] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C2.dat
[10:55:53.819] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C3.dat
[10:55:53.820] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C4.dat
[10:55:53.820] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C5.dat
[10:55:53.820] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C6.dat
[10:55:53.820] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C7.dat
[10:55:53.820] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C8.dat
[10:55:53.820] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C9.dat
[10:55:53.820] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C10.dat
[10:55:53.820] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C11.dat
[10:55:53.820] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C12.dat
[10:55:53.821] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C13.dat
[10:55:53.821] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C14.dat
[10:55:53.821] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C15.dat
[10:55:53.821] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C0.dat
[10:55:53.827] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C1.dat
[10:55:53.833] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C2.dat
[10:55:53.839] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C3.dat
[10:55:53.845] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C4.dat
[10:55:53.851] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C5.dat
[10:55:53.857] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C6.dat
[10:55:53.863] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C7.dat
[10:55:53.870] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C8.dat
[10:55:53.876] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C9.dat
[10:55:53.882] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C10.dat
[10:55:53.888] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C11.dat
[10:55:53.894] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C12.dat
[10:55:53.901] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C13.dat
[10:55:53.907] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C14.dat
[10:55:53.913] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//trimParameters35_C15.dat
[10:55:53.919] <TB1> INFO: PixTestTrim::trimTest() done
[10:55:53.919] <TB1> INFO: vtrim: 236 95 104 103 93 116 96 109 105 121 98 90 115 84 104 121
[10:55:53.919] <TB1> INFO: vthrcomp: 99 102 100 99 93 107 84 97 91 108 97 96 103 96 100 104
[10:55:53.919] <TB1> INFO: vcal mean: 35.02 34.99 34.99 34.97 35.00 35.01 34.96 34.99 35.03 34.98 34.95 34.95 34.89 34.92 34.90 34.99
[10:55:53.919] <TB1> INFO: vcal RMS: 0.87 0.86 0.92 0.84 0.84 0.87 0.86 0.93 0.84 0.93 0.84 0.78 0.94 0.88 0.90 0.93
[10:55:53.919] <TB1> INFO: bits mean: 8.57 8.60 9.41 9.79 9.38 9.09 9.43 9.47 9.36 8.01 9.74 9.31 9.59 9.83 9.88 9.20
[10:55:53.919] <TB1> INFO: bits RMS: 3.32 3.01 2.83 2.61 2.77 2.43 2.68 2.77 2.55 2.71 2.71 2.95 2.59 2.62 2.64 2.56
[10:55:53.925] <TB1> INFO: ----------------------------------------------------------------------
[10:55:53.925] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[10:55:53.925] <TB1> INFO: ----------------------------------------------------------------------
[10:55:53.927] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[10:55:53.936] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:55:53.936] <TB1> INFO: run 1 of 1
[10:55:54.200] <TB1> INFO: Expecting 4160000 events.
[10:56:36.709] <TB1> INFO: 954890 events read in total (41952ms).
[10:57:18.689] <TB1> INFO: 1903855 events read in total (83932ms).
[10:58:00.442] <TB1> INFO: 2844995 events read in total (125685ms).
[10:58:42.206] <TB1> INFO: 3784670 events read in total (167449ms).
[10:58:59.118] <TB1> INFO: 4160000 events read in total (184361ms).
[10:58:59.154] <TB1> INFO: Test took 185219ms.
[10:59:27.180] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 195 (-1/-1) hits flags = 528 (plus default)
[10:59:27.188] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[10:59:27.188] <TB1> INFO: run 1 of 1
[10:59:27.447] <TB1> INFO: Expecting 4076800 events.
[11:00:09.534] <TB1> INFO: 930775 events read in total (41530ms).
[11:00:51.613] <TB1> INFO: 1856195 events read in total (83609ms).
[11:01:32.591] <TB1> INFO: 2774075 events read in total (124588ms).
[11:02:13.718] <TB1> INFO: 3689955 events read in total (165714ms).
[11:02:31.434] <TB1> INFO: 4076800 events read in total (183430ms).
[11:02:31.471] <TB1> INFO: Test took 184283ms.
[11:03:00.887] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 182 (-1/-1) hits flags = 528 (plus default)
[11:03:00.895] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:03:00.895] <TB1> INFO: run 1 of 1
[11:03:01.158] <TB1> INFO: Expecting 3806400 events.
[11:03:43.777] <TB1> INFO: 957885 events read in total (42063ms).
[11:04:26.439] <TB1> INFO: 1908890 events read in total (84725ms).
[11:05:07.785] <TB1> INFO: 2849960 events read in total (126071ms).
[11:05:49.282] <TB1> INFO: 3793925 events read in total (167568ms).
[11:05:50.218] <TB1> INFO: 3806400 events read in total (168504ms).
[11:05:50.257] <TB1> INFO: Test took 169362ms.
[11:06:17.124] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 182 (-1/-1) hits flags = 528 (plus default)
[11:06:17.132] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:06:17.132] <TB1> INFO: run 1 of 1
[11:06:17.423] <TB1> INFO: Expecting 3806400 events.
[11:07:00.293] <TB1> INFO: 957615 events read in total (42314ms).
[11:07:42.355] <TB1> INFO: 1908145 events read in total (84376ms).
[11:08:23.825] <TB1> INFO: 2848645 events read in total (125846ms).
[11:09:05.821] <TB1> INFO: 3792015 events read in total (167842ms).
[11:09:06.805] <TB1> INFO: 3806400 events read in total (168826ms).
[11:09:06.844] <TB1> INFO: Test took 169711ms.
[11:09:35.834] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 184 (-1/-1) hits flags = 528 (plus default)
[11:09:35.842] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[11:09:35.842] <TB1> INFO: run 1 of 1
[11:09:36.116] <TB1> INFO: Expecting 3848000 events.
[11:10:18.672] <TB1> INFO: 952695 events read in total (41999ms).
[11:11:00.364] <TB1> INFO: 1898875 events read in total (83691ms).
[11:11:41.813] <TB1> INFO: 2835570 events read in total (125140ms).
[11:12:22.938] <TB1> INFO: 3772025 events read in total (166265ms).
[11:12:26.658] <TB1> INFO: 3848000 events read in total (169985ms).
[11:12:26.686] <TB1> INFO: Test took 170844ms.
[11:12:55.002] <TB1> INFO: PixTestTrim::trimBitTest() done
[11:12:55.003] <TB1> INFO: PixTestTrim::doTest() done, duration: 3022 seconds
[11:12:55.003] <TB1> INFO: Decoding statistics:
[11:12:55.003] <TB1> INFO: General information:
[11:12:55.003] <TB1> INFO: 16bit words read: 0
[11:12:55.003] <TB1> INFO: valid events total: 0
[11:12:55.003] <TB1> INFO: empty events: 0
[11:12:55.003] <TB1> INFO: valid events with pixels: 0
[11:12:55.003] <TB1> INFO: valid pixel hits: 0
[11:12:55.003] <TB1> INFO: Event errors: 0
[11:12:55.003] <TB1> INFO: start marker: 0
[11:12:55.003] <TB1> INFO: stop marker: 0
[11:12:55.003] <TB1> INFO: overflow: 0
[11:12:55.003] <TB1> INFO: invalid 5bit words: 0
[11:12:55.003] <TB1> INFO: invalid XOR eye diagram: 0
[11:12:55.003] <TB1> INFO: frame (failed synchr.): 0
[11:12:55.003] <TB1> INFO: idle data (no TBM trl): 0
[11:12:55.003] <TB1> INFO: no data (only TBM hdr): 0
[11:12:55.003] <TB1> INFO: TBM errors: 0
[11:12:55.003] <TB1> INFO: flawed TBM headers: 0
[11:12:55.003] <TB1> INFO: flawed TBM trailers: 0
[11:12:55.003] <TB1> INFO: event ID mismatches: 0
[11:12:55.003] <TB1> INFO: ROC errors: 0
[11:12:55.003] <TB1> INFO: missing ROC header(s): 0
[11:12:55.003] <TB1> INFO: misplaced readback start: 0
[11:12:55.003] <TB1> INFO: Pixel decoding errors: 0
[11:12:55.003] <TB1> INFO: pixel data incomplete: 0
[11:12:55.003] <TB1> INFO: pixel address: 0
[11:12:55.003] <TB1> INFO: pulse height fill bit: 0
[11:12:55.003] <TB1> INFO: buffer corruption: 0
[11:12:55.735] <TB1> INFO: ######################################################################
[11:12:55.735] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:12:55.735] <TB1> INFO: ######################################################################
[11:12:56.008] <TB1> INFO: Expecting 41600 events.
[11:12:59.849] <TB1> INFO: 41600 events read in total (3285ms).
[11:12:59.850] <TB1> INFO: Test took 4114ms.
[11:13:00.340] <TB1> INFO: Expecting 41600 events.
[11:13:04.158] <TB1> INFO: 41600 events read in total (3261ms).
[11:13:04.158] <TB1> INFO: Test took 4110ms.
[11:13:04.439] <TB1> INFO: Expecting 41600 events.
[11:13:08.264] <TB1> INFO: 41600 events read in total (3269ms).
[11:13:08.265] <TB1> INFO: Test took 4086ms.
[11:13:08.520] <TB1> INFO: Expecting 2560 events.
[11:13:09.376] <TB1> INFO: 2560 events read in total (300ms).
[11:13:09.376] <TB1> INFO: Test took 1098ms.
[11:13:09.681] <TB1> INFO: Expecting 2560 events.
[11:13:10.537] <TB1> INFO: 2560 events read in total (300ms).
[11:13:10.537] <TB1> INFO: Test took 1160ms.
[11:13:10.842] <TB1> INFO: Expecting 2560 events.
[11:13:11.696] <TB1> INFO: 2560 events read in total (298ms).
[11:13:11.697] <TB1> INFO: Test took 1160ms.
[11:13:12.001] <TB1> INFO: Expecting 2560 events.
[11:13:12.853] <TB1> INFO: 2560 events read in total (296ms).
[11:13:12.853] <TB1> INFO: Test took 1156ms.
[11:13:13.158] <TB1> INFO: Expecting 2560 events.
[11:13:14.011] <TB1> INFO: 2560 events read in total (297ms).
[11:13:14.011] <TB1> INFO: Test took 1157ms.
[11:13:14.316] <TB1> INFO: Expecting 2560 events.
[11:13:15.176] <TB1> INFO: 2560 events read in total (303ms).
[11:13:15.176] <TB1> INFO: Test took 1164ms.
[11:13:15.482] <TB1> INFO: Expecting 2560 events.
[11:13:16.340] <TB1> INFO: 2560 events read in total (302ms).
[11:13:16.340] <TB1> INFO: Test took 1163ms.
[11:13:16.645] <TB1> INFO: Expecting 2560 events.
[11:13:17.497] <TB1> INFO: 2560 events read in total (295ms).
[11:13:17.497] <TB1> INFO: Test took 1157ms.
[11:13:17.802] <TB1> INFO: Expecting 2560 events.
[11:13:18.655] <TB1> INFO: 2560 events read in total (297ms).
[11:13:18.655] <TB1> INFO: Test took 1158ms.
[11:13:18.959] <TB1> INFO: Expecting 2560 events.
[11:13:19.813] <TB1> INFO: 2560 events read in total (297ms).
[11:13:19.813] <TB1> INFO: Test took 1157ms.
[11:13:20.118] <TB1> INFO: Expecting 2560 events.
[11:13:20.970] <TB1> INFO: 2560 events read in total (296ms).
[11:13:20.970] <TB1> INFO: Test took 1157ms.
[11:13:21.275] <TB1> INFO: Expecting 2560 events.
[11:13:22.130] <TB1> INFO: 2560 events read in total (299ms).
[11:13:22.130] <TB1> INFO: Test took 1160ms.
[11:13:22.435] <TB1> INFO: Expecting 2560 events.
[11:13:23.287] <TB1> INFO: 2560 events read in total (296ms).
[11:13:23.288] <TB1> INFO: Test took 1158ms.
[11:13:23.592] <TB1> INFO: Expecting 2560 events.
[11:13:24.445] <TB1> INFO: 2560 events read in total (296ms).
[11:13:24.446] <TB1> INFO: Test took 1158ms.
[11:13:24.750] <TB1> INFO: Expecting 2560 events.
[11:13:25.604] <TB1> INFO: 2560 events read in total (298ms).
[11:13:25.604] <TB1> INFO: Test took 1158ms.
[11:13:25.909] <TB1> INFO: Expecting 2560 events.
[11:13:26.763] <TB1> INFO: 2560 events read in total (298ms).
[11:13:26.763] <TB1> INFO: Test took 1158ms.
[11:13:26.765] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:13:27.068] <TB1> INFO: Expecting 655360 events.
[11:13:43.878] <TB1> INFO: 655360 events read in total (16253ms).
[11:13:43.887] <TB1> INFO: Expecting 655360 events.
[11:14:00.557] <TB1> INFO: 655360 events read in total (16268ms).
[11:14:00.570] <TB1> INFO: Expecting 655360 events.
[11:14:17.143] <TB1> INFO: 655360 events read in total (16172ms).
[11:14:17.159] <TB1> INFO: Expecting 655360 events.
[11:14:33.699] <TB1> INFO: 655360 events read in total (16138ms).
[11:14:33.718] <TB1> INFO: Expecting 655360 events.
[11:14:50.214] <TB1> INFO: 655360 events read in total (16094ms).
[11:14:50.247] <TB1> INFO: Expecting 655360 events.
[11:15:06.740] <TB1> INFO: 655360 events read in total (16091ms).
[11:15:06.768] <TB1> INFO: Expecting 655360 events.
[11:15:23.518] <TB1> INFO: 655360 events read in total (16348ms).
[11:15:23.551] <TB1> INFO: Expecting 655360 events.
[11:15:40.343] <TB1> INFO: 655360 events read in total (16390ms).
[11:15:40.377] <TB1> INFO: Expecting 655360 events.
[11:15:57.223] <TB1> INFO: 655360 events read in total (16445ms).
[11:15:57.259] <TB1> INFO: Expecting 655360 events.
[11:16:14.094] <TB1> INFO: 655360 events read in total (16433ms).
[11:16:14.133] <TB1> INFO: Expecting 655360 events.
[11:16:31.004] <TB1> INFO: 655360 events read in total (16470ms).
[11:16:31.047] <TB1> INFO: Expecting 655360 events.
[11:16:47.796] <TB1> INFO: 655360 events read in total (16347ms).
[11:16:47.842] <TB1> INFO: Expecting 655360 events.
[11:17:04.657] <TB1> INFO: 655360 events read in total (16413ms).
[11:17:04.708] <TB1> INFO: Expecting 655360 events.
[11:17:21.492] <TB1> INFO: 655360 events read in total (16383ms).
[11:17:21.550] <TB1> INFO: Expecting 655360 events.
[11:17:38.276] <TB1> INFO: 655360 events read in total (16324ms).
[11:17:38.334] <TB1> INFO: Expecting 655360 events.
[11:17:55.072] <TB1> INFO: 655360 events read in total (16336ms).
[11:17:55.133] <TB1> INFO: Test took 268368ms.
[11:17:55.212] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:17:55.443] <TB1> INFO: Expecting 655360 events.
[11:18:12.211] <TB1> INFO: 655360 events read in total (16212ms).
[11:18:12.220] <TB1> INFO: Expecting 655360 events.
[11:18:28.832] <TB1> INFO: 655360 events read in total (16211ms).
[11:18:28.844] <TB1> INFO: Expecting 655360 events.
[11:18:45.658] <TB1> INFO: 655360 events read in total (16412ms).
[11:18:45.675] <TB1> INFO: Expecting 655360 events.
[11:19:02.407] <TB1> INFO: 655360 events read in total (16330ms).
[11:19:02.427] <TB1> INFO: Expecting 655360 events.
[11:19:19.093] <TB1> INFO: 655360 events read in total (16266ms).
[11:19:19.116] <TB1> INFO: Expecting 655360 events.
[11:19:35.884] <TB1> INFO: 655360 events read in total (16366ms).
[11:19:35.910] <TB1> INFO: Expecting 655360 events.
[11:19:52.510] <TB1> INFO: 655360 events read in total (16198ms).
[11:19:52.539] <TB1> INFO: Expecting 655360 events.
[11:20:09.307] <TB1> INFO: 655360 events read in total (16366ms).
[11:20:09.343] <TB1> INFO: Expecting 655360 events.
[11:20:26.107] <TB1> INFO: 655360 events read in total (16362ms).
[11:20:26.143] <TB1> INFO: Expecting 655360 events.
[11:20:42.907] <TB1> INFO: 655360 events read in total (16362ms).
[11:20:42.948] <TB1> INFO: Expecting 655360 events.
[11:20:59.701] <TB1> INFO: 655360 events read in total (16352ms).
[11:20:59.748] <TB1> INFO: Expecting 655360 events.
[11:21:16.481] <TB1> INFO: 655360 events read in total (16331ms).
[11:21:16.528] <TB1> INFO: Expecting 655360 events.
[11:21:33.242] <TB1> INFO: 655360 events read in total (16313ms).
[11:21:33.292] <TB1> INFO: Expecting 655360 events.
[11:21:49.932] <TB1> INFO: 655360 events read in total (16238ms).
[11:21:49.986] <TB1> INFO: Expecting 655360 events.
[11:22:06.715] <TB1> INFO: 655360 events read in total (16327ms).
[11:22:06.775] <TB1> INFO: Expecting 655360 events.
[11:22:23.721] <TB1> INFO: 655360 events read in total (16545ms).
[11:22:23.783] <TB1> INFO: Test took 268571ms.
[11:22:24.007] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:22:24.014] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:22:24.022] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:22:24.029] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:22:24.037] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:22:24.044] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:22:24.052] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[11:22:24.059] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[11:22:24.066] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:22:24.072] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:22:24.079] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[11:22:24.085] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[11:22:24.092] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[11:22:24.098] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[11:22:24.105] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[11:22:24.111] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[11:22:24.118] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[11:22:24.124] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:22:24.131] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:22:24.137] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[11:22:24.145] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[11:22:24.153] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[11:22:24.162] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[11:22:24.170] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[11:22:24.178] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[11:22:24.185] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[11:22:24.194] <TB1> INFO: safety margin for low PH: adding 8, margin is now 28
[11:22:24.201] <TB1> INFO: safety margin for low PH: adding 9, margin is now 29
[11:22:24.209] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:22:24.216] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:22:24.224] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:22:24.231] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:22:24.238] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:22:24.246] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[11:22:24.288] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C0.dat
[11:22:24.288] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C1.dat
[11:22:24.289] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C2.dat
[11:22:24.289] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C3.dat
[11:22:24.290] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C4.dat
[11:22:24.290] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C5.dat
[11:22:24.290] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C6.dat
[11:22:24.290] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C7.dat
[11:22:24.291] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C8.dat
[11:22:24.291] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C9.dat
[11:22:24.291] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C10.dat
[11:22:24.292] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C11.dat
[11:22:24.292] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C12.dat
[11:22:24.293] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C13.dat
[11:22:24.293] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C14.dat
[11:22:24.293] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//dacParameters35_C15.dat
[11:22:24.564] <TB1> INFO: Expecting 41600 events.
[11:22:28.009] <TB1> INFO: 41600 events read in total (2889ms).
[11:22:28.010] <TB1> INFO: Test took 3713ms.
[11:22:28.452] <TB1> INFO: Expecting 41600 events.
[11:22:31.939] <TB1> INFO: 41600 events read in total (2931ms).
[11:22:31.940] <TB1> INFO: Test took 3752ms.
[11:22:32.383] <TB1> INFO: Expecting 41600 events.
[11:22:35.884] <TB1> INFO: 41600 events read in total (2944ms).
[11:22:35.884] <TB1> INFO: Test took 3766ms.
[11:22:36.065] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:36.153] <TB1> INFO: Expecting 2560 events.
[11:22:37.012] <TB1> INFO: 2560 events read in total (303ms).
[11:22:37.013] <TB1> INFO: Test took 948ms.
[11:22:37.015] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:37.319] <TB1> INFO: Expecting 2560 events.
[11:22:38.177] <TB1> INFO: 2560 events read in total (302ms).
[11:22:38.177] <TB1> INFO: Test took 1162ms.
[11:22:38.180] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:38.483] <TB1> INFO: Expecting 2560 events.
[11:22:39.340] <TB1> INFO: 2560 events read in total (301ms).
[11:22:39.341] <TB1> INFO: Test took 1161ms.
[11:22:39.342] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:39.646] <TB1> INFO: Expecting 2560 events.
[11:22:40.500] <TB1> INFO: 2560 events read in total (297ms).
[11:22:40.500] <TB1> INFO: Test took 1158ms.
[11:22:40.502] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:40.806] <TB1> INFO: Expecting 2560 events.
[11:22:41.659] <TB1> INFO: 2560 events read in total (297ms).
[11:22:41.659] <TB1> INFO: Test took 1157ms.
[11:22:41.661] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:41.964] <TB1> INFO: Expecting 2560 events.
[11:22:42.817] <TB1> INFO: 2560 events read in total (297ms).
[11:22:42.817] <TB1> INFO: Test took 1156ms.
[11:22:42.819] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:43.124] <TB1> INFO: Expecting 2560 events.
[11:22:43.978] <TB1> INFO: 2560 events read in total (298ms).
[11:22:43.978] <TB1> INFO: Test took 1159ms.
[11:22:43.980] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:44.284] <TB1> INFO: Expecting 2560 events.
[11:22:45.136] <TB1> INFO: 2560 events read in total (296ms).
[11:22:45.136] <TB1> INFO: Test took 1156ms.
[11:22:45.138] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:45.442] <TB1> INFO: Expecting 2560 events.
[11:22:46.298] <TB1> INFO: 2560 events read in total (300ms).
[11:22:46.298] <TB1> INFO: Test took 1160ms.
[11:22:46.300] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:46.605] <TB1> INFO: Expecting 2560 events.
[11:22:47.462] <TB1> INFO: 2560 events read in total (301ms).
[11:22:47.462] <TB1> INFO: Test took 1162ms.
[11:22:47.464] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:47.768] <TB1> INFO: Expecting 2560 events.
[11:22:48.625] <TB1> INFO: 2560 events read in total (301ms).
[11:22:48.625] <TB1> INFO: Test took 1161ms.
[11:22:48.627] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:48.931] <TB1> INFO: Expecting 2560 events.
[11:22:49.784] <TB1> INFO: 2560 events read in total (296ms).
[11:22:49.785] <TB1> INFO: Test took 1158ms.
[11:22:49.787] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:50.090] <TB1> INFO: Expecting 2560 events.
[11:22:50.946] <TB1> INFO: 2560 events read in total (300ms).
[11:22:50.947] <TB1> INFO: Test took 1160ms.
[11:22:50.949] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:51.252] <TB1> INFO: Expecting 2560 events.
[11:22:52.106] <TB1> INFO: 2560 events read in total (298ms).
[11:22:52.107] <TB1> INFO: Test took 1158ms.
[11:22:52.109] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:52.412] <TB1> INFO: Expecting 2560 events.
[11:22:53.265] <TB1> INFO: 2560 events read in total (297ms).
[11:22:53.266] <TB1> INFO: Test took 1157ms.
[11:22:53.268] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:53.571] <TB1> INFO: Expecting 2560 events.
[11:22:54.429] <TB1> INFO: 2560 events read in total (302ms).
[11:22:54.429] <TB1> INFO: Test took 1161ms.
[11:22:54.431] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:54.736] <TB1> INFO: Expecting 2560 events.
[11:22:55.590] <TB1> INFO: 2560 events read in total (298ms).
[11:22:55.590] <TB1> INFO: Test took 1159ms.
[11:22:55.593] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:55.895] <TB1> INFO: Expecting 2560 events.
[11:22:56.753] <TB1> INFO: 2560 events read in total (302ms).
[11:22:56.753] <TB1> INFO: Test took 1160ms.
[11:22:56.756] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:57.058] <TB1> INFO: Expecting 2560 events.
[11:22:57.912] <TB1> INFO: 2560 events read in total (298ms).
[11:22:57.912] <TB1> INFO: Test took 1156ms.
[11:22:57.914] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:58.218] <TB1> INFO: Expecting 2560 events.
[11:22:59.072] <TB1> INFO: 2560 events read in total (298ms).
[11:22:59.072] <TB1> INFO: Test took 1158ms.
[11:22:59.074] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:22:59.378] <TB1> INFO: Expecting 2560 events.
[11:23:00.233] <TB1> INFO: 2560 events read in total (299ms).
[11:23:00.233] <TB1> INFO: Test took 1159ms.
[11:23:00.234] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:23:00.539] <TB1> INFO: Expecting 2560 events.
[11:23:01.396] <TB1> INFO: 2560 events read in total (301ms).
[11:23:01.397] <TB1> INFO: Test took 1163ms.
[11:23:01.399] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:23:01.701] <TB1> INFO: Expecting 2560 events.
[11:23:02.553] <TB1> INFO: 2560 events read in total (296ms).
[11:23:02.553] <TB1> INFO: Test took 1154ms.
[11:23:02.555] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:23:02.859] <TB1> INFO: Expecting 2560 events.
[11:23:03.717] <TB1> INFO: 2560 events read in total (302ms).
[11:23:03.718] <TB1> INFO: Test took 1163ms.
[11:23:03.720] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:23:04.023] <TB1> INFO: Expecting 2560 events.
[11:23:04.877] <TB1> INFO: 2560 events read in total (298ms).
[11:23:04.877] <TB1> INFO: Test took 1157ms.
[11:23:04.879] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:23:05.182] <TB1> INFO: Expecting 2560 events.
[11:23:06.037] <TB1> INFO: 2560 events read in total (298ms).
[11:23:06.038] <TB1> INFO: Test took 1159ms.
[11:23:06.040] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:23:06.344] <TB1> INFO: Expecting 2560 events.
[11:23:07.196] <TB1> INFO: 2560 events read in total (296ms).
[11:23:07.196] <TB1> INFO: Test took 1156ms.
[11:23:07.198] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:23:07.502] <TB1> INFO: Expecting 2560 events.
[11:23:08.363] <TB1> INFO: 2560 events read in total (305ms).
[11:23:08.363] <TB1> INFO: Test took 1165ms.
[11:23:08.365] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:23:08.669] <TB1> INFO: Expecting 2560 events.
[11:23:09.524] <TB1> INFO: 2560 events read in total (299ms).
[11:23:09.524] <TB1> INFO: Test took 1159ms.
[11:23:09.527] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:23:09.828] <TB1> INFO: Expecting 2560 events.
[11:23:10.686] <TB1> INFO: 2560 events read in total (302ms).
[11:23:10.686] <TB1> INFO: Test took 1160ms.
[11:23:10.687] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:23:10.992] <TB1> INFO: Expecting 2560 events.
[11:23:11.844] <TB1> INFO: 2560 events read in total (296ms).
[11:23:11.844] <TB1> INFO: Test took 1157ms.
[11:23:11.846] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:23:12.150] <TB1> INFO: Expecting 2560 events.
[11:23:13.003] <TB1> INFO: 2560 events read in total (297ms).
[11:23:13.003] <TB1> INFO: Test took 1157ms.
[11:23:13.454] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 617 seconds
[11:23:13.454] <TB1> INFO: PH scale (per ROC): 82 76 86 74 82 75 92 83 77 81 90 81 83 79 85 78
[11:23:13.454] <TB1> INFO: PH offset (per ROC): 159 165 174 157 158 160 154 176 148 176 150 163 157 156 154 172
[11:23:13.459] <TB1> INFO: Decoding statistics:
[11:23:13.459] <TB1> INFO: General information:
[11:23:13.459] <TB1> INFO: 16bit words read: 91912
[11:23:13.459] <TB1> INFO: valid events total: 10240
[11:23:13.459] <TB1> INFO: empty events: 7742
[11:23:13.459] <TB1> INFO: valid events with pixels: 2498
[11:23:13.459] <TB1> INFO: valid pixel hits: 2498
[11:23:13.459] <TB1> INFO: Event errors: 0
[11:23:13.459] <TB1> INFO: start marker: 0
[11:23:13.459] <TB1> INFO: stop marker: 0
[11:23:13.459] <TB1> INFO: overflow: 0
[11:23:13.459] <TB1> INFO: invalid 5bit words: 0
[11:23:13.459] <TB1> INFO: invalid XOR eye diagram: 0
[11:23:13.459] <TB1> INFO: frame (failed synchr.): 0
[11:23:13.459] <TB1> INFO: idle data (no TBM trl): 0
[11:23:13.459] <TB1> INFO: no data (only TBM hdr): 0
[11:23:13.459] <TB1> INFO: TBM errors: 0
[11:23:13.459] <TB1> INFO: flawed TBM headers: 0
[11:23:13.459] <TB1> INFO: flawed TBM trailers: 0
[11:23:13.459] <TB1> INFO: event ID mismatches: 0
[11:23:13.459] <TB1> INFO: ROC errors: 0
[11:23:13.459] <TB1> INFO: missing ROC header(s): 0
[11:23:13.459] <TB1> INFO: misplaced readback start: 0
[11:23:13.459] <TB1> INFO: Pixel decoding errors: 0
[11:23:13.459] <TB1> INFO: pixel data incomplete: 0
[11:23:13.459] <TB1> INFO: pixel address: 0
[11:23:13.459] <TB1> INFO: pulse height fill bit: 0
[11:23:13.459] <TB1> INFO: buffer corruption: 0
[11:23:13.636] <TB1> INFO: ######################################################################
[11:23:13.636] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:23:13.636] <TB1> INFO: ######################################################################
[11:23:13.645] <TB1> INFO: scanning low vcal = 10
[11:23:13.909] <TB1> INFO: Expecting 41600 events.
[11:23:17.439] <TB1> INFO: 41600 events read in total (2973ms).
[11:23:17.439] <TB1> INFO: Test took 3794ms.
[11:23:17.441] <TB1> INFO: scanning low vcal = 20
[11:23:17.745] <TB1> INFO: Expecting 41600 events.
[11:23:21.281] <TB1> INFO: 41600 events read in total (2980ms).
[11:23:21.282] <TB1> INFO: Test took 3841ms.
[11:23:21.283] <TB1> INFO: scanning low vcal = 30
[11:23:21.586] <TB1> INFO: Expecting 41600 events.
[11:23:25.169] <TB1> INFO: 41600 events read in total (3026ms).
[11:23:25.170] <TB1> INFO: Test took 3887ms.
[11:23:25.172] <TB1> INFO: scanning low vcal = 40
[11:23:25.499] <TB1> INFO: Expecting 41600 events.
[11:23:29.634] <TB1> INFO: 41600 events read in total (3578ms).
[11:23:29.635] <TB1> INFO: Test took 4463ms.
[11:23:29.637] <TB1> INFO: scanning low vcal = 50
[11:23:29.907] <TB1> INFO: Expecting 41600 events.
[11:23:34.124] <TB1> INFO: 41600 events read in total (3661ms).
[11:23:34.125] <TB1> INFO: Test took 4487ms.
[11:23:34.127] <TB1> INFO: scanning low vcal = 60
[11:23:34.400] <TB1> INFO: Expecting 41600 events.
[11:23:38.619] <TB1> INFO: 41600 events read in total (3663ms).
[11:23:38.620] <TB1> INFO: Test took 4493ms.
[11:23:38.622] <TB1> INFO: scanning low vcal = 70
[11:23:38.922] <TB1> INFO: Expecting 41600 events.
[11:23:43.136] <TB1> INFO: 41600 events read in total (3658ms).
[11:23:43.136] <TB1> INFO: Test took 4514ms.
[11:23:43.139] <TB1> INFO: scanning low vcal = 80
[11:23:43.418] <TB1> INFO: Expecting 41600 events.
[11:23:47.605] <TB1> INFO: 41600 events read in total (3631ms).
[11:23:47.606] <TB1> INFO: Test took 4467ms.
[11:23:47.608] <TB1> INFO: scanning low vcal = 90
[11:23:47.869] <TB1> INFO: Expecting 41600 events.
[11:23:52.041] <TB1> INFO: 41600 events read in total (3615ms).
[11:23:52.041] <TB1> INFO: Test took 4432ms.
[11:23:52.044] <TB1> INFO: scanning low vcal = 100
[11:23:52.303] <TB1> INFO: Expecting 41600 events.
[11:23:56.484] <TB1> INFO: 41600 events read in total (3625ms).
[11:23:56.485] <TB1> INFO: Test took 4441ms.
[11:23:56.487] <TB1> INFO: scanning low vcal = 110
[11:23:56.757] <TB1> INFO: Expecting 41600 events.
[11:24:00.984] <TB1> INFO: 41600 events read in total (3671ms).
[11:24:00.984] <TB1> INFO: Test took 4497ms.
[11:24:00.987] <TB1> INFO: scanning low vcal = 120
[11:24:01.263] <TB1> INFO: Expecting 41600 events.
[11:24:05.445] <TB1> INFO: 41600 events read in total (3626ms).
[11:24:05.445] <TB1> INFO: Test took 4458ms.
[11:24:05.448] <TB1> INFO: scanning low vcal = 130
[11:24:05.718] <TB1> INFO: Expecting 41600 events.
[11:24:09.889] <TB1> INFO: 41600 events read in total (3615ms).
[11:24:09.890] <TB1> INFO: Test took 4442ms.
[11:24:09.892] <TB1> INFO: scanning low vcal = 140
[11:24:10.162] <TB1> INFO: Expecting 41600 events.
[11:24:14.407] <TB1> INFO: 41600 events read in total (3689ms).
[11:24:14.408] <TB1> INFO: Test took 4516ms.
[11:24:14.411] <TB1> INFO: scanning low vcal = 150
[11:24:14.758] <TB1> INFO: Expecting 41600 events.
[11:24:19.003] <TB1> INFO: 41600 events read in total (3689ms).
[11:24:19.003] <TB1> INFO: Test took 4592ms.
[11:24:19.006] <TB1> INFO: scanning low vcal = 160
[11:24:19.285] <TB1> INFO: Expecting 41600 events.
[11:24:23.452] <TB1> INFO: 41600 events read in total (3611ms).
[11:24:23.453] <TB1> INFO: Test took 4447ms.
[11:24:23.455] <TB1> INFO: scanning low vcal = 170
[11:24:23.722] <TB1> INFO: Expecting 41600 events.
[11:24:27.929] <TB1> INFO: 41600 events read in total (3650ms).
[11:24:27.929] <TB1> INFO: Test took 4474ms.
[11:24:27.933] <TB1> INFO: scanning low vcal = 180
[11:24:28.255] <TB1> INFO: Expecting 41600 events.
[11:24:32.437] <TB1> INFO: 41600 events read in total (3626ms).
[11:24:32.438] <TB1> INFO: Test took 4505ms.
[11:24:32.441] <TB1> INFO: scanning low vcal = 190
[11:24:32.796] <TB1> INFO: Expecting 41600 events.
[11:24:36.979] <TB1> INFO: 41600 events read in total (3626ms).
[11:24:36.980] <TB1> INFO: Test took 4539ms.
[11:24:36.982] <TB1> INFO: scanning low vcal = 200
[11:24:37.255] <TB1> INFO: Expecting 41600 events.
[11:24:41.444] <TB1> INFO: 41600 events read in total (3633ms).
[11:24:41.444] <TB1> INFO: Test took 4462ms.
[11:24:41.449] <TB1> INFO: scanning low vcal = 210
[11:24:41.787] <TB1> INFO: Expecting 41600 events.
[11:24:45.993] <TB1> INFO: 41600 events read in total (3650ms).
[11:24:45.993] <TB1> INFO: Test took 4544ms.
[11:24:45.996] <TB1> INFO: scanning low vcal = 220
[11:24:46.266] <TB1> INFO: Expecting 41600 events.
[11:24:50.473] <TB1> INFO: 41600 events read in total (3650ms).
[11:24:50.473] <TB1> INFO: Test took 4477ms.
[11:24:50.476] <TB1> INFO: scanning low vcal = 230
[11:24:50.755] <TB1> INFO: Expecting 41600 events.
[11:24:54.941] <TB1> INFO: 41600 events read in total (3630ms).
[11:24:54.941] <TB1> INFO: Test took 4465ms.
[11:24:54.943] <TB1> INFO: scanning low vcal = 240
[11:24:55.208] <TB1> INFO: Expecting 41600 events.
[11:24:59.386] <TB1> INFO: 41600 events read in total (3622ms).
[11:24:59.386] <TB1> INFO: Test took 4443ms.
[11:24:59.389] <TB1> INFO: scanning low vcal = 250
[11:24:59.648] <TB1> INFO: Expecting 41600 events.
[11:25:03.826] <TB1> INFO: 41600 events read in total (3622ms).
[11:25:03.827] <TB1> INFO: Test took 4438ms.
[11:25:03.830] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[11:25:04.099] <TB1> INFO: Expecting 41600 events.
[11:25:08.268] <TB1> INFO: 41600 events read in total (3613ms).
[11:25:08.268] <TB1> INFO: Test took 4438ms.
[11:25:08.271] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[11:25:08.541] <TB1> INFO: Expecting 41600 events.
[11:25:12.721] <TB1> INFO: 41600 events read in total (3623ms).
[11:25:12.722] <TB1> INFO: Test took 4451ms.
[11:25:12.724] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[11:25:12.991] <TB1> INFO: Expecting 41600 events.
[11:25:17.165] <TB1> INFO: 41600 events read in total (3618ms).
[11:25:17.165] <TB1> INFO: Test took 4440ms.
[11:25:17.168] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[11:25:17.432] <TB1> INFO: Expecting 41600 events.
[11:25:21.599] <TB1> INFO: 41600 events read in total (3610ms).
[11:25:21.600] <TB1> INFO: Test took 4432ms.
[11:25:21.602] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:25:21.868] <TB1> INFO: Expecting 41600 events.
[11:25:26.074] <TB1> INFO: 41600 events read in total (3650ms).
[11:25:26.075] <TB1> INFO: Test took 4473ms.
[11:25:26.592] <TB1> INFO: PixTestGainPedestal::measure() done
[11:25:58.520] <TB1> INFO: PixTestGainPedestal::fit() done
[11:25:58.520] <TB1> INFO: non-linearity mean: 0.954 0.954 0.958 0.953 0.957 0.959 0.957 0.965 0.953 0.961 0.957 0.952 0.957 0.955 0.958 0.951
[11:25:58.520] <TB1> INFO: non-linearity RMS: 0.005 0.006 0.006 0.006 0.006 0.005 0.006 0.005 0.006 0.004 0.005 0.006 0.006 0.006 0.006 0.006
[11:25:58.520] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[11:25:58.541] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[11:25:58.559] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[11:25:58.578] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[11:25:58.596] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[11:25:58.614] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[11:25:58.632] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[11:25:58.650] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[11:25:58.669] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[11:25:58.687] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[11:25:58.711] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[11:25:58.734] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[11:25:58.753] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[11:25:58.772] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[11:25:58.793] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[11:25:58.814] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[11:25:58.836] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 165 seconds
[11:25:58.836] <TB1> INFO: Decoding statistics:
[11:25:58.836] <TB1> INFO: General information:
[11:25:58.836] <TB1> INFO: 16bit words read: 2662400
[11:25:58.836] <TB1> INFO: valid events total: 166400
[11:25:58.836] <TB1> INFO: empty events: 0
[11:25:58.836] <TB1> INFO: valid events with pixels: 166400
[11:25:58.836] <TB1> INFO: valid pixel hits: 665590
[11:25:58.836] <TB1> INFO: Event errors: 0
[11:25:58.836] <TB1> INFO: start marker: 0
[11:25:58.836] <TB1> INFO: stop marker: 0
[11:25:58.836] <TB1> INFO: overflow: 0
[11:25:58.836] <TB1> INFO: invalid 5bit words: 0
[11:25:58.836] <TB1> INFO: invalid XOR eye diagram: 0
[11:25:58.836] <TB1> INFO: frame (failed synchr.): 0
[11:25:58.836] <TB1> INFO: idle data (no TBM trl): 0
[11:25:58.836] <TB1> INFO: no data (only TBM hdr): 0
[11:25:58.836] <TB1> INFO: TBM errors: 0
[11:25:58.836] <TB1> INFO: flawed TBM headers: 0
[11:25:58.836] <TB1> INFO: flawed TBM trailers: 0
[11:25:58.836] <TB1> INFO: event ID mismatches: 0
[11:25:58.836] <TB1> INFO: ROC errors: 0
[11:25:58.836] <TB1> INFO: missing ROC header(s): 0
[11:25:58.836] <TB1> INFO: misplaced readback start: 0
[11:25:58.836] <TB1> INFO: Pixel decoding errors: 0
[11:25:58.836] <TB1> INFO: pixel data incomplete: 0
[11:25:58.836] <TB1> INFO: pixel address: 0
[11:25:58.836] <TB1> INFO: pulse height fill bit: 0
[11:25:58.836] <TB1> INFO: buffer corruption: 0
[11:25:58.850] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C15.dat
[11:25:58.851] <TB1> INFO: ######################################################################
[11:25:58.851] <TB1> INFO: PixTestReadback::doTest()
[11:25:58.852] <TB1> INFO: ######################################################################
[11:25:58.852] <TB1> INFO: ----------------------------------------------------------------------
[11:25:58.852] <TB1> INFO: PixTestReadback::CalibrateVd()
[11:25:58.852] <TB1> INFO: ----------------------------------------------------------------------
[11:26:08.349] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C0.dat
[11:26:08.349] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C1.dat
[11:26:08.349] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C2.dat
[11:26:08.349] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C3.dat
[11:26:08.349] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C4.dat
[11:26:08.349] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C5.dat
[11:26:08.349] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C6.dat
[11:26:08.349] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C7.dat
[11:26:08.349] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C8.dat
[11:26:08.349] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C9.dat
[11:26:08.349] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C10.dat
[11:26:08.349] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C11.dat
[11:26:08.349] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C12.dat
[11:26:08.349] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C13.dat
[11:26:08.350] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C14.dat
[11:26:08.350] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C15.dat
[11:26:08.371] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:26:08.371] <TB1> INFO: ----------------------------------------------------------------------
[11:26:08.371] <TB1> INFO: PixTestReadback::CalibrateVa()
[11:26:08.371] <TB1> INFO: ----------------------------------------------------------------------
[11:26:17.827] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C0.dat
[11:26:17.827] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C1.dat
[11:26:17.827] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C2.dat
[11:26:17.827] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C3.dat
[11:26:17.827] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C4.dat
[11:26:17.827] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C5.dat
[11:26:17.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C6.dat
[11:26:17.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C7.dat
[11:26:17.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C8.dat
[11:26:17.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C9.dat
[11:26:17.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C10.dat
[11:26:17.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C11.dat
[11:26:17.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C12.dat
[11:26:17.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C13.dat
[11:26:17.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C14.dat
[11:26:17.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C15.dat
[11:26:17.858] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:26:17.858] <TB1> INFO: ----------------------------------------------------------------------
[11:26:17.858] <TB1> INFO: PixTestReadback::readbackVbg()
[11:26:17.858] <TB1> INFO: ----------------------------------------------------------------------
[11:26:25.158] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:26:25.158] <TB1> INFO: ----------------------------------------------------------------------
[11:26:25.158] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[11:26:25.158] <TB1> INFO: ----------------------------------------------------------------------
[11:26:25.158] <TB1> INFO: Vbg will be calibrated using Vd calibration
[11:26:25.158] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 142.6calibrated Vbg = 1.18332 :::*/*/*/*/
[11:26:25.158] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 156.8calibrated Vbg = 1.18349 :::*/*/*/*/
[11:26:25.159] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 152.2calibrated Vbg = 1.18574 :::*/*/*/*/
[11:26:25.159] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 148.9calibrated Vbg = 1.18761 :::*/*/*/*/
[11:26:25.159] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 145.9calibrated Vbg = 1.19 :::*/*/*/*/
[11:26:25.159] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 158.1calibrated Vbg = 1.19708 :::*/*/*/*/
[11:26:25.159] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 156.4calibrated Vbg = 1.18812 :::*/*/*/*/
[11:26:25.159] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 149.8calibrated Vbg = 1.19633 :::*/*/*/*/
[11:26:25.159] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 149.1calibrated Vbg = 1.19232 :::*/*/*/*/
[11:26:25.159] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 156calibrated Vbg = 1.19677 :::*/*/*/*/
[11:26:25.159] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 147.1calibrated Vbg = 1.19499 :::*/*/*/*/
[11:26:25.159] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 148.7calibrated Vbg = 1.18227 :::*/*/*/*/
[11:26:25.159] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 153.5calibrated Vbg = 1.18084 :::*/*/*/*/
[11:26:25.159] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 147.3calibrated Vbg = 1.18015 :::*/*/*/*/
[11:26:25.159] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 156.2calibrated Vbg = 1.18767 :::*/*/*/*/
[11:26:25.159] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 165.2calibrated Vbg = 1.18965 :::*/*/*/*/
[11:26:25.161] <TB1> INFO: ----------------------------------------------------------------------
[11:26:25.161] <TB1> INFO: PixTestReadback::CalibrateIa()
[11:26:25.161] <TB1> INFO: ----------------------------------------------------------------------
[11:29:00.381] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C0.dat
[11:29:00.381] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C1.dat
[11:29:00.381] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C2.dat
[11:29:00.381] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C3.dat
[11:29:00.381] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C4.dat
[11:29:00.381] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C5.dat
[11:29:00.381] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C6.dat
[11:29:00.381] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C7.dat
[11:29:00.381] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C8.dat
[11:29:00.382] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C9.dat
[11:29:00.382] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C10.dat
[11:29:00.382] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C11.dat
[11:29:00.382] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C12.dat
[11:29:00.382] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C13.dat
[11:29:00.382] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C14.dat
[11:29:00.382] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2258_FullQualification_2016-06-15_08h06m_1465970760//002_FulltestPxar_m20//readbackCal_C15.dat
[11:29:00.409] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[11:29:00.410] <TB1> INFO: PixTestReadback::doTest() done
[11:29:00.410] <TB1> INFO: Decoding statistics:
[11:29:00.410] <TB1> INFO: General information:
[11:29:00.410] <TB1> INFO: 16bit words read: 1024
[11:29:00.410] <TB1> INFO: valid events total: 128
[11:29:00.410] <TB1> INFO: empty events: 128
[11:29:00.410] <TB1> INFO: valid events with pixels: 0
[11:29:00.410] <TB1> INFO: valid pixel hits: 0
[11:29:00.410] <TB1> INFO: Event errors: 0
[11:29:00.410] <TB1> INFO: start marker: 0
[11:29:00.410] <TB1> INFO: stop marker: 0
[11:29:00.410] <TB1> INFO: overflow: 0
[11:29:00.410] <TB1> INFO: invalid 5bit words: 0
[11:29:00.410] <TB1> INFO: invalid XOR eye diagram: 0
[11:29:00.410] <TB1> INFO: frame (failed synchr.): 0
[11:29:00.410] <TB1> INFO: idle data (no TBM trl): 0
[11:29:00.410] <TB1> INFO: no data (only TBM hdr): 0
[11:29:00.410] <TB1> INFO: TBM errors: 0
[11:29:00.410] <TB1> INFO: flawed TBM headers: 0
[11:29:00.410] <TB1> INFO: flawed TBM trailers: 0
[11:29:00.410] <TB1> INFO: event ID mismatches: 0
[11:29:00.410] <TB1> INFO: ROC errors: 0
[11:29:00.410] <TB1> INFO: missing ROC header(s): 0
[11:29:00.410] <TB1> INFO: misplaced readback start: 0
[11:29:00.410] <TB1> INFO: Pixel decoding errors: 0
[11:29:00.410] <TB1> INFO: pixel data incomplete: 0
[11:29:00.410] <TB1> INFO: pixel address: 0
[11:29:00.410] <TB1> INFO: pulse height fill bit: 0
[11:29:00.411] <TB1> INFO: buffer corruption: 0
[11:29:00.430] <TB1> INFO: Decoding statistics:
[11:29:00.430] <TB1> INFO: General information:
[11:29:00.430] <TB1> INFO: 16bit words read: 2755336
[11:29:00.430] <TB1> INFO: valid events total: 176768
[11:29:00.430] <TB1> INFO: empty events: 7870
[11:29:00.430] <TB1> INFO: valid events with pixels: 168898
[11:29:00.430] <TB1> INFO: valid pixel hits: 668088
[11:29:00.430] <TB1> INFO: Event errors: 0
[11:29:00.430] <TB1> INFO: start marker: 0
[11:29:00.430] <TB1> INFO: stop marker: 0
[11:29:00.430] <TB1> INFO: overflow: 0
[11:29:00.430] <TB1> INFO: invalid 5bit words: 0
[11:29:00.430] <TB1> INFO: invalid XOR eye diagram: 0
[11:29:00.430] <TB1> INFO: frame (failed synchr.): 0
[11:29:00.430] <TB1> INFO: idle data (no TBM trl): 0
[11:29:00.430] <TB1> INFO: no data (only TBM hdr): 0
[11:29:00.430] <TB1> INFO: TBM errors: 0
[11:29:00.430] <TB1> INFO: flawed TBM headers: 0
[11:29:00.430] <TB1> INFO: flawed TBM trailers: 0
[11:29:00.430] <TB1> INFO: event ID mismatches: 0
[11:29:00.430] <TB1> INFO: ROC errors: 0
[11:29:00.430] <TB1> INFO: missing ROC header(s): 0
[11:29:00.430] <TB1> INFO: misplaced readback start: 0
[11:29:00.430] <TB1> INFO: Pixel decoding errors: 0
[11:29:00.430] <TB1> INFO: pixel data incomplete: 0
[11:29:00.430] <TB1> INFO: pixel address: 0
[11:29:00.430] <TB1> INFO: pulse height fill bit: 0
[11:29:00.430] <TB1> INFO: buffer corruption: 0
[11:29:00.430] <TB1> INFO: enter test to run
[11:29:00.430] <TB1> INFO: test: exit no parameter change
[11:29:00.558] <TB1> QUIET: Connection to board 153 closed.
[11:29:00.637] <TB1> INFO: pXar: this is the end, my friend