[12:24:44.616] <TB1> INFO: *** Welcome to pxar ***
[12:24:44.616] <TB1> INFO: *** Today: 2016/04/19
[12:24:44.703] <TB1> INFO: *** Version: 9751-dirty
[12:24:44.703] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters_C15.dat
[12:24:44.703] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//tbmParameters_C0b.dat
[12:24:44.704] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//defaultMaskFile.dat
[12:24:44.704] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//trimParameters_C15.dat
[12:24:44.773] <TB1> INFO: clk: 4
[12:24:44.773] <TB1> INFO: ctr: 4
[12:24:44.773] <TB1> INFO: sda: 19
[12:24:44.773] <TB1> INFO: tin: 9
[12:24:44.773] <TB1> INFO: level: 15
[12:24:44.773] <TB1> INFO: triggerdelay: 0
[12:24:44.773] <TB1> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[12:24:44.773] <TB1> INFO: Log level: INFO
[12:24:44.778] <TB1> INFO: Found DTB DTB_WXBYFL
[12:24:44.787] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[12:24:44.789] <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:
------------------------------------------------------
[12:24:44.790] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[12:24:46.321] <TB1> INFO: DUT info:
[12:24:46.322] <TB1> INFO: The DUT currently contains the following objects:
[12:24:46.322] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[12:24:46.322] <TB1> INFO: TBM Core alpha (0): 7 registers set
[12:24:46.322] <TB1> INFO: TBM Core beta (1): 7 registers set
[12:24:46.322] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:24:46.322] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:46.322] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:46.322] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:46.322] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:46.322] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:46.322] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:46.322] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:46.322] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:46.322] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:46.322] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:46.322] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:46.322] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:46.322] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:46.322] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:46.322] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:46.322] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:24:46.723] <TB1> INFO: enter 'restricted' command line mode
[12:24:46.723] <TB1> INFO: enter test to run
[12:24:46.723] <TB1> INFO: test: pretest no parameter change
[12:24:46.723] <TB1> INFO: running: pretest
[12:24:46.731] <TB1> INFO: ----------------------------------------------------------------------
[12:24:46.731] <TB1> INFO: PixTestPretest::programROC()
[12:24:46.731] <TB1> INFO: ----------------------------------------------------------------------
[12:25:04.745] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:25:04.745] <TB1> INFO: IA differences per ROC: 18.5 18.5 20.1 18.5 17.7 19.3 17.7 19.3 17.7 17.7 19.3 18.5 21.7 19.3 20.1 20.1
[12:25:04.858] <TB1> INFO: enter test to run
[12:25:04.858] <TB1> INFO: test: pretest no parameter change
[12:25:04.858] <TB1> INFO: running: pretest
[12:25:04.859] <TB1> INFO: ----------------------------------------------------------------------
[12:25:04.859] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:25:04.859] <TB1> INFO: ----------------------------------------------------------------------
[12:25:10.445] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 390.7 mA = 24.4187 mA/ROC
[12:25:10.445] <TB1> INFO: i(loss) [mA/ROC]: 20.1 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 18.5 18.5 19.3
[12:25:10.479] <TB1> INFO: enter test to run
[12:25:10.479] <TB1> INFO: test: pretest no parameter change
[12:25:10.479] <TB1> INFO: running: pretest
[12:25:10.479] <TB1> INFO: ----------------------------------------------------------------------
[12:25:10.479] <TB1> INFO: PixTestPretest::findTiming()
[12:25:10.479] <TB1> INFO: ----------------------------------------------------------------------
[12:25:10.479] <TB1> INFO: PixTestCmd::init()
[12:25:11.310] <TB1> WARNING: Not unmasking DUT, not setting Calibrate bits!
[12:26:40.724] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[12:26:40.724] <TB1> INFO: (success/tries = 100/100), width = 5
[12:26:40.725] <TB1> INFO: enter test to run
[12:26:40.725] <TB1> INFO: test: pretest no parameter change
[12:26:40.725] <TB1> INFO: running: pretest
[12:26:40.726] <TB1> INFO: ----------------------------------------------------------------------
[12:26:40.726] <TB1> INFO: PixTestPretest::findWorkingPixel()
[12:26:40.726] <TB1> INFO: ----------------------------------------------------------------------
[12:26:40.816] <TB1> INFO: Expecting 231680 events.
[12:26:46.587] <TB1> ERROR: <datapipe.cc/CheckEventValidity:L497> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[12:26:46.671] <TB1> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[12:26:50.905] <TB1> INFO: 231680 events read in total (9532ms).
[12:26:50.908] <TB1> INFO: Test took 10179ms.
[12:26:51.128] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[12:26:51.195] <TB1> INFO: enter test to run
[12:26:51.195] <TB1> INFO: test: pretest no parameter change
[12:26:51.195] <TB1> INFO: running: pretest
[12:26:51.196] <TB1> INFO: ----------------------------------------------------------------------
[12:26:51.196] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[12:26:51.196] <TB1> INFO: ----------------------------------------------------------------------
[12:26:51.288] <TB1> INFO: Expecting 231680 events.
[12:27:01.418] <TB1> INFO: 231680 events read in total (9574ms).
[12:27:01.422] <TB1> INFO: Test took 10222ms.
[12:27:01.656] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[12:27:01.656] <TB1> INFO: CalDel: 130 126 120 136 123 123 118 137 136 135 137 127 134 149 142 115
[12:27:01.656] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[12:27:01.689] <TB1> INFO: enter test to run
[12:27:01.689] <TB1> INFO: test: pretest no parameter change
[12:27:01.689] <TB1> INFO: running: pretest
[12:27:01.689] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters_C0.dat
[12:27:01.690] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters_C1.dat
[12:27:01.690] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters_C2.dat
[12:27:01.690] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters_C3.dat
[12:27:01.690] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters_C4.dat
[12:27:01.691] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters_C5.dat
[12:27:01.691] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters_C6.dat
[12:27:01.691] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters_C7.dat
[12:27:01.691] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters_C8.dat
[12:27:01.691] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters_C9.dat
[12:27:01.691] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters_C10.dat
[12:27:01.691] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters_C11.dat
[12:27:01.691] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters_C12.dat
[12:27:01.692] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters_C13.dat
[12:27:01.692] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters_C14.dat
[12:27:01.692] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters_C15.dat
[12:27:01.692] <TB1> INFO: enter test to run
[12:27:01.692] <TB1> INFO: test: fulltest no parameter change
[12:27:01.692] <TB1> INFO: running: fulltest
[12:27:01.692] <TB1> INFO: ######################################################################
[12:27:01.692] <TB1> INFO: PixTestFullTest::doTest()
[12:27:01.692] <TB1> INFO: ######################################################################
[12:27:01.693] <TB1> INFO: ######################################################################
[12:27:01.693] <TB1> INFO: PixTestAlive::doTest()
[12:27:01.693] <TB1> INFO: ######################################################################
[12:27:01.694] <TB1> INFO: ----------------------------------------------------------------------
[12:27:01.694] <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)
[12:27:01.694] <TB1> INFO: ----------------------------------------------------------------------
[12:27:02.001] <TB1> INFO: Expecting 41600 events.
[12:27:05.737] <TB1> INFO: 41600 events read in total (3179ms).
[12:27:05.738] <TB1> INFO: Test took 4042ms.
[12:27:05.969] <TB1> INFO: PixTestAlive::aliveTest() done
[12:27:05.969] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0
[12:27:05.970] <TB1> INFO: ----------------------------------------------------------------------
[12:27:05.970] <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)
[12:27:05.970] <TB1> INFO: ----------------------------------------------------------------------
[12:27:06.245] <TB1> INFO: Expecting 41600 events.
[12:27:09.233] <TB1> INFO: 41600 events read in total (2432ms).
[12:27:09.233] <TB1> INFO: Test took 3261ms.
[12:27:09.234] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:27:09.483] <TB1> INFO: PixTestAlive::maskTest() done
[12:27:09.483] <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
[12:27:09.485] <TB1> INFO: ----------------------------------------------------------------------
[12:27:09.485] <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)
[12:27:09.485] <TB1> INFO: ----------------------------------------------------------------------
[12:27:09.787] <TB1> INFO: Expecting 41600 events.
[12:27:13.574] <TB1> INFO: 41600 events read in total (3231ms).
[12:27:13.575] <TB1> INFO: Test took 4088ms.
[12:27:13.804] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[12:27:13.804] <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
[12:27:13.804] <TB1> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[12:27:13.805] <TB1> INFO: Decoding statistics:
[12:27:13.805] <TB1> INFO: General information:
[12:27:13.805] <TB1> INFO: 16bit words read: 0
[12:27:13.805] <TB1> INFO: valid events total: 0
[12:27:13.805] <TB1> INFO: empty events: 0
[12:27:13.805] <TB1> INFO: valid events with pixels: 0
[12:27:13.805] <TB1> INFO: valid pixel hits: 0
[12:27:13.805] <TB1> INFO: Event errors: 0
[12:27:13.805] <TB1> INFO: start marker: 0
[12:27:13.805] <TB1> INFO: stop marker: 0
[12:27:13.805] <TB1> INFO: overflow: 0
[12:27:13.805] <TB1> INFO: invalid 5bit words: 0
[12:27:13.805] <TB1> INFO: invalid XOR eye diagram: 0
[12:27:13.805] <TB1> INFO: frame (failed synchr.): 0
[12:27:13.805] <TB1> INFO: idle data (no TBM trl): 0
[12:27:13.805] <TB1> INFO: no data (only TBM hdr): 0
[12:27:13.805] <TB1> INFO: TBM errors: 0
[12:27:13.805] <TB1> INFO: flawed TBM headers: 0
[12:27:13.805] <TB1> INFO: flawed TBM trailers: 0
[12:27:13.805] <TB1> INFO: event ID mismatches: 0
[12:27:13.805] <TB1> INFO: ROC errors: 0
[12:27:13.805] <TB1> INFO: missing ROC header(s): 0
[12:27:13.805] <TB1> INFO: misplaced readback start: 0
[12:27:13.805] <TB1> INFO: Pixel decoding errors: 0
[12:27:13.805] <TB1> INFO: pixel data incomplete: 0
[12:27:13.805] <TB1> INFO: pixel address: 0
[12:27:13.805] <TB1> INFO: pulse height fill bit: 0
[12:27:13.805] <TB1> INFO: buffer corruption: 0
[12:27:13.820] <TB1> INFO: ######################################################################
[12:27:13.820] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[12:27:13.820] <TB1> INFO: ######################################################################
[12:27:13.822] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[12:27:13.836] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[12:27:13.836] <TB1> INFO: run 1 of 1
[12:27:14.102] <TB1> INFO: Expecting 3120000 events.
[12:27:55.427] <TB1> INFO: 880280 events read in total (40768ms).
[12:28:36.191] <TB1> INFO: 1749345 events read in total (81532ms).
[12:29:16.596] <TB1> INFO: 2627135 events read in total (121937ms).
[12:29:39.504] <TB1> INFO: 3120000 events read in total (144845ms).
[12:29:39.546] <TB1> INFO: Test took 145710ms.
[12:30:03.437] <TB1> INFO: PixTestBBMap::doTest() done, duration: 169 seconds
[12:30:03.437] <TB1> INFO: number of dead bumps (per ROC): 4 3 3 2 1 1 0 1 3 0 0 0 2 0 2 4
[12:30:03.437] <TB1> INFO: separation cut (per ROC): 98 97 97 91 91 88 94 101 93 87 93 101 103 96 89 108
[12:30:03.437] <TB1> INFO: Decoding statistics:
[12:30:03.437] <TB1> INFO: General information:
[12:30:03.437] <TB1> INFO: 16bit words read: 0
[12:30:03.437] <TB1> INFO: valid events total: 0
[12:30:03.437] <TB1> INFO: empty events: 0
[12:30:03.437] <TB1> INFO: valid events with pixels: 0
[12:30:03.437] <TB1> INFO: valid pixel hits: 0
[12:30:03.437] <TB1> INFO: Event errors: 0
[12:30:03.437] <TB1> INFO: start marker: 0
[12:30:03.437] <TB1> INFO: stop marker: 0
[12:30:03.437] <TB1> INFO: overflow: 0
[12:30:03.437] <TB1> INFO: invalid 5bit words: 0
[12:30:03.437] <TB1> INFO: invalid XOR eye diagram: 0
[12:30:03.437] <TB1> INFO: frame (failed synchr.): 0
[12:30:03.437] <TB1> INFO: idle data (no TBM trl): 0
[12:30:03.437] <TB1> INFO: no data (only TBM hdr): 0
[12:30:03.438] <TB1> INFO: TBM errors: 0
[12:30:03.438] <TB1> INFO: flawed TBM headers: 0
[12:30:03.438] <TB1> INFO: flawed TBM trailers: 0
[12:30:03.438] <TB1> INFO: event ID mismatches: 0
[12:30:03.438] <TB1> INFO: ROC errors: 0
[12:30:03.438] <TB1> INFO: missing ROC header(s): 0
[12:30:03.438] <TB1> INFO: misplaced readback start: 0
[12:30:03.438] <TB1> INFO: Pixel decoding errors: 0
[12:30:03.438] <TB1> INFO: pixel data incomplete: 0
[12:30:03.438] <TB1> INFO: pixel address: 0
[12:30:03.438] <TB1> INFO: pulse height fill bit: 0
[12:30:03.438] <TB1> INFO: buffer corruption: 0
[12:30:03.506] <TB1> INFO: ######################################################################
[12:30:03.506] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:30:03.506] <TB1> INFO: ######################################################################
[12:30:03.506] <TB1> INFO: ----------------------------------------------------------------------
[12:30:03.506] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:30:03.506] <TB1> INFO: ----------------------------------------------------------------------
[12:30:03.506] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:30:03.514] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[12:30:03.514] <TB1> INFO: run 1 of 1
[12:30:03.787] <TB1> INFO: Expecting 26208000 events.
[12:30:37.618] <TB1> INFO: 953700 events read in total (33273ms).
[12:31:10.786] <TB1> INFO: 1889150 events read in total (66441ms).
[12:31:44.707] <TB1> INFO: 2823650 events read in total (100362ms).
[12:32:18.295] <TB1> INFO: 3756400 events read in total (133950ms).
[12:32:51.358] <TB1> INFO: 4689650 events read in total (167013ms).
[12:33:25.155] <TB1> INFO: 5619650 events read in total (200810ms).
[12:33:58.045] <TB1> INFO: 6550850 events read in total (233700ms).
[12:34:31.540] <TB1> INFO: 7478100 events read in total (267195ms).
[12:35:04.645] <TB1> INFO: 8407350 events read in total (300300ms).
[12:35:38.061] <TB1> INFO: 9335600 events read in total (333716ms).
[12:36:11.556] <TB1> INFO: 10262600 events read in total (367211ms).
[12:36:45.121] <TB1> INFO: 11190950 events read in total (400776ms).
[12:37:18.539] <TB1> INFO: 12119100 events read in total (434194ms).
[12:37:51.182] <TB1> INFO: 13041000 events read in total (466837ms).
[12:38:24.349] <TB1> INFO: 13958100 events read in total (500004ms).
[12:38:57.746] <TB1> INFO: 14872150 events read in total (533401ms).
[12:39:30.969] <TB1> INFO: 15785750 events read in total (566624ms).
[12:40:04.375] <TB1> INFO: 16696400 events read in total (600030ms).
[12:40:37.284] <TB1> INFO: 17606950 events read in total (632939ms).
[12:41:10.673] <TB1> INFO: 18517200 events read in total (666328ms).
[12:41:43.513] <TB1> INFO: 19426650 events read in total (699168ms).
[12:42:16.566] <TB1> INFO: 20336000 events read in total (732221ms).
[12:42:49.857] <TB1> INFO: 21245000 events read in total (765512ms).
[12:43:22.856] <TB1> INFO: 22154700 events read in total (798511ms).
[12:43:55.878] <TB1> INFO: 23063500 events read in total (831533ms).
[12:44:29.236] <TB1> INFO: 23976550 events read in total (864891ms).
[12:45:02.297] <TB1> INFO: 24890300 events read in total (897952ms).
[12:45:35.236] <TB1> INFO: 25809350 events read in total (930891ms).
[12:45:49.328] <TB1> INFO: 26208000 events read in total (944983ms).
[12:45:49.355] <TB1> INFO: Test took 945841ms.
[12:45:49.600] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:45:51.087] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:45:52.464] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:45:53.958] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:45:55.363] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:45:56.743] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:45:58.138] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:45:59.502] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:46:00.886] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:46:02.232] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:46:03.594] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:46:05.081] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:46:06.592] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:46:08.186] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:46:09.770] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:46:11.430] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:46:12.861] <TB1> INFO: PixTestScurves::scurves() done
[12:46:12.861] <TB1> INFO: Vcal mean: 105.18 100.69 94.75 84.40 98.94 96.69 108.59 101.72 101.94 88.34 94.29 103.05 98.31 99.69 87.71 101.34
[12:46:12.861] <TB1> INFO: Vcal RMS: 5.14 5.72 5.12 4.99 5.86 4.94 4.73 5.57 5.38 4.88 5.27 5.75 5.28 4.93 4.88 5.70
[12:46:12.861] <TB1> INFO: PixTestScurves::fullTest() done, duration: 969 seconds
[12:46:12.861] <TB1> INFO: Decoding statistics:
[12:46:12.861] <TB1> INFO: General information:
[12:46:12.861] <TB1> INFO: 16bit words read: 0
[12:46:12.861] <TB1> INFO: valid events total: 0
[12:46:12.861] <TB1> INFO: empty events: 0
[12:46:12.861] <TB1> INFO: valid events with pixels: 0
[12:46:12.861] <TB1> INFO: valid pixel hits: 0
[12:46:12.861] <TB1> INFO: Event errors: 0
[12:46:12.861] <TB1> INFO: start marker: 0
[12:46:12.861] <TB1> INFO: stop marker: 0
[12:46:12.861] <TB1> INFO: overflow: 0
[12:46:12.861] <TB1> INFO: invalid 5bit words: 0
[12:46:12.861] <TB1> INFO: invalid XOR eye diagram: 0
[12:46:12.861] <TB1> INFO: frame (failed synchr.): 0
[12:46:12.861] <TB1> INFO: idle data (no TBM trl): 0
[12:46:12.861] <TB1> INFO: no data (only TBM hdr): 0
[12:46:12.861] <TB1> INFO: TBM errors: 0
[12:46:12.861] <TB1> INFO: flawed TBM headers: 0
[12:46:12.861] <TB1> INFO: flawed TBM trailers: 0
[12:46:12.861] <TB1> INFO: event ID mismatches: 0
[12:46:12.861] <TB1> INFO: ROC errors: 0
[12:46:12.861] <TB1> INFO: missing ROC header(s): 0
[12:46:12.861] <TB1> INFO: misplaced readback start: 0
[12:46:12.861] <TB1> INFO: Pixel decoding errors: 0
[12:46:12.861] <TB1> INFO: pixel data incomplete: 0
[12:46:12.861] <TB1> INFO: pixel address: 0
[12:46:12.861] <TB1> INFO: pulse height fill bit: 0
[12:46:12.861] <TB1> INFO: buffer corruption: 0
[12:46:12.933] <TB1> INFO: ######################################################################
[12:46:12.933] <TB1> INFO: PixTestTrim::doTest()
[12:46:12.933] <TB1> INFO: ######################################################################
[12:46:12.934] <TB1> INFO: ----------------------------------------------------------------------
[12:46:12.934] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[12:46:12.934] <TB1> INFO: ----------------------------------------------------------------------
[12:46:13.010] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[12:46:13.010] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:46:13.018] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[12:46:13.018] <TB1> INFO: run 1 of 1
[12:46:13.274] <TB1> INFO: Expecting 6281600 events.
[12:46:52.472] <TB1> INFO: 1091600 events read in total (38641ms).
[12:47:31.336] <TB1> INFO: 2177000 events read in total (77505ms).
[12:48:10.105] <TB1> INFO: 3255230 events read in total (116275ms).
[12:48:50.016] <TB1> INFO: 4339930 events read in total (156185ms).
[12:49:29.003] <TB1> INFO: 5427330 events read in total (195172ms).
[12:49:59.800] <TB1> INFO: 6281600 events read in total (225969ms).
[12:49:59.829] <TB1> INFO: Test took 226811ms.
[12:50:20.353] <TB1> INFO: ROC 0 VthrComp = 104
[12:50:20.353] <TB1> INFO: ROC 1 VthrComp = 100
[12:50:20.354] <TB1> INFO: ROC 2 VthrComp = 98
[12:50:20.354] <TB1> INFO: ROC 3 VthrComp = 88
[12:50:20.354] <TB1> INFO: ROC 4 VthrComp = 100
[12:50:20.354] <TB1> INFO: ROC 5 VthrComp = 95
[12:50:20.354] <TB1> INFO: ROC 6 VthrComp = 104
[12:50:20.354] <TB1> INFO: ROC 7 VthrComp = 103
[12:50:20.354] <TB1> INFO: ROC 8 VthrComp = 101
[12:50:20.354] <TB1> INFO: ROC 9 VthrComp = 92
[12:50:20.354] <TB1> INFO: ROC 10 VthrComp = 97
[12:50:20.354] <TB1> INFO: ROC 11 VthrComp = 102
[12:50:20.355] <TB1> INFO: ROC 12 VthrComp = 102
[12:50:20.355] <TB1> INFO: ROC 13 VthrComp = 101
[12:50:20.355] <TB1> INFO: ROC 14 VthrComp = 90
[12:50:20.355] <TB1> INFO: ROC 15 VthrComp = 103
[12:50:20.355] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[12:50:20.355] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:50:20.363] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[12:50:20.363] <TB1> INFO: run 1 of 1
[12:50:20.636] <TB1> INFO: Expecting 6281600 events.
[12:50:56.023] <TB1> INFO: 755490 events read in total (34831ms).
[12:51:30.563] <TB1> INFO: 1508760 events read in total (69371ms).
[12:52:05.314] <TB1> INFO: 2262400 events read in total (104122ms).
[12:52:40.283] <TB1> INFO: 3016880 events read in total (139091ms).
[12:53:14.716] <TB1> INFO: 3766430 events read in total (173524ms).
[12:53:48.707] <TB1> INFO: 4511850 events read in total (207515ms).
[12:54:23.594] <TB1> INFO: 5255520 events read in total (242402ms).
[12:54:58.170] <TB1> INFO: 5999660 events read in total (276978ms).
[12:55:11.479] <TB1> INFO: 6281600 events read in total (290287ms).
[12:55:11.520] <TB1> INFO: Test took 291157ms.
[12:55:37.280] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 60.6459 for pixel 20/0 mean/min/max = 47.5182/34.3554/60.6811
[12:55:37.280] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 58.6888 for pixel 23/5 mean/min/max = 45.2767/31.8417/58.7116
[12:55:37.281] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 56.7196 for pixel 3/69 mean/min/max = 44.4806/31.9971/56.964
[12:55:37.281] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 58.2546 for pixel 0/60 mean/min/max = 46.1015/33.9335/58.2695
[12:55:37.281] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 58.4063 for pixel 12/70 mean/min/max = 45.0589/31.7051/58.4127
[12:55:37.281] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 57.3955 for pixel 39/2 mean/min/max = 44.9827/32.503/57.4624
[12:55:37.282] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 60.4217 for pixel 0/9 mean/min/max = 47.2785/34.0817/60.4753
[12:55:37.282] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 58.305 for pixel 18/60 mean/min/max = 45.3135/32.3114/58.3156
[12:55:37.282] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 57.4571 for pixel 45/79 mean/min/max = 44.7555/32.0233/57.4878
[12:55:37.283] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 56.1303 for pixel 20/77 mean/min/max = 45.135/33.6498/56.6203
[12:55:37.283] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 56.9486 for pixel 21/0 mean/min/max = 44.5735/31.9955/57.1516
[12:55:37.283] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 60.2907 for pixel 9/3 mean/min/max = 46.5102/32.6976/60.3227
[12:55:37.283] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 57.1371 for pixel 17/74 mean/min/max = 44.6954/32.1499/57.2409
[12:55:37.284] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 56.0452 for pixel 7/1 mean/min/max = 44.3287/32.4168/56.2406
[12:55:37.284] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 56.5975 for pixel 17/16 mean/min/max = 45.1151/33.5034/56.7269
[12:55:37.284] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 58.6629 for pixel 8/9 mean/min/max = 45.8786/33.0306/58.7266
[12:55:37.285] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:55:37.372] <TB1> INFO: Expecting 514560 events.
[12:55:50.302] <TB1> INFO: 514560 events read in total (12373ms).
[12:55:50.307] <TB1> INFO: Expecting 514560 events.
[12:56:02.967] <TB1> INFO: 514560 events read in total (12237ms).
[12:56:02.975] <TB1> INFO: Expecting 514560 events.
[12:56:15.569] <TB1> INFO: 514560 events read in total (12176ms).
[12:56:15.579] <TB1> INFO: Expecting 514560 events.
[12:56:28.428] <TB1> INFO: 514560 events read in total (12434ms).
[12:56:28.438] <TB1> INFO: Expecting 514560 events.
[12:56:41.279] <TB1> INFO: 514560 events read in total (12429ms).
[12:56:41.292] <TB1> INFO: Expecting 514560 events.
[12:56:54.182] <TB1> INFO: 514560 events read in total (12478ms).
[12:56:54.199] <TB1> INFO: Expecting 514560 events.
[12:57:07.061] <TB1> INFO: 514560 events read in total (12460ms).
[12:57:07.079] <TB1> INFO: Expecting 514560 events.
[12:57:20.029] <TB1> INFO: 514560 events read in total (12543ms).
[12:57:20.051] <TB1> INFO: Expecting 514560 events.
[12:57:32.818] <TB1> INFO: 514560 events read in total (12366ms).
[12:57:32.839] <TB1> INFO: Expecting 514560 events.
[12:57:45.667] <TB1> INFO: 514560 events read in total (12421ms).
[12:57:45.689] <TB1> INFO: Expecting 514560 events.
[12:57:58.513] <TB1> INFO: 514560 events read in total (12419ms).
[12:57:58.537] <TB1> INFO: Expecting 514560 events.
[12:58:11.313] <TB1> INFO: 514560 events read in total (12375ms).
[12:58:11.339] <TB1> INFO: Expecting 514560 events.
[12:58:24.188] <TB1> INFO: 514560 events read in total (12447ms).
[12:58:24.217] <TB1> INFO: Expecting 514560 events.
[12:58:36.957] <TB1> INFO: 514560 events read in total (12337ms).
[12:58:36.986] <TB1> INFO: Expecting 514560 events.
[12:58:49.760] <TB1> INFO: 514560 events read in total (12373ms).
[12:58:49.790] <TB1> INFO: Expecting 514560 events.
[12:59:02.818] <TB1> INFO: 514560 events read in total (12626ms).
[12:59:02.851] <TB1> INFO: Test took 205566ms.
[12:59:03.858] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:59:03.866] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[12:59:03.866] <TB1> INFO: run 1 of 1
[12:59:04.139] <TB1> INFO: Expecting 6281600 events.
[12:59:38.845] <TB1> INFO: 742440 events read in total (34150ms).
[13:00:12.995] <TB1> INFO: 1483150 events read in total (68300ms).
[13:00:47.775] <TB1> INFO: 2224050 events read in total (103080ms).
[13:01:21.748] <TB1> INFO: 2965830 events read in total (137053ms).
[13:01:56.239] <TB1> INFO: 3702340 events read in total (171544ms).
[13:02:31.162] <TB1> INFO: 4434550 events read in total (206467ms).
[13:03:05.364] <TB1> INFO: 5164620 events read in total (240669ms).
[13:03:39.556] <TB1> INFO: 5894200 events read in total (274861ms).
[13:03:57.540] <TB1> INFO: 6281600 events read in total (292845ms).
[13:03:57.590] <TB1> INFO: Test took 293724ms.
[13:04:21.912] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.264978 .. 255.000000
[13:04:22.005] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[13:04:22.015] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:04:22.015] <TB1> INFO: run 1 of 1
[13:04:22.282] <TB1> INFO: Expecting 10649600 events.
[13:04:56.498] <TB1> INFO: 712340 events read in total (33658ms).
[13:05:30.177] <TB1> INFO: 1425120 events read in total (67337ms).
[13:06:03.803] <TB1> INFO: 2137680 events read in total (100963ms).
[13:06:38.073] <TB1> INFO: 2850640 events read in total (135233ms).
[13:07:12.356] <TB1> INFO: 3563400 events read in total (169516ms).
[13:07:46.588] <TB1> INFO: 4276170 events read in total (203748ms).
[13:08:20.861] <TB1> INFO: 4989070 events read in total (238021ms).
[13:08:54.788] <TB1> INFO: 5702130 events read in total (271948ms).
[13:09:28.299] <TB1> INFO: 6414460 events read in total (305459ms).
[13:10:02.594] <TB1> INFO: 7126040 events read in total (339754ms).
[13:10:36.718] <TB1> INFO: 7837590 events read in total (373878ms).
[13:11:10.297] <TB1> INFO: 8548370 events read in total (407457ms).
[13:11:43.671] <TB1> INFO: 9259190 events read in total (440831ms).
[13:12:17.699] <TB1> INFO: 9970250 events read in total (474859ms).
[13:12:50.617] <TB1> INFO: 10649600 events read in total (507777ms).
[13:12:50.736] <TB1> INFO: Test took 508721ms.
[13:13:19.830] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 15.443267 .. 46.776399
[13:13:19.903] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 5 .. 56 (-1/-1) hits flags = 528 (plus default)
[13:13:19.911] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:13:19.911] <TB1> INFO: run 1 of 1
[13:13:20.167] <TB1> INFO: Expecting 2163200 events.
[13:13:57.808] <TB1> INFO: 931110 events read in total (37085ms).
[13:14:35.319] <TB1> INFO: 1860440 events read in total (74597ms).
[13:14:47.615] <TB1> INFO: 2163200 events read in total (86893ms).
[13:14:47.634] <TB1> INFO: Test took 87724ms.
[13:15:01.240] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 18.409473 .. 44.311323
[13:15:01.315] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 8 .. 54 (-1/-1) hits flags = 528 (plus default)
[13:15:01.323] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:15:01.323] <TB1> INFO: run 1 of 1
[13:15:01.587] <TB1> INFO: Expecting 1955200 events.
[13:15:39.700] <TB1> INFO: 932200 events read in total (37557ms).
[13:16:17.246] <TB1> INFO: 1862300 events read in total (75103ms).
[13:16:21.440] <TB1> INFO: 1955200 events read in total (79298ms).
[13:16:21.455] <TB1> INFO: Test took 80132ms.
[13:16:35.166] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 1.918037 .. 43.955093
[13:16:35.243] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 1 .. 53 (-1/-1) hits flags = 528 (plus default)
[13:16:35.251] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:16:35.251] <TB1> INFO: run 1 of 1
[13:16:35.511] <TB1> INFO: Expecting 2204800 events.
[13:17:13.797] <TB1> INFO: 977270 events read in total (37730ms).
[13:17:51.529] <TB1> INFO: 1954270 events read in total (75462ms).
[13:18:01.615] <TB1> INFO: 2204800 events read in total (85548ms).
[13:18:01.626] <TB1> INFO: Test took 86375ms.
[13:18:15.233] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[13:18:15.233] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:18:15.241] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:18:15.241] <TB1> INFO: run 1 of 1
[13:18:15.502] <TB1> INFO: Expecting 1705600 events.
[13:18:52.241] <TB1> INFO: 878240 events read in total (36183ms).
[13:19:26.387] <TB1> INFO: 1705600 events read in total (70330ms).
[13:19:26.404] <TB1> INFO: Test took 71163ms.
[13:19:42.255] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C0.dat
[13:19:42.255] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C1.dat
[13:19:42.255] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C2.dat
[13:19:42.255] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C3.dat
[13:19:42.255] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C4.dat
[13:19:42.255] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C5.dat
[13:19:42.256] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C6.dat
[13:19:42.256] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C7.dat
[13:19:42.256] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C8.dat
[13:19:42.256] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C9.dat
[13:19:42.256] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C10.dat
[13:19:42.256] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C11.dat
[13:19:42.256] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C12.dat
[13:19:42.257] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C13.dat
[13:19:42.257] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C14.dat
[13:19:42.257] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C15.dat
[13:19:42.257] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//trimParameters35_C0.dat
[13:19:42.267] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//trimParameters35_C1.dat
[13:19:42.277] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//trimParameters35_C2.dat
[13:19:42.287] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//trimParameters35_C3.dat
[13:19:42.297] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//trimParameters35_C4.dat
[13:19:42.307] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//trimParameters35_C5.dat
[13:19:42.316] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//trimParameters35_C6.dat
[13:19:42.323] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//trimParameters35_C7.dat
[13:19:42.331] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//trimParameters35_C8.dat
[13:19:42.339] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//trimParameters35_C9.dat
[13:19:42.345] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//trimParameters35_C10.dat
[13:19:42.354] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//trimParameters35_C11.dat
[13:19:42.360] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//trimParameters35_C12.dat
[13:19:42.366] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//trimParameters35_C13.dat
[13:19:42.372] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//trimParameters35_C14.dat
[13:19:42.378] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//trimParameters35_C15.dat
[13:19:42.384] <TB1> INFO: PixTestTrim::trimTest() done
[13:19:42.384] <TB1> INFO: vtrim: 111 106 93 90 95 94 103 102 83 103 97 102 91 94 92 106
[13:19:42.384] <TB1> INFO: vthrcomp: 104 100 98 88 100 95 104 103 101 92 97 102 102 101 90 103
[13:19:42.384] <TB1> INFO: vcal mean: 34.98 34.78 34.97 35.01 34.95 34.93 34.93 34.97 35.03 35.00 34.92 34.98 35.02 34.95 35.00 34.94
[13:19:42.384] <TB1> INFO: vcal RMS: 1.02 1.05 0.84 0.79 1.05 0.86 0.86 0.86 0.89 0.83 0.89 0.90 0.85 0.86 0.83 0.85
[13:19:42.384] <TB1> INFO: bits mean: 8.85 9.96 9.70 8.63 9.74 9.74 8.79 9.51 9.34 9.62 9.95 9.40 9.63 9.95 9.62 9.51
[13:19:42.384] <TB1> INFO: bits RMS: 2.51 2.60 2.69 2.79 2.71 2.64 2.63 2.71 2.87 2.49 2.61 2.64 2.74 2.54 2.52 2.54
[13:19:42.390] <TB1> INFO: ----------------------------------------------------------------------
[13:19:42.390] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[13:19:42.390] <TB1> INFO: ----------------------------------------------------------------------
[13:19:42.392] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[13:19:42.401] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:19:42.401] <TB1> INFO: run 1 of 1
[13:19:42.667] <TB1> INFO: Expecting 4160000 events.
[13:20:25.527] <TB1> INFO: 968955 events read in total (42304ms).
[13:21:07.539] <TB1> INFO: 1930290 events read in total (84316ms).
[13:21:49.727] <TB1> INFO: 2881760 events read in total (126504ms).
[13:22:30.998] <TB1> INFO: 3830815 events read in total (167775ms).
[13:22:45.670] <TB1> INFO: 4160000 events read in total (182448ms).
[13:22:45.706] <TB1> INFO: Test took 183305ms.
[13:23:13.609] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 184 (-1/-1) hits flags = 528 (plus default)
[13:23:13.617] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:23:13.617] <TB1> INFO: run 1 of 1
[13:23:13.875] <TB1> INFO: Expecting 3848000 events.
[13:23:55.720] <TB1> INFO: 964430 events read in total (41288ms).
[13:24:37.663] <TB1> INFO: 1921015 events read in total (83232ms).
[13:25:18.995] <TB1> INFO: 2867310 events read in total (124563ms).
[13:26:00.631] <TB1> INFO: 3815595 events read in total (166199ms).
[13:26:02.432] <TB1> INFO: 3848000 events read in total (168000ms).
[13:26:02.465] <TB1> INFO: Test took 168848ms.
[13:26:29.469] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 170 (-1/-1) hits flags = 528 (plus default)
[13:26:29.477] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:26:29.477] <TB1> INFO: run 1 of 1
[13:26:29.749] <TB1> INFO: Expecting 3556800 events.
[13:27:12.817] <TB1> INFO: 1000290 events read in total (42511ms).
[13:27:55.715] <TB1> INFO: 1990155 events read in total (85409ms).
[13:28:38.134] <TB1> INFO: 2969360 events read in total (127829ms).
[13:29:04.120] <TB1> INFO: 3556800 events read in total (153814ms).
[13:29:04.155] <TB1> INFO: Test took 154678ms.
[13:29:29.452] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 169 (-1/-1) hits flags = 528 (plus default)
[13:29:29.459] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:29:29.459] <TB1> INFO: run 1 of 1
[13:29:29.742] <TB1> INFO: Expecting 3536000 events.
[13:30:12.637] <TB1> INFO: 1002425 events read in total (42339ms).
[13:30:54.937] <TB1> INFO: 1994075 events read in total (84639ms).
[13:31:37.413] <TB1> INFO: 2975295 events read in total (127115ms).
[13:32:01.788] <TB1> INFO: 3536000 events read in total (151490ms).
[13:32:01.835] <TB1> INFO: Test took 152375ms.
[13:32:26.701] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 169 (-1/-1) hits flags = 528 (plus default)
[13:32:26.709] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:32:26.709] <TB1> INFO: run 1 of 1
[13:32:26.995] <TB1> INFO: Expecting 3536000 events.
[13:33:10.371] <TB1> INFO: 1001770 events read in total (42820ms).
[13:33:53.111] <TB1> INFO: 1992720 events read in total (85560ms).
[13:34:35.646] <TB1> INFO: 2973270 events read in total (128095ms).
[13:34:59.843] <TB1> INFO: 3536000 events read in total (152292ms).
[13:34:59.877] <TB1> INFO: Test took 153168ms.
[13:35:25.610] <TB1> INFO: PixTestTrim::trimBitTest() done
[13:35:25.611] <TB1> INFO: PixTestTrim::doTest() done, duration: 2952 seconds
[13:35:25.611] <TB1> INFO: Decoding statistics:
[13:35:25.611] <TB1> INFO: General information:
[13:35:25.611] <TB1> INFO: 16bit words read: 0
[13:35:25.611] <TB1> INFO: valid events total: 0
[13:35:25.611] <TB1> INFO: empty events: 0
[13:35:25.611] <TB1> INFO: valid events with pixels: 0
[13:35:25.611] <TB1> INFO: valid pixel hits: 0
[13:35:25.611] <TB1> INFO: Event errors: 0
[13:35:25.611] <TB1> INFO: start marker: 0
[13:35:25.611] <TB1> INFO: stop marker: 0
[13:35:25.611] <TB1> INFO: overflow: 0
[13:35:25.611] <TB1> INFO: invalid 5bit words: 0
[13:35:25.611] <TB1> INFO: invalid XOR eye diagram: 0
[13:35:25.611] <TB1> INFO: frame (failed synchr.): 0
[13:35:25.611] <TB1> INFO: idle data (no TBM trl): 0
[13:35:25.611] <TB1> INFO: no data (only TBM hdr): 0
[13:35:25.611] <TB1> INFO: TBM errors: 0
[13:35:25.611] <TB1> INFO: flawed TBM headers: 0
[13:35:25.611] <TB1> INFO: flawed TBM trailers: 0
[13:35:25.611] <TB1> INFO: event ID mismatches: 0
[13:35:25.611] <TB1> INFO: ROC errors: 0
[13:35:25.611] <TB1> INFO: missing ROC header(s): 0
[13:35:25.611] <TB1> INFO: misplaced readback start: 0
[13:35:25.611] <TB1> INFO: Pixel decoding errors: 0
[13:35:25.611] <TB1> INFO: pixel data incomplete: 0
[13:35:25.612] <TB1> INFO: pixel address: 0
[13:35:25.612] <TB1> INFO: pulse height fill bit: 0
[13:35:25.612] <TB1> INFO: buffer corruption: 0
[13:35:26.369] <TB1> INFO: ######################################################################
[13:35:26.369] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[13:35:26.369] <TB1> INFO: ######################################################################
[13:35:26.629] <TB1> INFO: Expecting 41600 events.
[13:35:30.431] <TB1> INFO: 41600 events read in total (3246ms).
[13:35:30.432] <TB1> INFO: Test took 4062ms.
[13:35:30.894] <TB1> INFO: Expecting 41600 events.
[13:35:34.786] <TB1> INFO: 41600 events read in total (3335ms).
[13:35:34.786] <TB1> INFO: Test took 4156ms.
[13:35:35.113] <TB1> INFO: Expecting 41600 events.
[13:35:39.004] <TB1> INFO: 41600 events read in total (3335ms).
[13:35:39.005] <TB1> INFO: Test took 4196ms.
[13:35:39.259] <TB1> INFO: Expecting 2560 events.
[13:35:40.113] <TB1> INFO: 2560 events read in total (298ms).
[13:35:40.113] <TB1> INFO: Test took 1096ms.
[13:35:40.418] <TB1> INFO: Expecting 2560 events.
[13:35:41.270] <TB1> INFO: 2560 events read in total (296ms).
[13:35:41.271] <TB1> INFO: Test took 1158ms.
[13:35:41.575] <TB1> INFO: Expecting 2560 events.
[13:35:42.429] <TB1> INFO: 2560 events read in total (297ms).
[13:35:42.429] <TB1> INFO: Test took 1158ms.
[13:35:42.734] <TB1> INFO: Expecting 2560 events.
[13:35:43.586] <TB1> INFO: 2560 events read in total (296ms).
[13:35:43.586] <TB1> INFO: Test took 1157ms.
[13:35:43.891] <TB1> INFO: Expecting 2560 events.
[13:35:44.746] <TB1> INFO: 2560 events read in total (298ms).
[13:35:44.746] <TB1> INFO: Test took 1159ms.
[13:35:45.051] <TB1> INFO: Expecting 2560 events.
[13:35:45.904] <TB1> INFO: 2560 events read in total (297ms).
[13:35:45.905] <TB1> INFO: Test took 1159ms.
[13:35:46.210] <TB1> INFO: Expecting 2560 events.
[13:35:47.062] <TB1> INFO: 2560 events read in total (296ms).
[13:35:47.063] <TB1> INFO: Test took 1158ms.
[13:35:47.368] <TB1> INFO: Expecting 2560 events.
[13:35:48.221] <TB1> INFO: 2560 events read in total (297ms).
[13:35:48.221] <TB1> INFO: Test took 1158ms.
[13:35:48.526] <TB1> INFO: Expecting 2560 events.
[13:35:49.378] <TB1> INFO: 2560 events read in total (296ms).
[13:35:49.378] <TB1> INFO: Test took 1157ms.
[13:35:49.683] <TB1> INFO: Expecting 2560 events.
[13:35:50.535] <TB1> INFO: 2560 events read in total (296ms).
[13:35:50.536] <TB1> INFO: Test took 1157ms.
[13:35:50.841] <TB1> INFO: Expecting 2560 events.
[13:35:51.694] <TB1> INFO: 2560 events read in total (297ms).
[13:35:51.694] <TB1> INFO: Test took 1158ms.
[13:35:51.999] <TB1> INFO: Expecting 2560 events.
[13:35:52.853] <TB1> INFO: 2560 events read in total (297ms).
[13:35:52.854] <TB1> INFO: Test took 1159ms.
[13:35:53.159] <TB1> INFO: Expecting 2560 events.
[13:35:54.012] <TB1> INFO: 2560 events read in total (297ms).
[13:35:54.012] <TB1> INFO: Test took 1158ms.
[13:35:54.317] <TB1> INFO: Expecting 2560 events.
[13:35:55.170] <TB1> INFO: 2560 events read in total (297ms).
[13:35:55.170] <TB1> INFO: Test took 1157ms.
[13:35:55.475] <TB1> INFO: Expecting 2560 events.
[13:35:56.328] <TB1> INFO: 2560 events read in total (297ms).
[13:35:56.328] <TB1> INFO: Test took 1157ms.
[13:35:56.633] <TB1> INFO: Expecting 2560 events.
[13:35:57.485] <TB1> INFO: 2560 events read in total (296ms).
[13:35:57.485] <TB1> INFO: Test took 1155ms.
[13:35:57.488] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:35:57.791] <TB1> INFO: Expecting 655360 events.
[13:36:14.443] <TB1> INFO: 655360 events read in total (16096ms).
[13:36:14.456] <TB1> INFO: Expecting 655360 events.
[13:36:30.989] <TB1> INFO: 655360 events read in total (16132ms).
[13:36:31.001] <TB1> INFO: Expecting 655360 events.
[13:36:47.893] <TB1> INFO: 655360 events read in total (16490ms).
[13:36:47.908] <TB1> INFO: Expecting 655360 events.
[13:37:04.508] <TB1> INFO: 655360 events read in total (16199ms).
[13:37:04.526] <TB1> INFO: Expecting 655360 events.
[13:37:21.129] <TB1> INFO: 655360 events read in total (16201ms).
[13:37:21.151] <TB1> INFO: Expecting 655360 events.
[13:37:37.791] <TB1> INFO: 655360 events read in total (16239ms).
[13:37:37.822] <TB1> INFO: Expecting 655360 events.
[13:37:54.441] <TB1> INFO: 655360 events read in total (16218ms).
[13:37:54.470] <TB1> INFO: Expecting 655360 events.
[13:38:10.920] <TB1> INFO: 655360 events read in total (16048ms).
[13:38:10.951] <TB1> INFO: Expecting 655360 events.
[13:38:27.818] <TB1> INFO: 655360 events read in total (16465ms).
[13:38:27.855] <TB1> INFO: Expecting 655360 events.
[13:38:44.617] <TB1> INFO: 655360 events read in total (16361ms).
[13:38:44.655] <TB1> INFO: Expecting 655360 events.
[13:39:01.326] <TB1> INFO: 655360 events read in total (16269ms).
[13:39:01.377] <TB1> INFO: Expecting 655360 events.
[13:39:18.045] <TB1> INFO: 655360 events read in total (16266ms).
[13:39:18.092] <TB1> INFO: Expecting 655360 events.
[13:39:34.758] <TB1> INFO: 655360 events read in total (16264ms).
[13:39:34.817] <TB1> INFO: Expecting 655360 events.
[13:39:51.385] <TB1> INFO: 655360 events read in total (16167ms).
[13:39:51.441] <TB1> INFO: Expecting 655360 events.
[13:40:08.107] <TB1> INFO: 655360 events read in total (16264ms).
[13:40:08.161] <TB1> INFO: Expecting 655360 events.
[13:40:24.727] <TB1> INFO: 655360 events read in total (16165ms).
[13:40:24.788] <TB1> INFO: Test took 267300ms.
[13:40:24.866] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:40:25.096] <TB1> INFO: Expecting 655360 events.
[13:40:41.901] <TB1> INFO: 655360 events read in total (16249ms).
[13:40:41.909] <TB1> INFO: Expecting 655360 events.
[13:40:58.594] <TB1> INFO: 655360 events read in total (16283ms).
[13:40:58.605] <TB1> INFO: Expecting 655360 events.
[13:41:15.334] <TB1> INFO: 655360 events read in total (16327ms).
[13:41:15.349] <TB1> INFO: Expecting 655360 events.
[13:41:32.024] <TB1> INFO: 655360 events read in total (16273ms).
[13:41:32.044] <TB1> INFO: Expecting 655360 events.
[13:41:48.686] <TB1> INFO: 655360 events read in total (16240ms).
[13:41:48.708] <TB1> INFO: Expecting 655360 events.
[13:42:05.309] <TB1> INFO: 655360 events read in total (16199ms).
[13:42:05.335] <TB1> INFO: Expecting 655360 events.
[13:42:21.858] <TB1> INFO: 655360 events read in total (16121ms).
[13:42:21.891] <TB1> INFO: Expecting 655360 events.
[13:42:38.650] <TB1> INFO: 655360 events read in total (16357ms).
[13:42:38.684] <TB1> INFO: Expecting 655360 events.
[13:42:55.316] <TB1> INFO: 655360 events read in total (16231ms).
[13:42:55.352] <TB1> INFO: Expecting 655360 events.
[13:43:11.928] <TB1> INFO: 655360 events read in total (16175ms).
[13:43:11.967] <TB1> INFO: Expecting 655360 events.
[13:43:28.746] <TB1> INFO: 655360 events read in total (16377ms).
[13:43:28.792] <TB1> INFO: Expecting 655360 events.
[13:43:45.303] <TB1> INFO: 655360 events read in total (16109ms).
[13:43:45.350] <TB1> INFO: Expecting 655360 events.
[13:44:02.089] <TB1> INFO: 655360 events read in total (16338ms).
[13:44:02.142] <TB1> INFO: Expecting 655360 events.
[13:44:18.683] <TB1> INFO: 655360 events read in total (16139ms).
[13:44:18.735] <TB1> INFO: Expecting 655360 events.
[13:44:35.467] <TB1> INFO: 655360 events read in total (16331ms).
[13:44:35.529] <TB1> INFO: Expecting 655360 events.
[13:44:52.138] <TB1> INFO: 655360 events read in total (16208ms).
[13:44:52.200] <TB1> INFO: Test took 267334ms.
[13:44:52.389] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:52.396] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:52.403] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:52.409] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:52.416] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[13:44:52.423] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[13:44:52.429] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[13:44:52.436] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:52.443] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:52.450] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:52.456] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:52.463] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:52.470] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:52.476] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:52.484] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:52.492] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:52.500] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[13:44:52.507] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[13:44:52.513] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[13:44:52.520] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[13:44:52.527] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[13:44:52.533] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[13:44:52.540] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[13:44:52.547] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:52.553] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:52.560] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[13:44:52.567] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[13:44:52.573] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[13:44:52.580] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[13:44:52.586] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[13:44:52.593] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:44:52.629] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C0.dat
[13:44:52.629] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C1.dat
[13:44:52.629] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C2.dat
[13:44:52.630] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C3.dat
[13:44:52.630] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C4.dat
[13:44:52.630] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C5.dat
[13:44:52.630] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C6.dat
[13:44:52.630] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C7.dat
[13:44:52.630] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C8.dat
[13:44:52.630] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C9.dat
[13:44:52.630] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C10.dat
[13:44:52.630] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C11.dat
[13:44:52.630] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C12.dat
[13:44:52.630] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C13.dat
[13:44:52.631] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C14.dat
[13:44:52.631] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//dacParameters35_C15.dat
[13:44:52.894] <TB1> INFO: Expecting 41600 events.
[13:44:56.358] <TB1> INFO: 41600 events read in total (2908ms).
[13:44:56.359] <TB1> INFO: Test took 3726ms.
[13:44:56.826] <TB1> INFO: Expecting 41600 events.
[13:45:00.304] <TB1> INFO: 41600 events read in total (2921ms).
[13:45:00.305] <TB1> INFO: Test took 3765ms.
[13:45:00.742] <TB1> INFO: Expecting 41600 events.
[13:45:04.183] <TB1> INFO: 41600 events read in total (2885ms).
[13:45:04.184] <TB1> INFO: Test took 3701ms.
[13:45:04.367] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:04.455] <TB1> INFO: Expecting 2560 events.
[13:45:05.307] <TB1> INFO: 2560 events read in total (296ms).
[13:45:05.307] <TB1> INFO: Test took 940ms.
[13:45:05.309] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:05.613] <TB1> INFO: Expecting 2560 events.
[13:45:06.466] <TB1> INFO: 2560 events read in total (297ms).
[13:45:06.466] <TB1> INFO: Test took 1157ms.
[13:45:06.468] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:06.772] <TB1> INFO: Expecting 2560 events.
[13:45:07.624] <TB1> INFO: 2560 events read in total (296ms).
[13:45:07.625] <TB1> INFO: Test took 1157ms.
[13:45:07.626] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:07.930] <TB1> INFO: Expecting 2560 events.
[13:45:08.783] <TB1> INFO: 2560 events read in total (297ms).
[13:45:08.783] <TB1> INFO: Test took 1157ms.
[13:45:08.785] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:09.089] <TB1> INFO: Expecting 2560 events.
[13:45:09.941] <TB1> INFO: 2560 events read in total (296ms).
[13:45:09.941] <TB1> INFO: Test took 1156ms.
[13:45:09.943] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:10.247] <TB1> INFO: Expecting 2560 events.
[13:45:11.107] <TB1> INFO: 2560 events read in total (304ms).
[13:45:11.107] <TB1> INFO: Test took 1164ms.
[13:45:11.109] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:11.411] <TB1> INFO: Expecting 2560 events.
[13:45:12.270] <TB1> INFO: 2560 events read in total (302ms).
[13:45:12.270] <TB1> INFO: Test took 1161ms.
[13:45:12.272] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:12.576] <TB1> INFO: Expecting 2560 events.
[13:45:13.433] <TB1> INFO: 2560 events read in total (301ms).
[13:45:13.434] <TB1> INFO: Test took 1162ms.
[13:45:13.436] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:13.740] <TB1> INFO: Expecting 2560 events.
[13:45:14.597] <TB1> INFO: 2560 events read in total (301ms).
[13:45:14.598] <TB1> INFO: Test took 1162ms.
[13:45:14.600] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:14.904] <TB1> INFO: Expecting 2560 events.
[13:45:15.759] <TB1> INFO: 2560 events read in total (299ms).
[13:45:15.759] <TB1> INFO: Test took 1159ms.
[13:45:15.762] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:16.064] <TB1> INFO: Expecting 2560 events.
[13:45:16.918] <TB1> INFO: 2560 events read in total (298ms).
[13:45:16.918] <TB1> INFO: Test took 1157ms.
[13:45:16.920] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:17.225] <TB1> INFO: Expecting 2560 events.
[13:45:18.078] <TB1> INFO: 2560 events read in total (297ms).
[13:45:18.078] <TB1> INFO: Test took 1158ms.
[13:45:18.080] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:18.384] <TB1> INFO: Expecting 2560 events.
[13:45:19.237] <TB1> INFO: 2560 events read in total (297ms).
[13:45:19.237] <TB1> INFO: Test took 1157ms.
[13:45:19.238] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:19.542] <TB1> INFO: Expecting 2560 events.
[13:45:20.395] <TB1> INFO: 2560 events read in total (297ms).
[13:45:20.395] <TB1> INFO: Test took 1157ms.
[13:45:20.397] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:20.700] <TB1> INFO: Expecting 2560 events.
[13:45:21.554] <TB1> INFO: 2560 events read in total (297ms).
[13:45:21.554] <TB1> INFO: Test took 1157ms.
[13:45:21.557] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:21.859] <TB1> INFO: Expecting 2560 events.
[13:45:22.716] <TB1> INFO: 2560 events read in total (301ms).
[13:45:22.717] <TB1> INFO: Test took 1160ms.
[13:45:22.718] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:23.022] <TB1> INFO: Expecting 2560 events.
[13:45:23.875] <TB1> INFO: 2560 events read in total (297ms).
[13:45:23.875] <TB1> INFO: Test took 1157ms.
[13:45:23.877] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:24.182] <TB1> INFO: Expecting 2560 events.
[13:45:25.035] <TB1> INFO: 2560 events read in total (297ms).
[13:45:25.035] <TB1> INFO: Test took 1158ms.
[13:45:25.037] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:25.341] <TB1> INFO: Expecting 2560 events.
[13:45:26.194] <TB1> INFO: 2560 events read in total (297ms).
[13:45:26.195] <TB1> INFO: Test took 1158ms.
[13:45:26.197] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:26.501] <TB1> INFO: Expecting 2560 events.
[13:45:27.356] <TB1> INFO: 2560 events read in total (299ms).
[13:45:27.356] <TB1> INFO: Test took 1160ms.
[13:45:27.357] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:27.662] <TB1> INFO: Expecting 2560 events.
[13:45:28.515] <TB1> INFO: 2560 events read in total (297ms).
[13:45:28.515] <TB1> INFO: Test took 1158ms.
[13:45:28.517] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:28.821] <TB1> INFO: Expecting 2560 events.
[13:45:29.677] <TB1> INFO: 2560 events read in total (299ms).
[13:45:29.677] <TB1> INFO: Test took 1160ms.
[13:45:29.679] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:29.984] <TB1> INFO: Expecting 2560 events.
[13:45:30.838] <TB1> INFO: 2560 events read in total (298ms).
[13:45:30.838] <TB1> INFO: Test took 1159ms.
[13:45:30.840] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:31.145] <TB1> INFO: Expecting 2560 events.
[13:45:31.999] <TB1> INFO: 2560 events read in total (298ms).
[13:45:31.999] <TB1> INFO: Test took 1159ms.
[13:45:32.001] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:32.305] <TB1> INFO: Expecting 2560 events.
[13:45:33.160] <TB1> INFO: 2560 events read in total (298ms).
[13:45:33.160] <TB1> INFO: Test took 1159ms.
[13:45:33.162] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:33.465] <TB1> INFO: Expecting 2560 events.
[13:45:34.323] <TB1> INFO: 2560 events read in total (301ms).
[13:45:34.324] <TB1> INFO: Test took 1162ms.
[13:45:34.326] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:34.629] <TB1> INFO: Expecting 2560 events.
[13:45:35.482] <TB1> INFO: 2560 events read in total (297ms).
[13:45:35.482] <TB1> INFO: Test took 1156ms.
[13:45:35.484] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:35.788] <TB1> INFO: Expecting 2560 events.
[13:45:36.641] <TB1> INFO: 2560 events read in total (297ms).
[13:45:36.641] <TB1> INFO: Test took 1158ms.
[13:45:36.643] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:36.946] <TB1> INFO: Expecting 2560 events.
[13:45:37.800] <TB1> INFO: 2560 events read in total (298ms).
[13:45:37.800] <TB1> INFO: Test took 1158ms.
[13:45:37.803] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:38.105] <TB1> INFO: Expecting 2560 events.
[13:45:38.959] <TB1> INFO: 2560 events read in total (297ms).
[13:45:38.959] <TB1> INFO: Test took 1157ms.
[13:45:38.961] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:39.264] <TB1> INFO: Expecting 2560 events.
[13:45:40.116] <TB1> INFO: 2560 events read in total (296ms).
[13:45:40.117] <TB1> INFO: Test took 1156ms.
[13:45:40.118] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:45:40.422] <TB1> INFO: Expecting 2560 events.
[13:45:41.277] <TB1> INFO: 2560 events read in total (299ms).
[13:45:41.277] <TB1> INFO: Test took 1159ms.
[13:45:41.739] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 615 seconds
[13:45:41.739] <TB1> INFO: PH scale (per ROC): 69 76 79 80 75 76 74 77 76 79 78 75 79 73 79 74
[13:45:41.739] <TB1> INFO: PH offset (per ROC): 199 173 170 175 174 174 177 182 178 171 163 179 176 171 176 188
[13:45:41.744] <TB1> INFO: Decoding statistics:
[13:45:41.744] <TB1> INFO: General information:
[13:45:41.744] <TB1> INFO: 16bit words read: 91940
[13:45:41.744] <TB1> INFO: valid events total: 10240
[13:45:41.744] <TB1> INFO: empty events: 7735
[13:45:41.744] <TB1> INFO: valid events with pixels: 2505
[13:45:41.744] <TB1> INFO: valid pixel hits: 2505
[13:45:41.744] <TB1> INFO: Event errors: 0
[13:45:41.744] <TB1> INFO: start marker: 0
[13:45:41.744] <TB1> INFO: stop marker: 0
[13:45:41.744] <TB1> INFO: overflow: 0
[13:45:41.744] <TB1> INFO: invalid 5bit words: 0
[13:45:41.744] <TB1> INFO: invalid XOR eye diagram: 0
[13:45:41.744] <TB1> INFO: frame (failed synchr.): 0
[13:45:41.744] <TB1> INFO: idle data (no TBM trl): 0
[13:45:41.744] <TB1> INFO: no data (only TBM hdr): 0
[13:45:41.744] <TB1> INFO: TBM errors: 0
[13:45:41.744] <TB1> INFO: flawed TBM headers: 0
[13:45:41.744] <TB1> INFO: flawed TBM trailers: 0
[13:45:41.744] <TB1> INFO: event ID mismatches: 0
[13:45:41.744] <TB1> INFO: ROC errors: 0
[13:45:41.744] <TB1> INFO: missing ROC header(s): 0
[13:45:41.744] <TB1> INFO: misplaced readback start: 0
[13:45:41.744] <TB1> INFO: Pixel decoding errors: 0
[13:45:41.744] <TB1> INFO: pixel data incomplete: 0
[13:45:41.744] <TB1> INFO: pixel address: 0
[13:45:41.744] <TB1> INFO: pulse height fill bit: 0
[13:45:41.744] <TB1> INFO: buffer corruption: 0
[13:45:41.916] <TB1> INFO: ######################################################################
[13:45:41.916] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[13:45:41.916] <TB1> INFO: ######################################################################
[13:45:41.925] <TB1> INFO: scanning low vcal = 10
[13:45:42.184] <TB1> INFO: Expecting 41600 events.
[13:45:45.710] <TB1> INFO: 41600 events read in total (2969ms).
[13:45:45.710] <TB1> INFO: Test took 3785ms.
[13:45:45.712] <TB1> INFO: scanning low vcal = 20
[13:45:46.015] <TB1> INFO: Expecting 41600 events.
[13:45:49.567] <TB1> INFO: 41600 events read in total (2995ms).
[13:45:49.567] <TB1> INFO: Test took 3855ms.
[13:45:49.569] <TB1> INFO: scanning low vcal = 30
[13:45:49.871] <TB1> INFO: Expecting 41600 events.
[13:45:53.421] <TB1> INFO: 41600 events read in total (2994ms).
[13:45:53.422] <TB1> INFO: Test took 3853ms.
[13:45:53.424] <TB1> INFO: scanning low vcal = 40
[13:45:53.719] <TB1> INFO: Expecting 41600 events.
[13:45:57.909] <TB1> INFO: 41600 events read in total (3634ms).
[13:45:57.910] <TB1> INFO: Test took 4486ms.
[13:45:57.912] <TB1> INFO: scanning low vcal = 50
[13:45:58.178] <TB1> INFO: Expecting 41600 events.
[13:46:02.415] <TB1> INFO: 41600 events read in total (3681ms).
[13:46:02.415] <TB1> INFO: Test took 4503ms.
[13:46:02.418] <TB1> INFO: scanning low vcal = 60
[13:46:02.681] <TB1> INFO: Expecting 41600 events.
[13:46:06.916] <TB1> INFO: 41600 events read in total (3679ms).
[13:46:06.916] <TB1> INFO: Test took 4498ms.
[13:46:06.919] <TB1> INFO: scanning low vcal = 70
[13:46:07.180] <TB1> INFO: Expecting 41600 events.
[13:46:11.376] <TB1> INFO: 41600 events read in total (3640ms).
[13:46:11.376] <TB1> INFO: Test took 4457ms.
[13:46:11.379] <TB1> INFO: scanning low vcal = 80
[13:46:11.665] <TB1> INFO: Expecting 41600 events.
[13:46:15.830] <TB1> INFO: 41600 events read in total (3609ms).
[13:46:15.830] <TB1> INFO: Test took 4451ms.
[13:46:15.833] <TB1> INFO: scanning low vcal = 90
[13:46:16.099] <TB1> INFO: Expecting 41600 events.
[13:46:20.305] <TB1> INFO: 41600 events read in total (3650ms).
[13:46:20.305] <TB1> INFO: Test took 4472ms.
[13:46:20.308] <TB1> INFO: scanning low vcal = 100
[13:46:20.567] <TB1> INFO: Expecting 41600 events.
[13:46:24.746] <TB1> INFO: 41600 events read in total (3623ms).
[13:46:24.747] <TB1> INFO: Test took 4439ms.
[13:46:24.749] <TB1> INFO: scanning low vcal = 110
[13:46:25.009] <TB1> INFO: Expecting 41600 events.
[13:46:29.229] <TB1> INFO: 41600 events read in total (3664ms).
[13:46:29.230] <TB1> INFO: Test took 4481ms.
[13:46:29.233] <TB1> INFO: scanning low vcal = 120
[13:46:29.537] <TB1> INFO: Expecting 41600 events.
[13:46:33.738] <TB1> INFO: 41600 events read in total (3644ms).
[13:46:33.738] <TB1> INFO: Test took 4505ms.
[13:46:33.741] <TB1> INFO: scanning low vcal = 130
[13:46:34.004] <TB1> INFO: Expecting 41600 events.
[13:46:38.234] <TB1> INFO: 41600 events read in total (3674ms).
[13:46:38.234] <TB1> INFO: Test took 4493ms.
[13:46:38.237] <TB1> INFO: scanning low vcal = 140
[13:46:38.541] <TB1> INFO: Expecting 41600 events.
[13:46:42.738] <TB1> INFO: 41600 events read in total (3641ms).
[13:46:42.738] <TB1> INFO: Test took 4501ms.
[13:46:42.741] <TB1> INFO: scanning low vcal = 150
[13:46:43.018] <TB1> INFO: Expecting 41600 events.
[13:46:47.208] <TB1> INFO: 41600 events read in total (3633ms).
[13:46:47.208] <TB1> INFO: Test took 4467ms.
[13:46:47.211] <TB1> INFO: scanning low vcal = 160
[13:46:47.491] <TB1> INFO: Expecting 41600 events.
[13:46:51.660] <TB1> INFO: 41600 events read in total (3613ms).
[13:46:51.661] <TB1> INFO: Test took 4450ms.
[13:46:51.663] <TB1> INFO: scanning low vcal = 170
[13:46:51.922] <TB1> INFO: Expecting 41600 events.
[13:46:56.115] <TB1> INFO: 41600 events read in total (3637ms).
[13:46:56.116] <TB1> INFO: Test took 4453ms.
[13:46:56.119] <TB1> INFO: scanning low vcal = 180
[13:46:56.379] <TB1> INFO: Expecting 41600 events.
[13:47:00.590] <TB1> INFO: 41600 events read in total (3655ms).
[13:47:00.591] <TB1> INFO: Test took 4472ms.
[13:47:00.593] <TB1> INFO: scanning low vcal = 190
[13:47:00.874] <TB1> INFO: Expecting 41600 events.
[13:47:05.085] <TB1> INFO: 41600 events read in total (3655ms).
[13:47:05.086] <TB1> INFO: Test took 4493ms.
[13:47:05.089] <TB1> INFO: scanning low vcal = 200
[13:47:05.374] <TB1> INFO: Expecting 41600 events.
[13:47:09.561] <TB1> INFO: 41600 events read in total (3631ms).
[13:47:09.561] <TB1> INFO: Test took 4472ms.
[13:47:09.564] <TB1> INFO: scanning low vcal = 210
[13:47:09.823] <TB1> INFO: Expecting 41600 events.
[13:47:14.031] <TB1> INFO: 41600 events read in total (3652ms).
[13:47:14.031] <TB1> INFO: Test took 4467ms.
[13:47:14.034] <TB1> INFO: scanning low vcal = 220
[13:47:14.305] <TB1> INFO: Expecting 41600 events.
[13:47:18.493] <TB1> INFO: 41600 events read in total (3631ms).
[13:47:18.493] <TB1> INFO: Test took 4459ms.
[13:47:18.496] <TB1> INFO: scanning low vcal = 230
[13:47:18.769] <TB1> INFO: Expecting 41600 events.
[13:47:22.992] <TB1> INFO: 41600 events read in total (3667ms).
[13:47:22.992] <TB1> INFO: Test took 4496ms.
[13:47:22.995] <TB1> INFO: scanning low vcal = 240
[13:47:23.283] <TB1> INFO: Expecting 41600 events.
[13:47:27.471] <TB1> INFO: 41600 events read in total (3631ms).
[13:47:27.472] <TB1> INFO: Test took 4477ms.
[13:47:27.474] <TB1> INFO: scanning low vcal = 250
[13:47:27.737] <TB1> INFO: Expecting 41600 events.
[13:47:31.971] <TB1> INFO: 41600 events read in total (3678ms).
[13:47:31.971] <TB1> INFO: Test took 4497ms.
[13:47:31.975] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[13:47:32.264] <TB1> INFO: Expecting 41600 events.
[13:47:36.463] <TB1> INFO: 41600 events read in total (3643ms).
[13:47:36.463] <TB1> INFO: Test took 4488ms.
[13:47:36.466] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[13:47:36.748] <TB1> INFO: Expecting 41600 events.
[13:47:40.912] <TB1> INFO: 41600 events read in total (3608ms).
[13:47:40.913] <TB1> INFO: Test took 4447ms.
[13:47:40.915] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[13:47:41.177] <TB1> INFO: Expecting 41600 events.
[13:47:45.388] <TB1> INFO: 41600 events read in total (3654ms).
[13:47:45.389] <TB1> INFO: Test took 4474ms.
[13:47:45.392] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[13:47:45.668] <TB1> INFO: Expecting 41600 events.
[13:47:49.893] <TB1> INFO: 41600 events read in total (3669ms).
[13:47:49.893] <TB1> INFO: Test took 4501ms.
[13:47:49.896] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[13:47:50.179] <TB1> INFO: Expecting 41600 events.
[13:47:54.353] <TB1> INFO: 41600 events read in total (3617ms).
[13:47:54.353] <TB1> INFO: Test took 4457ms.
[13:47:54.896] <TB1> INFO: PixTestGainPedestal::measure() done
[13:48:28.537] <TB1> INFO: PixTestGainPedestal::fit() done
[13:48:28.538] <TB1> INFO: non-linearity mean: 0.960 0.960 0.955 0.962 0.957 0.960 0.956 0.950 0.963 0.961 0.960 0.957 0.962 0.956 0.964 0.960
[13:48:28.538] <TB1> INFO: non-linearity RMS: 0.005 0.006 0.005 0.005 0.005 0.006 0.006 0.006 0.006 0.005 0.006 0.006 0.005 0.006 0.005 0.005
[13:48:28.538] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[13:48:28.565] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[13:48:28.590] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[13:48:28.621] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[13:48:28.642] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[13:48:28.664] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[13:48:28.689] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[13:48:28.715] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[13:48:28.741] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[13:48:28.761] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[13:48:28.785] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[13:48:28.808] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[13:48:28.834] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[13:48:28.859] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[13:48:28.886] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[13:48:28.913] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[13:48:28.932] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 167 seconds
[13:48:28.932] <TB1> INFO: Decoding statistics:
[13:48:28.932] <TB1> INFO: General information:
[13:48:28.932] <TB1> INFO: 16bit words read: 2662400
[13:48:28.932] <TB1> INFO: valid events total: 166400
[13:48:28.932] <TB1> INFO: empty events: 0
[13:48:28.932] <TB1> INFO: valid events with pixels: 166400
[13:48:28.932] <TB1> INFO: valid pixel hits: 665580
[13:48:28.932] <TB1> INFO: Event errors: 0
[13:48:28.932] <TB1> INFO: start marker: 0
[13:48:28.932] <TB1> INFO: stop marker: 0
[13:48:28.932] <TB1> INFO: overflow: 0
[13:48:28.932] <TB1> INFO: invalid 5bit words: 0
[13:48:28.932] <TB1> INFO: invalid XOR eye diagram: 0
[13:48:28.932] <TB1> INFO: frame (failed synchr.): 0
[13:48:28.932] <TB1> INFO: idle data (no TBM trl): 0
[13:48:28.933] <TB1> INFO: no data (only TBM hdr): 0
[13:48:28.933] <TB1> INFO: TBM errors: 0
[13:48:28.933] <TB1> INFO: flawed TBM headers: 0
[13:48:28.933] <TB1> INFO: flawed TBM trailers: 0
[13:48:28.933] <TB1> INFO: event ID mismatches: 0
[13:48:28.933] <TB1> INFO: ROC errors: 0
[13:48:28.933] <TB1> INFO: missing ROC header(s): 0
[13:48:28.933] <TB1> INFO: misplaced readback start: 0
[13:48:28.933] <TB1> INFO: Pixel decoding errors: 0
[13:48:28.933] <TB1> INFO: pixel data incomplete: 0
[13:48:28.933] <TB1> INFO: pixel address: 0
[13:48:28.933] <TB1> INFO: pulse height fill bit: 0
[13:48:28.933] <TB1> INFO: buffer corruption: 0
[13:48:28.940] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C15.dat
[13:48:28.941] <TB1> INFO: ######################################################################
[13:48:28.941] <TB1> INFO: PixTestReadback::doTest()
[13:48:28.941] <TB1> INFO: ######################################################################
[13:48:28.941] <TB1> INFO: ----------------------------------------------------------------------
[13:48:28.941] <TB1> INFO: PixTestReadback::CalibrateVd()
[13:48:28.941] <TB1> INFO: ----------------------------------------------------------------------
[13:48:38.429] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C0.dat
[13:48:38.429] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C1.dat
[13:48:38.429] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C2.dat
[13:48:38.429] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C3.dat
[13:48:38.429] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C4.dat
[13:48:38.429] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C5.dat
[13:48:38.429] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C6.dat
[13:48:38.430] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C7.dat
[13:48:38.430] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C8.dat
[13:48:38.430] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C9.dat
[13:48:38.430] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C10.dat
[13:48:38.430] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C11.dat
[13:48:38.430] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C12.dat
[13:48:38.430] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C13.dat
[13:48:38.430] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C14.dat
[13:48:38.430] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C15.dat
[13:48:38.459] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[13:48:38.459] <TB1> INFO: ----------------------------------------------------------------------
[13:48:38.459] <TB1> INFO: PixTestReadback::CalibrateVa()
[13:48:38.459] <TB1> INFO: ----------------------------------------------------------------------
[13:48:47.912] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C0.dat
[13:48:47.912] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C1.dat
[13:48:47.912] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C2.dat
[13:48:47.912] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C3.dat
[13:48:47.912] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C4.dat
[13:48:47.913] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C5.dat
[13:48:47.913] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C6.dat
[13:48:47.913] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C7.dat
[13:48:47.913] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C8.dat
[13:48:47.913] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C9.dat
[13:48:47.913] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C10.dat
[13:48:47.913] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C11.dat
[13:48:47.913] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C12.dat
[13:48:47.913] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C13.dat
[13:48:47.913] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C14.dat
[13:48:47.914] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C15.dat
[13:48:47.942] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[13:48:47.942] <TB1> INFO: ----------------------------------------------------------------------
[13:48:47.942] <TB1> INFO: PixTestReadback::readbackVbg()
[13:48:47.942] <TB1> INFO: ----------------------------------------------------------------------
[13:48:55.230] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[13:48:55.230] <TB1> INFO: ----------------------------------------------------------------------
[13:48:55.230] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[13:48:55.230] <TB1> INFO: ----------------------------------------------------------------------
[13:48:55.230] <TB1> INFO: Vbg will be calibrated using Vd calibration
[13:48:55.230] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 157.9calibrated Vbg = 1.20495 :::*/*/*/*/
[13:48:55.230] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 158.6calibrated Vbg = 1.19877 :::*/*/*/*/
[13:48:55.230] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 148.7calibrated Vbg = 1.20409 :::*/*/*/*/
[13:48:55.230] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 153.4calibrated Vbg = 1.21292 :::*/*/*/*/
[13:48:55.230] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 155.3calibrated Vbg = 1.2122 :::*/*/*/*/
[13:48:55.230] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 152.6calibrated Vbg = 1.21103 :::*/*/*/*/
[13:48:55.230] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 158.1calibrated Vbg = 1.21023 :::*/*/*/*/
[13:48:55.230] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 151.1calibrated Vbg = 1.21458 :::*/*/*/*/
[13:48:55.231] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 154.5calibrated Vbg = 1.20631 :::*/*/*/*/
[13:48:55.231] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 147.2calibrated Vbg = 1.21954 :::*/*/*/*/
[13:48:55.231] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 156.5calibrated Vbg = 1.20336 :::*/*/*/*/
[13:48:55.231] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 161.7calibrated Vbg = 1.20802 :::*/*/*/*/
[13:48:55.231] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 149.1calibrated Vbg = 1.20547 :::*/*/*/*/
[13:48:55.231] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 152.3calibrated Vbg = 1.2011 :::*/*/*/*/
[13:48:55.231] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 154.1calibrated Vbg = 1.19989 :::*/*/*/*/
[13:48:55.231] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 153.2calibrated Vbg = 1.20012 :::*/*/*/*/
[13:48:55.233] <TB1> INFO: ----------------------------------------------------------------------
[13:48:55.233] <TB1> INFO: PixTestReadback::CalibrateIa()
[13:48:55.233] <TB1> INFO: ----------------------------------------------------------------------
[13:51:30.489] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C0.dat
[13:51:30.489] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C1.dat
[13:51:30.489] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C2.dat
[13:51:30.489] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C3.dat
[13:51:30.489] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C4.dat
[13:51:30.489] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C5.dat
[13:51:30.489] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C6.dat
[13:51:30.489] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C7.dat
[13:51:30.489] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C8.dat
[13:51:30.489] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C9.dat
[13:51:30.490] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C10.dat
[13:51:30.490] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C11.dat
[13:51:30.490] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C12.dat
[13:51:30.490] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C13.dat
[13:51:30.490] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C14.dat
[13:51:30.490] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2210_FullQualification_2016-04-19_08h02m_1461045727//004_FulltestPxar_p17//readbackCal_C15.dat
[13:51:30.518] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[13:51:30.519] <TB1> INFO: PixTestReadback::doTest() done
[13:51:30.519] <TB1> INFO: Decoding statistics:
[13:51:30.519] <TB1> INFO: General information:
[13:51:30.519] <TB1> INFO: 16bit words read: 1024
[13:51:30.519] <TB1> INFO: valid events total: 128
[13:51:30.519] <TB1> INFO: empty events: 128
[13:51:30.519] <TB1> INFO: valid events with pixels: 0
[13:51:30.519] <TB1> INFO: valid pixel hits: 0
[13:51:30.519] <TB1> INFO: Event errors: 0
[13:51:30.519] <TB1> INFO: start marker: 0
[13:51:30.519] <TB1> INFO: stop marker: 0
[13:51:30.519] <TB1> INFO: overflow: 0
[13:51:30.519] <TB1> INFO: invalid 5bit words: 0
[13:51:30.519] <TB1> INFO: invalid XOR eye diagram: 0
[13:51:30.519] <TB1> INFO: frame (failed synchr.): 0
[13:51:30.519] <TB1> INFO: idle data (no TBM trl): 0
[13:51:30.519] <TB1> INFO: no data (only TBM hdr): 0
[13:51:30.519] <TB1> INFO: TBM errors: 0
[13:51:30.519] <TB1> INFO: flawed TBM headers: 0
[13:51:30.519] <TB1> INFO: flawed TBM trailers: 0
[13:51:30.519] <TB1> INFO: event ID mismatches: 0
[13:51:30.519] <TB1> INFO: ROC errors: 0
[13:51:30.519] <TB1> INFO: missing ROC header(s): 0
[13:51:30.520] <TB1> INFO: misplaced readback start: 0
[13:51:30.520] <TB1> INFO: Pixel decoding errors: 0
[13:51:30.520] <TB1> INFO: pixel data incomplete: 0
[13:51:30.520] <TB1> INFO: pixel address: 0
[13:51:30.520] <TB1> INFO: pulse height fill bit: 0
[13:51:30.520] <TB1> INFO: buffer corruption: 0
[13:51:30.540] <TB1> INFO: Decoding statistics:
[13:51:30.540] <TB1> INFO: General information:
[13:51:30.540] <TB1> INFO: 16bit words read: 2755364
[13:51:30.540] <TB1> INFO: valid events total: 176768
[13:51:30.540] <TB1> INFO: empty events: 7863
[13:51:30.540] <TB1> INFO: valid events with pixels: 168905
[13:51:30.540] <TB1> INFO: valid pixel hits: 668085
[13:51:30.540] <TB1> INFO: Event errors: 0
[13:51:30.540] <TB1> INFO: start marker: 0
[13:51:30.540] <TB1> INFO: stop marker: 0
[13:51:30.540] <TB1> INFO: overflow: 0
[13:51:30.540] <TB1> INFO: invalid 5bit words: 0
[13:51:30.540] <TB1> INFO: invalid XOR eye diagram: 0
[13:51:30.540] <TB1> INFO: frame (failed synchr.): 0
[13:51:30.540] <TB1> INFO: idle data (no TBM trl): 0
[13:51:30.540] <TB1> INFO: no data (only TBM hdr): 0
[13:51:30.540] <TB1> INFO: TBM errors: 0
[13:51:30.540] <TB1> INFO: flawed TBM headers: 0
[13:51:30.540] <TB1> INFO: flawed TBM trailers: 0
[13:51:30.540] <TB1> INFO: event ID mismatches: 0
[13:51:30.540] <TB1> INFO: ROC errors: 0
[13:51:30.540] <TB1> INFO: missing ROC header(s): 0
[13:51:30.540] <TB1> INFO: misplaced readback start: 0
[13:51:30.540] <TB1> INFO: Pixel decoding errors: 0
[13:51:30.540] <TB1> INFO: pixel data incomplete: 0
[13:51:30.540] <TB1> INFO: pixel address: 0
[13:51:30.540] <TB1> INFO: pulse height fill bit: 0
[13:51:30.540] <TB1> INFO: buffer corruption: 0
[13:51:30.540] <TB1> INFO: enter test to run
[13:51:30.540] <TB1> INFO: test: exit no parameter change
[13:51:30.692] <TB1> QUIET: Connection to board 153 closed.
[13:51:30.772] <TB1> INFO: pXar: this is the end, my friend