[10:17:27.350] <TB0> INFO: *** Welcome to pxar ***
[10:17:27.350] <TB0> INFO: *** Today: 2016/07/18
[10:17:27.819] <TB0> INFO: *** Version: 9751-dirty
[10:17:27.819] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C15.dat
[10:17:27.820] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//tbmParameters_C0b.dat
[10:17:27.820] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//defaultMaskFile.dat
[10:17:27.820] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters_C15.dat
[10:17:27.887] <TB0> INFO: clk: 4
[10:17:27.887] <TB0> INFO: ctr: 4
[10:17:27.887] <TB0> INFO: sda: 19
[10:17:27.887] <TB0> INFO: tin: 9
[10:17:27.887] <TB0> INFO: level: 15
[10:17:27.887] <TB0> INFO: triggerdelay: 0
[10:17:27.887] <TB0> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[10:17:27.887] <TB0> INFO: Log level: INFO
[10:17:27.894] <TB0> INFO: Found DTB DTB_WS6AYH
[10:17:27.906] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[10:17:27.908] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[10:17:27.909] <TB0> INFO: RPC call hashes of host and DTB match: 484264910
[10:17:29.437] <TB0> INFO: DUT info:
[10:17:29.437] <TB0> INFO: The DUT currently contains the following objects:
[10:17:29.437] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[10:17:29.437] <TB0> INFO: TBM Core alpha (0): 7 registers set
[10:17:29.438] <TB0> INFO: TBM Core beta (1): 7 registers set
[10:17:29.438] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:17:29.438] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:29.438] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:29.438] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:29.438] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:29.438] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:29.438] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:29.438] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:29.438] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:29.438] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:29.438] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:29.438] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:29.438] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:29.438] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:29.438] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:29.438] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:29.438] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:17:29.838] <TB0> INFO: enter 'restricted' command line mode
[10:17:29.838] <TB0> INFO: enter test to run
[10:17:29.838] <TB0> INFO: test: pretest no parameter change
[10:17:29.838] <TB0> INFO: running: pretest
[10:17:29.843] <TB0> INFO: ----------------------------------------------------------------------
[10:17:29.843] <TB0> INFO: PixTestPretest::programROC()
[10:17:29.843] <TB0> INFO: ----------------------------------------------------------------------
[10:17:47.857] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[10:17:47.857] <TB0> INFO: IA differences per ROC: 17.7 17.7 18.5 16.9 17.7 16.1 16.9 17.7 19.3 18.5 16.9 16.9 18.5 18.5 18.5 17.7
[10:17:47.977] <TB0> INFO: enter test to run
[10:17:47.977] <TB0> INFO: test: pretest no parameter change
[10:17:47.977] <TB0> INFO: running: pretest
[10:17:47.978] <TB0> INFO: ----------------------------------------------------------------------
[10:17:47.978] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[10:17:47.978] <TB0> INFO: ----------------------------------------------------------------------
[10:18:09.247] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 393.9 mA = 24.6187 mA/ROC
[10:18:09.247] <TB0> INFO: i(loss) [mA/ROC]: 20.9 20.1 21.7 20.1 20.1 20.1 20.1 20.1 20.1 20.9 19.3 20.1 20.1 20.9 19.3 19.3
[10:18:09.281] <TB0> INFO: enter test to run
[10:18:09.281] <TB0> INFO: test: pretest no parameter change
[10:18:09.281] <TB0> INFO: running: pretest
[10:18:09.282] <TB0> INFO: ----------------------------------------------------------------------
[10:18:09.282] <TB0> INFO: PixTestPretest::findTiming()
[10:18:09.282] <TB0> INFO: ----------------------------------------------------------------------
[10:18:09.282] <TB0> INFO: PixTestCmd::init()
[10:18:09.952] <TB0> WARNING: Not unmasking DUT, not setting Calibrate bits!
[10:19:37.983] <TB0> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[10:19:37.983] <TB0> INFO: (success/tries = 100/100), width = 5
[10:19:37.984] <TB0> INFO: enter test to run
[10:19:37.984] <TB0> INFO: test: pretest no parameter change
[10:19:37.984] <TB0> INFO: running: pretest
[10:19:37.985] <TB0> INFO: ----------------------------------------------------------------------
[10:19:37.985] <TB0> INFO: PixTestPretest::findWorkingPixel()
[10:19:37.985] <TB0> INFO: ----------------------------------------------------------------------
[10:19:38.079] <TB0> INFO: Expecting 231680 events.
[10:19:43.859] <TB0> ERROR: <datapipe.cc/CheckEventValidity:L497> Channel 0 Number of ROCs (1) != Token Chain Length (4)
[10:19:43.942] <TB0> ERROR: <datapipe.cc/CheckEventID:L459> Channel 0 Event ID mismatch: local ID (21) != TBM ID (2)
[10:19:48.373] <TB0> INFO: 231680 events read in total (9737ms).
[10:19:48.377] <TB0> INFO: Test took 10386ms.
[10:19:48.595] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[10:19:48.664] <TB0> INFO: enter test to run
[10:19:48.664] <TB0> INFO: test: pretest no parameter change
[10:19:48.664] <TB0> INFO: running: pretest
[10:19:48.665] <TB0> INFO: ----------------------------------------------------------------------
[10:19:48.665] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[10:19:48.665] <TB0> INFO: ----------------------------------------------------------------------
[10:19:48.757] <TB0> INFO: Expecting 231680 events.
[10:19:59.094] <TB0> INFO: 231680 events read in total (9781ms).
[10:19:59.098] <TB0> INFO: Test took 10429ms.
[10:19:59.337] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[10:19:59.337] <TB0> INFO: CalDel: 143 125 141 105 114 107 132 123 132 111 103 99 119 162 111 124
[10:19:59.337] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 54 51 51
[10:19:59.379] <TB0> INFO: enter test to run
[10:19:59.379] <TB0> INFO: test: pretest no parameter change
[10:19:59.379] <TB0> INFO: running: pretest
[10:19:59.379] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C0.dat
[10:19:59.379] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C1.dat
[10:19:59.379] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C2.dat
[10:19:59.380] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C3.dat
[10:19:59.380] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C4.dat
[10:19:59.380] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C5.dat
[10:19:59.380] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C6.dat
[10:19:59.380] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C7.dat
[10:19:59.380] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C8.dat
[10:19:59.380] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C9.dat
[10:19:59.380] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C10.dat
[10:19:59.380] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C11.dat
[10:19:59.381] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C12.dat
[10:19:59.381] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C13.dat
[10:19:59.381] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C14.dat
[10:19:59.381] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters_C15.dat
[10:19:59.381] <TB0> INFO: enter test to run
[10:19:59.381] <TB0> INFO: test: fulltest no parameter change
[10:19:59.381] <TB0> INFO: running: fulltest
[10:19:59.381] <TB0> INFO: ######################################################################
[10:19:59.381] <TB0> INFO: PixTestFullTest::doTest()
[10:19:59.381] <TB0> INFO: ######################################################################
[10:19:59.382] <TB0> INFO: ######################################################################
[10:19:59.382] <TB0> INFO: PixTestAlive::doTest()
[10:19:59.382] <TB0> INFO: ######################################################################
[10:19:59.383] <TB0> INFO: ----------------------------------------------------------------------
[10:19:59.383] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:19:59.383] <TB0> INFO: ----------------------------------------------------------------------
[10:19:59.645] <TB0> INFO: Expecting 41600 events.
[10:20:03.517] <TB0> INFO: 41600 events read in total (3316ms).
[10:20:03.517] <TB0> INFO: Test took 4132ms.
[10:20:03.737] <TB0> INFO: PixTestAlive::aliveTest() done
[10:20:03.737] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:20:03.738] <TB0> INFO: ----------------------------------------------------------------------
[10:20:03.738] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:20:03.738] <TB0> INFO: ----------------------------------------------------------------------
[10:20:04.037] <TB0> INFO: Expecting 41600 events.
[10:20:06.992] <TB0> INFO: 41600 events read in total (2399ms).
[10:20:06.992] <TB0> INFO: Test took 3253ms.
[10:20:06.993] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[10:20:07.244] <TB0> INFO: PixTestAlive::maskTest() done
[10:20:07.244] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:20:07.245] <TB0> INFO: ----------------------------------------------------------------------
[10:20:07.245] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:20:07.245] <TB0> INFO: ----------------------------------------------------------------------
[10:20:07.547] <TB0> INFO: Expecting 41600 events.
[10:20:11.307] <TB0> INFO: 41600 events read in total (3204ms).
[10:20:11.308] <TB0> INFO: Test took 4061ms.
[10:20:11.530] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[10:20:11.531] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:20:11.531] <TB0> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[10:20:11.531] <TB0> INFO: Decoding statistics:
[10:20:11.531] <TB0> INFO: General information:
[10:20:11.531] <TB0> INFO: 16bit words read: 0
[10:20:11.531] <TB0> INFO: valid events total: 0
[10:20:11.531] <TB0> INFO: empty events: 0
[10:20:11.531] <TB0> INFO: valid events with pixels: 0
[10:20:11.531] <TB0> INFO: valid pixel hits: 0
[10:20:11.531] <TB0> INFO: Event errors: 0
[10:20:11.531] <TB0> INFO: start marker: 0
[10:20:11.531] <TB0> INFO: stop marker: 0
[10:20:11.531] <TB0> INFO: overflow: 0
[10:20:11.531] <TB0> INFO: invalid 5bit words: 0
[10:20:11.531] <TB0> INFO: invalid XOR eye diagram: 0
[10:20:11.531] <TB0> INFO: frame (failed synchr.): 0
[10:20:11.531] <TB0> INFO: idle data (no TBM trl): 0
[10:20:11.531] <TB0> INFO: no data (only TBM hdr): 0
[10:20:11.531] <TB0> INFO: TBM errors: 0
[10:20:11.531] <TB0> INFO: flawed TBM headers: 0
[10:20:11.531] <TB0> INFO: flawed TBM trailers: 0
[10:20:11.531] <TB0> INFO: event ID mismatches: 0
[10:20:11.531] <TB0> INFO: ROC errors: 0
[10:20:11.531] <TB0> INFO: missing ROC header(s): 0
[10:20:11.531] <TB0> INFO: misplaced readback start: 0
[10:20:11.531] <TB0> INFO: Pixel decoding errors: 0
[10:20:11.531] <TB0> INFO: pixel data incomplete: 0
[10:20:11.531] <TB0> INFO: pixel address: 0
[10:20:11.531] <TB0> INFO: pulse height fill bit: 0
[10:20:11.531] <TB0> INFO: buffer corruption: 0
[10:20:11.538] <TB0> INFO: ######################################################################
[10:20:11.538] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[10:20:11.538] <TB0> INFO: ######################################################################
[10:20:11.540] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[10:20:11.551] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[10:20:11.551] <TB0> INFO: run 1 of 1
[10:20:11.814] <TB0> INFO: Expecting 3120000 events.
[10:20:54.027] <TB0> INFO: 906945 events read in total (41657ms).
[10:21:35.020] <TB0> INFO: 1785565 events read in total (82650ms).
[10:22:15.305] <TB0> INFO: 2661130 events read in total (122935ms).
[10:22:36.302] <TB0> INFO: 3120000 events read in total (143932ms).
[10:22:36.348] <TB0> INFO: Test took 144797ms.
[10:23:00.602] <TB0> INFO: PixTestBBMap::doTest() done, duration: 169 seconds
[10:23:00.602] <TB0> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:23:00.602] <TB0> INFO: separation cut (per ROC): 125 114 128 118 109 117 111 107 118 117 113 118 111 118 117 119
[10:23:00.602] <TB0> INFO: Decoding statistics:
[10:23:00.602] <TB0> INFO: General information:
[10:23:00.602] <TB0> INFO: 16bit words read: 0
[10:23:00.602] <TB0> INFO: valid events total: 0
[10:23:00.602] <TB0> INFO: empty events: 0
[10:23:00.602] <TB0> INFO: valid events with pixels: 0
[10:23:00.602] <TB0> INFO: valid pixel hits: 0
[10:23:00.602] <TB0> INFO: Event errors: 0
[10:23:00.602] <TB0> INFO: start marker: 0
[10:23:00.602] <TB0> INFO: stop marker: 0
[10:23:00.602] <TB0> INFO: overflow: 0
[10:23:00.602] <TB0> INFO: invalid 5bit words: 0
[10:23:00.602] <TB0> INFO: invalid XOR eye diagram: 0
[10:23:00.602] <TB0> INFO: frame (failed synchr.): 0
[10:23:00.602] <TB0> INFO: idle data (no TBM trl): 0
[10:23:00.602] <TB0> INFO: no data (only TBM hdr): 0
[10:23:00.602] <TB0> INFO: TBM errors: 0
[10:23:00.602] <TB0> INFO: flawed TBM headers: 0
[10:23:00.602] <TB0> INFO: flawed TBM trailers: 0
[10:23:00.602] <TB0> INFO: event ID mismatches: 0
[10:23:00.602] <TB0> INFO: ROC errors: 0
[10:23:00.602] <TB0> INFO: missing ROC header(s): 0
[10:23:00.602] <TB0> INFO: misplaced readback start: 0
[10:23:00.602] <TB0> INFO: Pixel decoding errors: 0
[10:23:00.602] <TB0> INFO: pixel data incomplete: 0
[10:23:00.602] <TB0> INFO: pixel address: 0
[10:23:00.602] <TB0> INFO: pulse height fill bit: 0
[10:23:00.602] <TB0> INFO: buffer corruption: 0
[10:23:00.671] <TB0> INFO: ######################################################################
[10:23:00.671] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:23:00.671] <TB0> INFO: ######################################################################
[10:23:00.671] <TB0> INFO: ----------------------------------------------------------------------
[10:23:00.671] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:23:00.671] <TB0> INFO: ----------------------------------------------------------------------
[10:23:00.671] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:23:00.679] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[10:23:00.679] <TB0> INFO: run 1 of 1
[10:23:00.945] <TB0> INFO: Expecting 26208000 events.
[10:23:35.104] <TB0> INFO: 985550 events read in total (33603ms).
[10:24:08.747] <TB0> INFO: 1950750 events read in total (67246ms).
[10:24:42.061] <TB0> INFO: 2916450 events read in total (100560ms).
[10:25:15.325] <TB0> INFO: 3884650 events read in total (133824ms).
[10:25:49.119] <TB0> INFO: 4849650 events read in total (167618ms).
[10:26:22.771] <TB0> INFO: 5813650 events read in total (201270ms).
[10:26:56.173] <TB0> INFO: 6777550 events read in total (234672ms).
[10:27:29.653] <TB0> INFO: 7742300 events read in total (268152ms).
[10:28:03.472] <TB0> INFO: 8706250 events read in total (301971ms).
[10:28:36.875] <TB0> INFO: 9671500 events read in total (335374ms).
[10:29:10.432] <TB0> INFO: 10633950 events read in total (368931ms).
[10:29:43.657] <TB0> INFO: 11596150 events read in total (402156ms).
[10:30:17.153] <TB0> INFO: 12558000 events read in total (435652ms).
[10:30:50.754] <TB0> INFO: 13512150 events read in total (469253ms).
[10:31:24.442] <TB0> INFO: 14458750 events read in total (502941ms).
[10:31:58.517] <TB0> INFO: 15407500 events read in total (537016ms).
[10:32:31.928] <TB0> INFO: 16352700 events read in total (570427ms).
[10:33:05.572] <TB0> INFO: 17298600 events read in total (604071ms).
[10:33:38.800] <TB0> INFO: 18244500 events read in total (637299ms).
[10:34:12.308] <TB0> INFO: 19189600 events read in total (670807ms).
[10:34:45.824] <TB0> INFO: 20133350 events read in total (704323ms).
[10:35:19.023] <TB0> INFO: 21078450 events read in total (737522ms).
[10:35:52.430] <TB0> INFO: 22024600 events read in total (770929ms).
[10:36:26.286] <TB0> INFO: 22972750 events read in total (804786ms).
[10:36:59.833] <TB0> INFO: 23922300 events read in total (838332ms).
[10:37:33.683] <TB0> INFO: 24875350 events read in total (872182ms).
[10:38:07.567] <TB0> INFO: 25830750 events read in total (906066ms).
[10:38:20.689] <TB0> INFO: 26208000 events read in total (919188ms).
[10:38:20.712] <TB0> INFO: Test took 920033ms.
[10:38:20.900] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:22.266] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:23.653] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:25.009] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:26.398] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:27.929] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:29.359] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:31.039] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:32.455] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:33.973] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:35.461] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:37.053] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:38.596] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:40.143] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:41.710] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:43.184] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[10:38:44.582] <TB0> INFO: PixTestScurves::scurves() done
[10:38:44.582] <TB0> INFO: Vcal mean: 107.65 99.12 109.56 98.29 105.16 113.94 103.79 103.62 104.47 100.23 95.36 108.26 104.09 108.68 103.93 105.92
[10:38:44.582] <TB0> INFO: Vcal RMS: 4.93 5.40 5.15 5.21 4.74 5.36 5.09 5.11 4.59 4.55 5.29 4.77 5.42 4.72 5.22 5.14
[10:38:44.582] <TB0> INFO: PixTestScurves::fullTest() done, duration: 943 seconds
[10:38:44.582] <TB0> INFO: Decoding statistics:
[10:38:44.582] <TB0> INFO: General information:
[10:38:44.582] <TB0> INFO: 16bit words read: 0
[10:38:44.582] <TB0> INFO: valid events total: 0
[10:38:44.582] <TB0> INFO: empty events: 0
[10:38:44.582] <TB0> INFO: valid events with pixels: 0
[10:38:44.582] <TB0> INFO: valid pixel hits: 0
[10:38:44.582] <TB0> INFO: Event errors: 0
[10:38:44.582] <TB0> INFO: start marker: 0
[10:38:44.582] <TB0> INFO: stop marker: 0
[10:38:44.582] <TB0> INFO: overflow: 0
[10:38:44.582] <TB0> INFO: invalid 5bit words: 0
[10:38:44.582] <TB0> INFO: invalid XOR eye diagram: 0
[10:38:44.582] <TB0> INFO: frame (failed synchr.): 0
[10:38:44.582] <TB0> INFO: idle data (no TBM trl): 0
[10:38:44.582] <TB0> INFO: no data (only TBM hdr): 0
[10:38:44.582] <TB0> INFO: TBM errors: 0
[10:38:44.582] <TB0> INFO: flawed TBM headers: 0
[10:38:44.582] <TB0> INFO: flawed TBM trailers: 0
[10:38:44.582] <TB0> INFO: event ID mismatches: 0
[10:38:44.582] <TB0> INFO: ROC errors: 0
[10:38:44.582] <TB0> INFO: missing ROC header(s): 0
[10:38:44.582] <TB0> INFO: misplaced readback start: 0
[10:38:44.582] <TB0> INFO: Pixel decoding errors: 0
[10:38:44.582] <TB0> INFO: pixel data incomplete: 0
[10:38:44.582] <TB0> INFO: pixel address: 0
[10:38:44.582] <TB0> INFO: pulse height fill bit: 0
[10:38:44.582] <TB0> INFO: buffer corruption: 0
[10:38:44.652] <TB0> INFO: ######################################################################
[10:38:44.653] <TB0> INFO: PixTestTrim::doTest()
[10:38:44.653] <TB0> INFO: ######################################################################
[10:38:44.654] <TB0> INFO: ----------------------------------------------------------------------
[10:38:44.654] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:38:44.654] <TB0> INFO: ----------------------------------------------------------------------
[10:38:44.750] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:38:44.750] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:38:44.759] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:38:44.759] <TB0> INFO: run 1 of 1
[10:38:45.045] <TB0> INFO: Expecting 6281600 events.
[10:39:25.003] <TB0> INFO: 1095040 events read in total (39402ms).
[10:40:04.159] <TB0> INFO: 2183550 events read in total (78558ms).
[10:40:43.045] <TB0> INFO: 3264100 events read in total (117445ms).
[10:41:21.929] <TB0> INFO: 4346520 events read in total (156328ms).
[10:42:00.945] <TB0> INFO: 5428970 events read in total (195344ms).
[10:42:31.944] <TB0> INFO: 6281600 events read in total (226343ms).
[10:42:31.979] <TB0> INFO: Test took 227220ms.
[10:42:51.902] <TB0> INFO: ROC 0 VthrComp = 106
[10:42:51.902] <TB0> INFO: ROC 1 VthrComp = 99
[10:42:51.902] <TB0> INFO: ROC 2 VthrComp = 109
[10:42:51.902] <TB0> INFO: ROC 3 VthrComp = 98
[10:42:51.902] <TB0> INFO: ROC 4 VthrComp = 102
[10:42:51.902] <TB0> INFO: ROC 5 VthrComp = 107
[10:42:51.903] <TB0> INFO: ROC 6 VthrComp = 101
[10:42:51.903] <TB0> INFO: ROC 7 VthrComp = 103
[10:42:51.903] <TB0> INFO: ROC 8 VthrComp = 107
[10:42:51.903] <TB0> INFO: ROC 9 VthrComp = 104
[10:42:51.903] <TB0> INFO: ROC 10 VthrComp = 94
[10:42:51.903] <TB0> INFO: ROC 11 VthrComp = 106
[10:42:51.903] <TB0> INFO: ROC 12 VthrComp = 101
[10:42:51.903] <TB0> INFO: ROC 13 VthrComp = 105
[10:42:51.903] <TB0> INFO: ROC 14 VthrComp = 99
[10:42:51.904] <TB0> INFO: ROC 15 VthrComp = 104
[10:42:51.904] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:42:51.904] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:42:51.914] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:42:51.914] <TB0> INFO: run 1 of 1
[10:42:52.202] <TB0> INFO: Expecting 6281600 events.
[10:43:27.952] <TB0> INFO: 754960 events read in total (35194ms).
[10:44:02.786] <TB0> INFO: 1509490 events read in total (70028ms).
[10:44:37.073] <TB0> INFO: 2264940 events read in total (104315ms).
[10:45:11.955] <TB0> INFO: 3022240 events read in total (139197ms).
[10:45:46.703] <TB0> INFO: 3774180 events read in total (173945ms).
[10:46:21.299] <TB0> INFO: 4522430 events read in total (208541ms).
[10:46:56.271] <TB0> INFO: 5268880 events read in total (243513ms).
[10:47:31.152] <TB0> INFO: 6015670 events read in total (278394ms).
[10:47:43.903] <TB0> INFO: 6281600 events read in total (291145ms).
[10:47:43.948] <TB0> INFO: Test took 292034ms.
[10:48:09.628] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 61.4231 for pixel 22/39 mean/min/max = 48.139/34.699/61.5789
[10:48:09.628] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 58.1652 for pixel 24/6 mean/min/max = 45.1195/32.0545/58.1845
[10:48:09.628] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 61.8675 for pixel 11/79 mean/min/max = 48.4382/34.8452/62.0312
[10:48:09.629] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 60.0095 for pixel 21/11 mean/min/max = 46.2876/32.4694/60.1058
[10:48:09.629] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 56.8562 for pixel 17/5 mean/min/max = 44.884/32.718/57.0499
[10:48:09.629] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 61.3307 for pixel 46/79 mean/min/max = 48.0656/34.7549/61.3763
[10:48:09.630] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 57.1316 for pixel 51/37 mean/min/max = 44.7899/32.3018/57.278
[10:48:09.630] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 55.9977 for pixel 19/44 mean/min/max = 44.341/32.5289/56.153
[10:48:09.631] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 57.6391 for pixel 44/0 mean/min/max = 46.3269/34.9522/57.7015
[10:48:09.631] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 56.8629 for pixel 24/42 mean/min/max = 45.9992/35.0865/56.9119
[10:48:09.631] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 56.8548 for pixel 20/74 mean/min/max = 45.7436/34.2865/57.2008
[10:48:09.632] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 60.7228 for pixel 0/53 mean/min/max = 47.3591/33.9767/60.7414
[10:48:09.632] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 57.7224 for pixel 0/70 mean/min/max = 44.9225/31.7762/58.0688
[10:48:09.632] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 60.9483 for pixel 51/18 mean/min/max = 47.9884/34.7343/61.2424
[10:48:09.633] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 61.0471 for pixel 6/2 mean/min/max = 46.2468/31.4121/61.0814
[10:48:09.633] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 59.3468 for pixel 23/57 mean/min/max = 46.6245/33.875/59.3739
[10:48:09.633] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:48:09.721] <TB0> INFO: Expecting 514560 events.
[10:48:22.734] <TB0> INFO: 514560 events read in total (12457ms).
[10:48:22.739] <TB0> INFO: Expecting 514560 events.
[10:48:35.369] <TB0> INFO: 514560 events read in total (12213ms).
[10:48:35.375] <TB0> INFO: Expecting 514560 events.
[10:48:48.173] <TB0> INFO: 514560 events read in total (12376ms).
[10:48:48.182] <TB0> INFO: Expecting 514560 events.
[10:49:01.014] <TB0> INFO: 514560 events read in total (12420ms).
[10:49:01.026] <TB0> INFO: Expecting 514560 events.
[10:49:13.671] <TB0> INFO: 514560 events read in total (12233ms).
[10:49:13.684] <TB0> INFO: Expecting 514560 events.
[10:49:26.405] <TB0> INFO: 514560 events read in total (12306ms).
[10:49:26.419] <TB0> INFO: Expecting 514560 events.
[10:49:39.004] <TB0> INFO: 514560 events read in total (12173ms).
[10:49:39.021] <TB0> INFO: Expecting 514560 events.
[10:49:51.952] <TB0> INFO: 514560 events read in total (12522ms).
[10:49:51.972] <TB0> INFO: Expecting 514560 events.
[10:50:04.848] <TB0> INFO: 514560 events read in total (12472ms).
[10:50:04.870] <TB0> INFO: Expecting 514560 events.
[10:50:17.774] <TB0> INFO: 514560 events read in total (12500ms).
[10:50:17.797] <TB0> INFO: Expecting 514560 events.
[10:50:30.557] <TB0> INFO: 514560 events read in total (12358ms).
[10:50:30.589] <TB0> INFO: Expecting 514560 events.
[10:50:43.381] <TB0> INFO: 514560 events read in total (12391ms).
[10:50:43.407] <TB0> INFO: Expecting 514560 events.
[10:50:56.139] <TB0> INFO: 514560 events read in total (12330ms).
[10:50:56.168] <TB0> INFO: Expecting 514560 events.
[10:51:08.934] <TB0> INFO: 514560 events read in total (12365ms).
[10:51:08.964] <TB0> INFO: Expecting 514560 events.
[10:51:21.793] <TB0> INFO: 514560 events read in total (12427ms).
[10:51:21.825] <TB0> INFO: Expecting 514560 events.
[10:51:34.698] <TB0> INFO: 514560 events read in total (12471ms).
[10:51:34.730] <TB0> INFO: Test took 205097ms.
[10:51:35.729] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:51:35.738] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:51:35.738] <TB0> INFO: run 1 of 1
[10:51:36.009] <TB0> INFO: Expecting 6281600 events.
[10:52:10.851] <TB0> INFO: 739090 events read in total (34285ms).
[10:52:45.211] <TB0> INFO: 1478240 events read in total (68645ms).
[10:53:19.754] <TB0> INFO: 2219100 events read in total (103188ms).
[10:53:54.133] <TB0> INFO: 2961930 events read in total (137567ms).
[10:54:28.518] <TB0> INFO: 3699330 events read in total (171952ms).
[10:55:03.219] <TB0> INFO: 4432890 events read in total (206653ms).
[10:55:37.470] <TB0> INFO: 5164420 events read in total (240904ms).
[10:56:11.472] <TB0> INFO: 5895950 events read in total (274906ms).
[10:56:29.602] <TB0> INFO: 6281600 events read in total (293036ms).
[10:56:29.647] <TB0> INFO: Test took 293909ms.
[10:56:54.114] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.076435 .. 255.000000
[10:56:54.193] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[10:56:54.202] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[10:56:54.202] <TB0> INFO: run 1 of 1
[10:56:54.463] <TB0> INFO: Expecting 10649600 events.
[10:57:28.999] <TB0> INFO: 712050 events read in total (33980ms).
[10:58:03.222] <TB0> INFO: 1424470 events read in total (68203ms).
[10:58:37.266] <TB0> INFO: 2136710 events read in total (102247ms).
[10:59:10.696] <TB0> INFO: 2849560 events read in total (135677ms).
[10:59:45.023] <TB0> INFO: 3562340 events read in total (170004ms).
[11:00:19.243] <TB0> INFO: 4274900 events read in total (204224ms).
[11:00:53.057] <TB0> INFO: 4987760 events read in total (238038ms).
[11:01:27.474] <TB0> INFO: 5700370 events read in total (272455ms).
[11:02:02.051] <TB0> INFO: 6413170 events read in total (307032ms).
[11:02:36.057] <TB0> INFO: 7125570 events read in total (341038ms).
[11:03:10.463] <TB0> INFO: 7837820 events read in total (375444ms).
[11:03:44.695] <TB0> INFO: 8549400 events read in total (409676ms).
[11:04:18.361] <TB0> INFO: 9260950 events read in total (443342ms).
[11:04:52.298] <TB0> INFO: 9972670 events read in total (477279ms).
[11:05:24.837] <TB0> INFO: 10649600 events read in total (509818ms).
[11:05:24.960] <TB0> INFO: Test took 510758ms.
[11:05:54.816] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 15.803616 .. 48.333141
[11:05:54.896] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 5 .. 58 (-1/-1) hits flags = 528 (plus default)
[11:05:54.904] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[11:05:54.904] <TB0> INFO: run 1 of 1
[11:05:55.175] <TB0> INFO: Expecting 2246400 events.
[11:06:33.368] <TB0> INFO: 915940 events read in total (37637ms).
[11:07:10.827] <TB0> INFO: 1831210 events read in total (75096ms).
[11:07:28.237] <TB0> INFO: 2246400 events read in total (92507ms).
[11:07:28.265] <TB0> INFO: Test took 93362ms.
[11:07:42.866] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 19.474513 .. 47.293400
[11:07:42.944] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 9 .. 57 (-1/-1) hits flags = 528 (plus default)
[11:07:42.952] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[11:07:42.952] <TB0> INFO: run 1 of 1
[11:07:43.216] <TB0> INFO: Expecting 2038400 events.
[11:08:20.901] <TB0> INFO: 902730 events read in total (37129ms).
[11:08:58.566] <TB0> INFO: 1805150 events read in total (74794ms).
[11:09:08.326] <TB0> INFO: 2038400 events read in total (84555ms).
[11:09:08.347] <TB0> INFO: Test took 85395ms.
[11:09:22.533] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 2.891168 .. 45.940916
[11:09:22.608] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 2 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:09:22.616] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[11:09:22.616] <TB0> INFO: run 1 of 1
[11:09:22.875] <TB0> INFO: Expecting 2246400 events.
[11:10:01.448] <TB0> INFO: 954960 events read in total (38017ms).
[11:10:39.808] <TB0> INFO: 1910430 events read in total (76377ms).
[11:10:53.409] <TB0> INFO: 2246400 events read in total (89979ms).
[11:10:53.423] <TB0> INFO: Test took 90807ms.
[11:11:08.011] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[11:11:08.012] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[11:11:08.020] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[11:11:08.020] <TB0> INFO: run 1 of 1
[11:11:08.282] <TB0> INFO: Expecting 1705600 events.
[11:11:45.651] <TB0> INFO: 877740 events read in total (36813ms).
[11:12:20.754] <TB0> INFO: 1705600 events read in total (71916ms).
[11:12:20.766] <TB0> INFO: Test took 72746ms.
[11:12:34.730] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C0.dat
[11:12:34.730] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C1.dat
[11:12:34.731] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C2.dat
[11:12:34.731] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C3.dat
[11:12:34.731] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C4.dat
[11:12:34.731] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C5.dat
[11:12:34.731] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C6.dat
[11:12:34.731] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C7.dat
[11:12:34.731] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C8.dat
[11:12:34.731] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C9.dat
[11:12:34.732] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C10.dat
[11:12:34.732] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C11.dat
[11:12:34.732] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C12.dat
[11:12:34.732] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C13.dat
[11:12:34.732] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C14.dat
[11:12:34.732] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C15.dat
[11:12:34.733] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C0.dat
[11:12:34.740] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C1.dat
[11:12:34.747] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C2.dat
[11:12:34.753] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C3.dat
[11:12:34.760] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C4.dat
[11:12:34.768] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C5.dat
[11:12:34.777] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C6.dat
[11:12:34.785] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C7.dat
[11:12:34.792] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C8.dat
[11:12:34.799] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C9.dat
[11:12:34.806] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C10.dat
[11:12:34.813] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C11.dat
[11:12:34.820] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C12.dat
[11:12:34.826] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C13.dat
[11:12:34.835] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C14.dat
[11:12:34.843] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//trimParameters35_C15.dat
[11:12:34.850] <TB0> INFO: PixTestTrim::trimTest() done
[11:12:34.850] <TB0> INFO: vtrim: 120 95 130 113 97 116 94 100 100 108 93 108 86 105 106 101
[11:12:34.850] <TB0> INFO: vthrcomp: 106 99 109 98 102 107 101 103 107 104 94 106 101 105 99 104
[11:12:34.850] <TB0> INFO: vcal mean: 34.95 34.95 35.00 34.95 34.99 34.96 34.95 34.98 35.05 34.99 35.00 35.06 34.97 34.99 34.99 34.99
[11:12:34.850] <TB0> INFO: vcal RMS: 0.88 0.86 0.88 0.92 0.92 1.03 0.91 0.85 0.83 0.82 0.84 0.89 0.91 0.90 0.96 0.85
[11:12:34.850] <TB0> INFO: bits mean: 8.61 9.34 7.98 9.54 9.66 9.18 9.68 9.96 8.47 9.07 9.01 8.74 9.30 8.25 9.49 9.04
[11:12:34.850] <TB0> INFO: bits RMS: 2.51 2.80 2.68 2.65 2.60 2.24 2.59 2.47 2.63 2.44 2.55 2.62 2.87 2.64 2.78 2.56
[11:12:34.857] <TB0> INFO: ----------------------------------------------------------------------
[11:12:34.857] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[11:12:34.857] <TB0> INFO: ----------------------------------------------------------------------
[11:12:34.860] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[11:12:34.871] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:12:34.871] <TB0> INFO: run 1 of 1
[11:12:35.163] <TB0> INFO: Expecting 4160000 events.
[11:13:17.553] <TB0> INFO: 996670 events read in total (41834ms).
[11:14:00.406] <TB0> INFO: 1986940 events read in total (84688ms).
[11:14:42.826] <TB0> INFO: 2965585 events read in total (127107ms).
[11:15:24.908] <TB0> INFO: 3943785 events read in total (169189ms).
[11:15:34.530] <TB0> INFO: 4160000 events read in total (178811ms).
[11:15:34.562] <TB0> INFO: Test took 179691ms.
[11:16:03.993] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 185 (-1/-1) hits flags = 528 (plus default)
[11:16:04.003] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:16:04.003] <TB0> INFO: run 1 of 1
[11:16:04.292] <TB0> INFO: Expecting 3868800 events.
[11:16:47.991] <TB0> INFO: 989085 events read in total (43143ms).
[11:17:30.683] <TB0> INFO: 1972285 events read in total (85835ms).
[11:18:12.764] <TB0> INFO: 2943640 events read in total (127916ms).
[11:18:53.602] <TB0> INFO: 3868800 events read in total (168754ms).
[11:18:53.636] <TB0> INFO: Test took 169632ms.
[11:19:22.660] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 173 (-1/-1) hits flags = 528 (plus default)
[11:19:22.669] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:19:22.669] <TB0> INFO: run 1 of 1
[11:19:22.943] <TB0> INFO: Expecting 3619200 events.
[11:20:07.198] <TB0> INFO: 1023845 events read in total (43699ms).
[11:20:50.461] <TB0> INFO: 2037910 events read in total (86962ms).
[11:21:33.439] <TB0> INFO: 3041680 events read in total (129940ms).
[11:21:57.994] <TB0> INFO: 3619200 events read in total (154495ms).
[11:21:58.020] <TB0> INFO: Test took 155350ms.
[11:22:25.795] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 172 (-1/-1) hits flags = 528 (plus default)
[11:22:25.804] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:22:25.804] <TB0> INFO: run 1 of 1
[11:22:26.078] <TB0> INFO: Expecting 3598400 events.
[11:23:10.479] <TB0> INFO: 1026170 events read in total (43845ms).
[11:23:53.508] <TB0> INFO: 2042895 events read in total (86874ms).
[11:24:36.515] <TB0> INFO: 3049585 events read in total (129881ms).
[11:25:00.047] <TB0> INFO: 3598400 events read in total (153413ms).
[11:25:00.084] <TB0> INFO: Test took 154280ms.
[11:25:27.318] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 171 (-1/-1) hits flags = 528 (plus default)
[11:25:27.327] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[11:25:27.327] <TB0> INFO: run 1 of 1
[11:25:27.604] <TB0> INFO: Expecting 3577600 events.
[11:26:11.242] <TB0> INFO: 1029195 events read in total (43081ms).
[11:26:53.957] <TB0> INFO: 2048085 events read in total (85796ms).
[11:27:36.758] <TB0> INFO: 3057140 events read in total (128597ms).
[11:27:59.153] <TB0> INFO: 3577600 events read in total (150992ms).
[11:27:59.183] <TB0> INFO: Test took 151856ms.
[11:28:26.023] <TB0> INFO: PixTestTrim::trimBitTest() done
[11:28:26.024] <TB0> INFO: PixTestTrim::doTest() done, duration: 2981 seconds
[11:28:26.024] <TB0> INFO: Decoding statistics:
[11:28:26.024] <TB0> INFO: General information:
[11:28:26.024] <TB0> INFO: 16bit words read: 0
[11:28:26.024] <TB0> INFO: valid events total: 0
[11:28:26.024] <TB0> INFO: empty events: 0
[11:28:26.024] <TB0> INFO: valid events with pixels: 0
[11:28:26.024] <TB0> INFO: valid pixel hits: 0
[11:28:26.024] <TB0> INFO: Event errors: 0
[11:28:26.024] <TB0> INFO: start marker: 0
[11:28:26.024] <TB0> INFO: stop marker: 0
[11:28:26.024] <TB0> INFO: overflow: 0
[11:28:26.024] <TB0> INFO: invalid 5bit words: 0
[11:28:26.024] <TB0> INFO: invalid XOR eye diagram: 0
[11:28:26.024] <TB0> INFO: frame (failed synchr.): 0
[11:28:26.024] <TB0> INFO: idle data (no TBM trl): 0
[11:28:26.024] <TB0> INFO: no data (only TBM hdr): 0
[11:28:26.024] <TB0> INFO: TBM errors: 0
[11:28:26.024] <TB0> INFO: flawed TBM headers: 0
[11:28:26.024] <TB0> INFO: flawed TBM trailers: 0
[11:28:26.024] <TB0> INFO: event ID mismatches: 0
[11:28:26.024] <TB0> INFO: ROC errors: 0
[11:28:26.024] <TB0> INFO: missing ROC header(s): 0
[11:28:26.025] <TB0> INFO: misplaced readback start: 0
[11:28:26.025] <TB0> INFO: Pixel decoding errors: 0
[11:28:26.025] <TB0> INFO: pixel data incomplete: 0
[11:28:26.025] <TB0> INFO: pixel address: 0
[11:28:26.025] <TB0> INFO: pulse height fill bit: 0
[11:28:26.025] <TB0> INFO: buffer corruption: 0
[11:28:26.734] <TB0> INFO: ######################################################################
[11:28:26.734] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:28:26.734] <TB0> INFO: ######################################################################
[11:28:27.014] <TB0> INFO: Expecting 41600 events.
[11:28:30.796] <TB0> INFO: 41600 events read in total (3225ms).
[11:28:30.796] <TB0> INFO: Test took 4061ms.
[11:28:31.253] <TB0> INFO: Expecting 41600 events.
[11:28:35.014] <TB0> INFO: 41600 events read in total (3205ms).
[11:28:35.014] <TB0> INFO: Test took 4021ms.
[11:28:35.299] <TB0> INFO: Expecting 41600 events.
[11:28:39.049] <TB0> INFO: 41600 events read in total (3194ms).
[11:28:39.049] <TB0> INFO: Test took 4013ms.
[11:28:39.305] <TB0> INFO: Expecting 2560 events.
[11:28:40.157] <TB0> INFO: 2560 events read in total (296ms).
[11:28:40.157] <TB0> INFO: Test took 1094ms.
[11:28:40.462] <TB0> INFO: Expecting 2560 events.
[11:28:41.316] <TB0> INFO: 2560 events read in total (297ms).
[11:28:41.316] <TB0> INFO: Test took 1158ms.
[11:28:41.621] <TB0> INFO: Expecting 2560 events.
[11:28:42.473] <TB0> INFO: 2560 events read in total (296ms).
[11:28:42.473] <TB0> INFO: Test took 1156ms.
[11:28:42.778] <TB0> INFO: Expecting 2560 events.
[11:28:43.630] <TB0> INFO: 2560 events read in total (296ms).
[11:28:43.630] <TB0> INFO: Test took 1156ms.
[11:28:43.935] <TB0> INFO: Expecting 2560 events.
[11:28:44.789] <TB0> INFO: 2560 events read in total (298ms).
[11:28:44.789] <TB0> INFO: Test took 1158ms.
[11:28:45.094] <TB0> INFO: Expecting 2560 events.
[11:28:45.947] <TB0> INFO: 2560 events read in total (297ms).
[11:28:45.947] <TB0> INFO: Test took 1158ms.
[11:28:46.252] <TB0> INFO: Expecting 2560 events.
[11:28:47.103] <TB0> INFO: 2560 events read in total (295ms).
[11:28:47.103] <TB0> INFO: Test took 1156ms.
[11:28:47.408] <TB0> INFO: Expecting 2560 events.
[11:28:48.260] <TB0> INFO: 2560 events read in total (296ms).
[11:28:48.261] <TB0> INFO: Test took 1158ms.
[11:28:48.565] <TB0> INFO: Expecting 2560 events.
[11:28:49.418] <TB0> INFO: 2560 events read in total (296ms).
[11:28:49.418] <TB0> INFO: Test took 1157ms.
[11:28:49.723] <TB0> INFO: Expecting 2560 events.
[11:28:50.575] <TB0> INFO: 2560 events read in total (296ms).
[11:28:50.575] <TB0> INFO: Test took 1157ms.
[11:28:50.880] <TB0> INFO: Expecting 2560 events.
[11:28:51.733] <TB0> INFO: 2560 events read in total (297ms).
[11:28:51.733] <TB0> INFO: Test took 1158ms.
[11:28:52.038] <TB0> INFO: Expecting 2560 events.
[11:28:52.891] <TB0> INFO: 2560 events read in total (297ms).
[11:28:52.891] <TB0> INFO: Test took 1158ms.
[11:28:53.196] <TB0> INFO: Expecting 2560 events.
[11:28:54.048] <TB0> INFO: 2560 events read in total (296ms).
[11:28:54.048] <TB0> INFO: Test took 1157ms.
[11:28:54.353] <TB0> INFO: Expecting 2560 events.
[11:28:55.205] <TB0> INFO: 2560 events read in total (297ms).
[11:28:55.206] <TB0> INFO: Test took 1158ms.
[11:28:55.511] <TB0> INFO: Expecting 2560 events.
[11:28:56.364] <TB0> INFO: 2560 events read in total (297ms).
[11:28:56.364] <TB0> INFO: Test took 1158ms.
[11:28:56.669] <TB0> INFO: Expecting 2560 events.
[11:28:57.521] <TB0> INFO: 2560 events read in total (296ms).
[11:28:57.521] <TB0> INFO: Test took 1157ms.
[11:28:57.524] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:28:57.827] <TB0> INFO: Expecting 655360 events.
[11:29:14.744] <TB0> INFO: 655360 events read in total (16361ms).
[11:29:14.753] <TB0> INFO: Expecting 655360 events.
[11:29:31.327] <TB0> INFO: 655360 events read in total (16173ms).
[11:29:31.339] <TB0> INFO: Expecting 655360 events.
[11:29:48.016] <TB0> INFO: 655360 events read in total (16275ms).
[11:29:48.031] <TB0> INFO: Expecting 655360 events.
[11:30:04.723] <TB0> INFO: 655360 events read in total (16290ms).
[11:30:04.743] <TB0> INFO: Expecting 655360 events.
[11:30:21.431] <TB0> INFO: 655360 events read in total (16287ms).
[11:30:21.453] <TB0> INFO: Expecting 655360 events.
[11:30:38.085] <TB0> INFO: 655360 events read in total (16230ms).
[11:30:38.111] <TB0> INFO: Expecting 655360 events.
[11:30:54.906] <TB0> INFO: 655360 events read in total (16393ms).
[11:30:54.939] <TB0> INFO: Expecting 655360 events.
[11:31:11.585] <TB0> INFO: 655360 events read in total (16244ms).
[11:31:11.620] <TB0> INFO: Expecting 655360 events.
[11:31:28.265] <TB0> INFO: 655360 events read in total (16244ms).
[11:31:28.301] <TB0> INFO: Expecting 655360 events.
[11:31:44.944] <TB0> INFO: 655360 events read in total (16241ms).
[11:31:44.983] <TB0> INFO: Expecting 655360 events.
[11:32:01.508] <TB0> INFO: 655360 events read in total (16124ms).
[11:32:01.553] <TB0> INFO: Expecting 655360 events.
[11:32:18.300] <TB0> INFO: 655360 events read in total (16345ms).
[11:32:18.351] <TB0> INFO: Expecting 655360 events.
[11:32:34.997] <TB0> INFO: 655360 events read in total (16244ms).
[11:32:35.049] <TB0> INFO: Expecting 655360 events.
[11:32:51.620] <TB0> INFO: 655360 events read in total (16170ms).
[11:32:51.673] <TB0> INFO: Expecting 655360 events.
[11:33:08.337] <TB0> INFO: 655360 events read in total (16262ms).
[11:33:08.399] <TB0> INFO: Expecting 655360 events.
[11:33:24.982] <TB0> INFO: 655360 events read in total (16182ms).
[11:33:25.046] <TB0> INFO: Test took 267523ms.
[11:33:25.129] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:33:25.359] <TB0> INFO: Expecting 655360 events.
[11:33:42.230] <TB0> INFO: 655360 events read in total (16315ms).
[11:33:42.240] <TB0> INFO: Expecting 655360 events.
[11:33:59.091] <TB0> INFO: 655360 events read in total (16449ms).
[11:33:59.105] <TB0> INFO: Expecting 655360 events.
[11:34:15.780] <TB0> INFO: 655360 events read in total (16273ms).
[11:34:15.796] <TB0> INFO: Expecting 655360 events.
[11:34:32.387] <TB0> INFO: 655360 events read in total (16189ms).
[11:34:32.406] <TB0> INFO: Expecting 655360 events.
[11:34:48.888] <TB0> INFO: 655360 events read in total (16081ms).
[11:34:48.910] <TB0> INFO: Expecting 655360 events.
[11:35:05.485] <TB0> INFO: 655360 events read in total (16173ms).
[11:35:05.512] <TB0> INFO: Expecting 655360 events.
[11:35:22.001] <TB0> INFO: 655360 events read in total (16088ms).
[11:35:22.030] <TB0> INFO: Expecting 655360 events.
[11:35:38.647] <TB0> INFO: 655360 events read in total (16215ms).
[11:35:38.678] <TB0> INFO: Expecting 655360 events.
[11:35:55.104] <TB0> INFO: 655360 events read in total (16024ms).
[11:35:55.140] <TB0> INFO: Expecting 655360 events.
[11:36:11.769] <TB0> INFO: 655360 events read in total (16228ms).
[11:36:11.810] <TB0> INFO: Expecting 655360 events.
[11:36:28.603] <TB0> INFO: 655360 events read in total (16391ms).
[11:36:28.648] <TB0> INFO: Expecting 655360 events.
[11:36:45.420] <TB0> INFO: 655360 events read in total (16370ms).
[11:36:45.470] <TB0> INFO: Expecting 655360 events.
[11:37:01.858] <TB0> INFO: 655360 events read in total (15986ms).
[11:37:01.906] <TB0> INFO: Expecting 655360 events.
[11:37:18.168] <TB0> INFO: 655360 events read in total (15860ms).
[11:37:18.221] <TB0> INFO: Expecting 655360 events.
[11:37:34.632] <TB0> INFO: 655360 events read in total (16010ms).
[11:37:34.687] <TB0> INFO: Expecting 655360 events.
[11:37:51.304] <TB0> INFO: 655360 events read in total (16215ms).
[11:37:51.371] <TB0> INFO: Test took 266242ms.
[11:37:51.574] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:51.582] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:51.589] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:51.597] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:51.604] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:51.611] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:51.618] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:51.624] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:51.631] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:51.637] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:51.644] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:51.650] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:51.657] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[11:37:51.663] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[11:37:51.670] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[11:37:51.676] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:51.683] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:51.690] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:51.696] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[11:37:51.703] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[11:37:51.738] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C0.dat
[11:37:51.739] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C1.dat
[11:37:51.739] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C2.dat
[11:37:51.739] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C3.dat
[11:37:51.739] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C4.dat
[11:37:51.739] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C5.dat
[11:37:51.739] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C6.dat
[11:37:51.739] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C7.dat
[11:37:51.739] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C8.dat
[11:37:51.740] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C9.dat
[11:37:51.740] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C10.dat
[11:37:51.740] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C11.dat
[11:37:51.740] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C12.dat
[11:37:51.740] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C13.dat
[11:37:51.740] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C14.dat
[11:37:51.741] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//dacParameters35_C15.dat
[11:37:52.018] <TB0> INFO: Expecting 41600 events.
[11:37:55.512] <TB0> INFO: 41600 events read in total (2938ms).
[11:37:55.512] <TB0> INFO: Test took 3769ms.
[11:37:55.961] <TB0> INFO: Expecting 41600 events.
[11:37:59.431] <TB0> INFO: 41600 events read in total (2914ms).
[11:37:59.432] <TB0> INFO: Test took 3743ms.
[11:37:59.901] <TB0> INFO: Expecting 41600 events.
[11:38:03.394] <TB0> INFO: 41600 events read in total (2937ms).
[11:38:03.395] <TB0> INFO: Test took 3777ms.
[11:38:03.575] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:03.663] <TB0> INFO: Expecting 2560 events.
[11:38:04.516] <TB0> INFO: 2560 events read in total (297ms).
[11:38:04.516] <TB0> INFO: Test took 941ms.
[11:38:04.519] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:04.821] <TB0> INFO: Expecting 2560 events.
[11:38:05.675] <TB0> INFO: 2560 events read in total (297ms).
[11:38:05.675] <TB0> INFO: Test took 1156ms.
[11:38:05.678] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:05.980] <TB0> INFO: Expecting 2560 events.
[11:38:06.838] <TB0> INFO: 2560 events read in total (302ms).
[11:38:06.838] <TB0> INFO: Test took 1160ms.
[11:38:06.839] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:07.143] <TB0> INFO: Expecting 2560 events.
[11:38:07.998] <TB0> INFO: 2560 events read in total (298ms).
[11:38:07.998] <TB0> INFO: Test took 1159ms.
[11:38:08.000] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:08.304] <TB0> INFO: Expecting 2560 events.
[11:38:09.162] <TB0> INFO: 2560 events read in total (302ms).
[11:38:09.163] <TB0> INFO: Test took 1163ms.
[11:38:09.165] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:09.467] <TB0> INFO: Expecting 2560 events.
[11:38:10.324] <TB0> INFO: 2560 events read in total (301ms).
[11:38:10.325] <TB0> INFO: Test took 1160ms.
[11:38:10.327] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:10.630] <TB0> INFO: Expecting 2560 events.
[11:38:11.485] <TB0> INFO: 2560 events read in total (299ms).
[11:38:11.485] <TB0> INFO: Test took 1158ms.
[11:38:11.487] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:11.792] <TB0> INFO: Expecting 2560 events.
[11:38:12.646] <TB0> INFO: 2560 events read in total (298ms).
[11:38:12.646] <TB0> INFO: Test took 1159ms.
[11:38:12.649] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:12.952] <TB0> INFO: Expecting 2560 events.
[11:38:13.811] <TB0> INFO: 2560 events read in total (303ms).
[11:38:13.812] <TB0> INFO: Test took 1164ms.
[11:38:13.815] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:14.117] <TB0> INFO: Expecting 2560 events.
[11:38:14.976] <TB0> INFO: 2560 events read in total (302ms).
[11:38:14.976] <TB0> INFO: Test took 1162ms.
[11:38:14.978] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:15.283] <TB0> INFO: Expecting 2560 events.
[11:38:16.137] <TB0> INFO: 2560 events read in total (298ms).
[11:38:16.137] <TB0> INFO: Test took 1159ms.
[11:38:16.138] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:16.442] <TB0> INFO: Expecting 2560 events.
[11:38:17.295] <TB0> INFO: 2560 events read in total (297ms).
[11:38:17.295] <TB0> INFO: Test took 1157ms.
[11:38:17.296] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:17.601] <TB0> INFO: Expecting 2560 events.
[11:38:18.461] <TB0> INFO: 2560 events read in total (304ms).
[11:38:18.461] <TB0> INFO: Test took 1165ms.
[11:38:18.463] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:18.766] <TB0> INFO: Expecting 2560 events.
[11:38:19.619] <TB0> INFO: 2560 events read in total (297ms).
[11:38:19.619] <TB0> INFO: Test took 1156ms.
[11:38:19.620] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:19.924] <TB0> INFO: Expecting 2560 events.
[11:38:20.777] <TB0> INFO: 2560 events read in total (296ms).
[11:38:20.777] <TB0> INFO: Test took 1157ms.
[11:38:20.779] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:21.082] <TB0> INFO: Expecting 2560 events.
[11:38:21.937] <TB0> INFO: 2560 events read in total (298ms).
[11:38:21.937] <TB0> INFO: Test took 1158ms.
[11:38:21.940] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:22.242] <TB0> INFO: Expecting 2560 events.
[11:38:23.095] <TB0> INFO: 2560 events read in total (297ms).
[11:38:23.095] <TB0> INFO: Test took 1155ms.
[11:38:23.098] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:23.400] <TB0> INFO: Expecting 2560 events.
[11:38:24.259] <TB0> INFO: 2560 events read in total (303ms).
[11:38:24.259] <TB0> INFO: Test took 1162ms.
[11:38:24.261] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:24.565] <TB0> INFO: Expecting 2560 events.
[11:38:25.418] <TB0> INFO: 2560 events read in total (297ms).
[11:38:25.419] <TB0> INFO: Test took 1158ms.
[11:38:25.420] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:25.724] <TB0> INFO: Expecting 2560 events.
[11:38:26.577] <TB0> INFO: 2560 events read in total (296ms).
[11:38:26.578] <TB0> INFO: Test took 1158ms.
[11:38:26.579] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:26.884] <TB0> INFO: Expecting 2560 events.
[11:38:27.742] <TB0> INFO: 2560 events read in total (302ms).
[11:38:27.742] <TB0> INFO: Test took 1163ms.
[11:38:27.745] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:28.048] <TB0> INFO: Expecting 2560 events.
[11:38:28.902] <TB0> INFO: 2560 events read in total (298ms).
[11:38:28.902] <TB0> INFO: Test took 1157ms.
[11:38:28.904] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:29.207] <TB0> INFO: Expecting 2560 events.
[11:38:30.063] <TB0> INFO: 2560 events read in total (299ms).
[11:38:30.063] <TB0> INFO: Test took 1159ms.
[11:38:30.065] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:30.369] <TB0> INFO: Expecting 2560 events.
[11:38:31.224] <TB0> INFO: 2560 events read in total (298ms).
[11:38:31.225] <TB0> INFO: Test took 1160ms.
[11:38:31.227] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:31.530] <TB0> INFO: Expecting 2560 events.
[11:38:32.383] <TB0> INFO: 2560 events read in total (296ms).
[11:38:32.383] <TB0> INFO: Test took 1156ms.
[11:38:32.385] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:32.689] <TB0> INFO: Expecting 2560 events.
[11:38:33.543] <TB0> INFO: 2560 events read in total (298ms).
[11:38:33.543] <TB0> INFO: Test took 1158ms.
[11:38:33.546] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:33.849] <TB0> INFO: Expecting 2560 events.
[11:38:34.703] <TB0> INFO: 2560 events read in total (298ms).
[11:38:34.703] <TB0> INFO: Test took 1158ms.
[11:38:34.705] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:35.009] <TB0> INFO: Expecting 2560 events.
[11:38:35.863] <TB0> INFO: 2560 events read in total (298ms).
[11:38:35.863] <TB0> INFO: Test took 1158ms.
[11:38:35.865] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:36.169] <TB0> INFO: Expecting 2560 events.
[11:38:37.028] <TB0> INFO: 2560 events read in total (303ms).
[11:38:37.028] <TB0> INFO: Test took 1163ms.
[11:38:37.031] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:37.333] <TB0> INFO: Expecting 2560 events.
[11:38:38.186] <TB0> INFO: 2560 events read in total (297ms).
[11:38:38.187] <TB0> INFO: Test took 1157ms.
[11:38:38.190] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:38.492] <TB0> INFO: Expecting 2560 events.
[11:38:39.345] <TB0> INFO: 2560 events read in total (297ms).
[11:38:39.345] <TB0> INFO: Test took 1156ms.
[11:38:39.346] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:38:39.651] <TB0> INFO: Expecting 2560 events.
[11:38:40.505] <TB0> INFO: 2560 events read in total (298ms).
[11:38:40.505] <TB0> INFO: Test took 1159ms.
[11:38:40.966] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 614 seconds
[11:38:40.966] <TB0> INFO: PH scale (per ROC): 70 76 74 76 68 58 74 75 75 79 78 72 70 64 79 69
[11:38:40.966] <TB0> INFO: PH offset (per ROC): 197 186 173 166 173 189 166 160 166 170 171 176 173 179 187 157
[11:38:40.970] <TB0> INFO: Decoding statistics:
[11:38:40.970] <TB0> INFO: General information:
[11:38:40.970] <TB0> INFO: 16bit words read: 91948
[11:38:40.970] <TB0> INFO: valid events total: 10240
[11:38:40.970] <TB0> INFO: empty events: 7733
[11:38:40.970] <TB0> INFO: valid events with pixels: 2507
[11:38:40.970] <TB0> INFO: valid pixel hits: 2507
[11:38:40.970] <TB0> INFO: Event errors: 0
[11:38:40.970] <TB0> INFO: start marker: 0
[11:38:40.970] <TB0> INFO: stop marker: 0
[11:38:40.970] <TB0> INFO: overflow: 0
[11:38:40.970] <TB0> INFO: invalid 5bit words: 0
[11:38:40.970] <TB0> INFO: invalid XOR eye diagram: 0
[11:38:40.970] <TB0> INFO: frame (failed synchr.): 0
[11:38:40.970] <TB0> INFO: idle data (no TBM trl): 0
[11:38:40.970] <TB0> INFO: no data (only TBM hdr): 0
[11:38:40.970] <TB0> INFO: TBM errors: 0
[11:38:40.970] <TB0> INFO: flawed TBM headers: 0
[11:38:40.970] <TB0> INFO: flawed TBM trailers: 0
[11:38:40.970] <TB0> INFO: event ID mismatches: 0
[11:38:40.970] <TB0> INFO: ROC errors: 0
[11:38:40.970] <TB0> INFO: missing ROC header(s): 0
[11:38:40.970] <TB0> INFO: misplaced readback start: 0
[11:38:40.970] <TB0> INFO: Pixel decoding errors: 0
[11:38:40.970] <TB0> INFO: pixel data incomplete: 0
[11:38:40.970] <TB0> INFO: pixel address: 0
[11:38:40.970] <TB0> INFO: pulse height fill bit: 0
[11:38:40.970] <TB0> INFO: buffer corruption: 0
[11:38:41.145] <TB0> INFO: ######################################################################
[11:38:41.145] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:38:41.145] <TB0> INFO: ######################################################################
[11:38:41.154] <TB0> INFO: scanning low vcal = 10
[11:38:41.417] <TB0> INFO: Expecting 41600 events.
[11:38:44.942] <TB0> INFO: 41600 events read in total (2969ms).
[11:38:44.942] <TB0> INFO: Test took 3788ms.
[11:38:44.943] <TB0> INFO: scanning low vcal = 20
[11:38:45.247] <TB0> INFO: Expecting 41600 events.
[11:38:48.786] <TB0> INFO: 41600 events read in total (2983ms).
[11:38:48.786] <TB0> INFO: Test took 3843ms.
[11:38:48.787] <TB0> INFO: scanning low vcal = 30
[11:38:49.091] <TB0> INFO: Expecting 41600 events.
[11:38:52.647] <TB0> INFO: 41600 events read in total (3000ms).
[11:38:52.648] <TB0> INFO: Test took 3860ms.
[11:38:52.650] <TB0> INFO: scanning low vcal = 40
[11:38:52.943] <TB0> INFO: Expecting 41600 events.
[11:38:57.070] <TB0> INFO: 41600 events read in total (3571ms).
[11:38:57.071] <TB0> INFO: Test took 4421ms.
[11:38:57.073] <TB0> INFO: scanning low vcal = 50
[11:38:57.339] <TB0> INFO: Expecting 41600 events.
[11:39:01.538] <TB0> INFO: 41600 events read in total (3643ms).
[11:39:01.538] <TB0> INFO: Test took 4465ms.
[11:39:01.541] <TB0> INFO: scanning low vcal = 60
[11:39:01.803] <TB0> INFO: Expecting 41600 events.
[11:39:05.991] <TB0> INFO: 41600 events read in total (3632ms).
[11:39:05.992] <TB0> INFO: Test took 4451ms.
[11:39:05.994] <TB0> INFO: scanning low vcal = 70
[11:39:06.264] <TB0> INFO: Expecting 41600 events.
[11:39:10.489] <TB0> INFO: 41600 events read in total (3669ms).
[11:39:10.489] <TB0> INFO: Test took 4495ms.
[11:39:10.492] <TB0> INFO: scanning low vcal = 80
[11:39:10.761] <TB0> INFO: Expecting 41600 events.
[11:39:14.972] <TB0> INFO: 41600 events read in total (3655ms).
[11:39:14.972] <TB0> INFO: Test took 4480ms.
[11:39:14.975] <TB0> INFO: scanning low vcal = 90
[11:39:15.247] <TB0> INFO: Expecting 41600 events.
[11:39:19.437] <TB0> INFO: 41600 events read in total (3634ms).
[11:39:19.438] <TB0> INFO: Test took 4463ms.
[11:39:19.441] <TB0> INFO: scanning low vcal = 100
[11:39:19.701] <TB0> INFO: Expecting 41600 events.
[11:39:23.893] <TB0> INFO: 41600 events read in total (3636ms).
[11:39:23.893] <TB0> INFO: Test took 4452ms.
[11:39:23.896] <TB0> INFO: scanning low vcal = 110
[11:39:24.165] <TB0> INFO: Expecting 41600 events.
[11:39:28.357] <TB0> INFO: 41600 events read in total (3636ms).
[11:39:28.358] <TB0> INFO: Test took 4462ms.
[11:39:28.360] <TB0> INFO: scanning low vcal = 120
[11:39:28.622] <TB0> INFO: Expecting 41600 events.
[11:39:32.831] <TB0> INFO: 41600 events read in total (3653ms).
[11:39:32.831] <TB0> INFO: Test took 4471ms.
[11:39:32.848] <TB0> INFO: scanning low vcal = 130
[11:39:33.111] <TB0> INFO: Expecting 41600 events.
[11:39:37.328] <TB0> INFO: 41600 events read in total (3661ms).
[11:39:37.328] <TB0> INFO: Test took 4480ms.
[11:39:37.330] <TB0> INFO: scanning low vcal = 140
[11:39:37.593] <TB0> INFO: Expecting 41600 events.
[11:39:41.799] <TB0> INFO: 41600 events read in total (3650ms).
[11:39:41.800] <TB0> INFO: Test took 4470ms.
[11:39:41.802] <TB0> INFO: scanning low vcal = 150
[11:39:42.064] <TB0> INFO: Expecting 41600 events.
[11:39:46.285] <TB0> INFO: 41600 events read in total (3664ms).
[11:39:46.286] <TB0> INFO: Test took 4484ms.
[11:39:46.288] <TB0> INFO: scanning low vcal = 160
[11:39:46.550] <TB0> INFO: Expecting 41600 events.
[11:39:50.726] <TB0> INFO: 41600 events read in total (3620ms).
[11:39:50.727] <TB0> INFO: Test took 4439ms.
[11:39:50.729] <TB0> INFO: scanning low vcal = 170
[11:39:50.992] <TB0> INFO: Expecting 41600 events.
[11:39:55.237] <TB0> INFO: 41600 events read in total (3689ms).
[11:39:55.237] <TB0> INFO: Test took 4507ms.
[11:39:55.241] <TB0> INFO: scanning low vcal = 180
[11:39:55.529] <TB0> INFO: Expecting 41600 events.
[11:39:59.705] <TB0> INFO: 41600 events read in total (3620ms).
[11:39:59.706] <TB0> INFO: Test took 4465ms.
[11:39:59.709] <TB0> INFO: scanning low vcal = 190
[11:39:59.968] <TB0> INFO: Expecting 41600 events.
[11:40:04.161] <TB0> INFO: 41600 events read in total (3637ms).
[11:40:04.162] <TB0> INFO: Test took 4453ms.
[11:40:04.164] <TB0> INFO: scanning low vcal = 200
[11:40:04.424] <TB0> INFO: Expecting 41600 events.
[11:40:08.624] <TB0> INFO: 41600 events read in total (3643ms).
[11:40:08.624] <TB0> INFO: Test took 4460ms.
[11:40:08.627] <TB0> INFO: scanning low vcal = 210
[11:40:08.889] <TB0> INFO: Expecting 41600 events.
[11:40:13.101] <TB0> INFO: 41600 events read in total (3655ms).
[11:40:13.101] <TB0> INFO: Test took 4474ms.
[11:40:13.104] <TB0> INFO: scanning low vcal = 220
[11:40:13.377] <TB0> INFO: Expecting 41600 events.
[11:40:17.591] <TB0> INFO: 41600 events read in total (3658ms).
[11:40:17.592] <TB0> INFO: Test took 4488ms.
[11:40:17.594] <TB0> INFO: scanning low vcal = 230
[11:40:17.854] <TB0> INFO: Expecting 41600 events.
[11:40:22.073] <TB0> INFO: 41600 events read in total (3663ms).
[11:40:22.074] <TB0> INFO: Test took 4480ms.
[11:40:22.077] <TB0> INFO: scanning low vcal = 240
[11:40:22.343] <TB0> INFO: Expecting 41600 events.
[11:40:26.565] <TB0> INFO: 41600 events read in total (3666ms).
[11:40:26.565] <TB0> INFO: Test took 4488ms.
[11:40:26.568] <TB0> INFO: scanning low vcal = 250
[11:40:26.855] <TB0> INFO: Expecting 41600 events.
[11:40:31.080] <TB0> INFO: 41600 events read in total (3669ms).
[11:40:31.081] <TB0> INFO: Test took 4513ms.
[11:40:31.084] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[11:40:31.347] <TB0> INFO: Expecting 41600 events.
[11:40:35.581] <TB0> INFO: 41600 events read in total (3678ms).
[11:40:35.582] <TB0> INFO: Test took 4498ms.
[11:40:35.585] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[11:40:35.863] <TB0> INFO: Expecting 41600 events.
[11:40:40.058] <TB0> INFO: 41600 events read in total (3639ms).
[11:40:40.059] <TB0> INFO: Test took 4474ms.
[11:40:40.061] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[11:40:40.324] <TB0> INFO: Expecting 41600 events.
[11:40:44.546] <TB0> INFO: 41600 events read in total (3666ms).
[11:40:44.547] <TB0> INFO: Test took 4485ms.
[11:40:44.549] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[11:40:44.813] <TB0> INFO: Expecting 41600 events.
[11:40:49.000] <TB0> INFO: 41600 events read in total (3631ms).
[11:40:49.001] <TB0> INFO: Test took 4452ms.
[11:40:49.003] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:40:49.263] <TB0> INFO: Expecting 41600 events.
[11:40:53.438] <TB0> INFO: 41600 events read in total (3619ms).
[11:40:53.439] <TB0> INFO: Test took 4436ms.
[11:40:53.881] <TB0> INFO: PixTestGainPedestal::measure() done
[11:41:26.916] <TB0> INFO: PixTestGainPedestal::fit() done
[11:41:26.916] <TB0> INFO: non-linearity mean: 0.959 0.962 0.957 0.957 0.952 0.947 0.955 0.959 0.958 0.950 0.951 0.950 0.958 0.953 0.960 0.954
[11:41:26.916] <TB0> INFO: non-linearity RMS: 0.007 0.006 0.007 0.006 0.007 0.008 0.007 0.005 0.006 0.007 0.006 0.006 0.006 0.008 0.007 0.006
[11:41:26.917] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[11:41:26.935] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[11:41:26.953] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[11:41:26.972] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[11:41:26.990] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[11:41:27.008] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[11:41:27.026] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[11:41:27.045] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[11:41:27.063] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[11:41:27.081] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[11:41:27.100] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[11:41:27.118] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[11:41:27.136] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[11:41:27.154] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[11:41:27.172] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[11:41:27.191] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[11:41:27.209] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 166 seconds
[11:41:27.209] <TB0> INFO: Decoding statistics:
[11:41:27.209] <TB0> INFO: General information:
[11:41:27.209] <TB0> INFO: 16bit words read: 2662400
[11:41:27.209] <TB0> INFO: valid events total: 166400
[11:41:27.209] <TB0> INFO: empty events: 0
[11:41:27.209] <TB0> INFO: valid events with pixels: 166400
[11:41:27.209] <TB0> INFO: valid pixel hits: 665588
[11:41:27.209] <TB0> INFO: Event errors: 0
[11:41:27.209] <TB0> INFO: start marker: 0
[11:41:27.209] <TB0> INFO: stop marker: 0
[11:41:27.209] <TB0> INFO: overflow: 0
[11:41:27.209] <TB0> INFO: invalid 5bit words: 0
[11:41:27.209] <TB0> INFO: invalid XOR eye diagram: 0
[11:41:27.209] <TB0> INFO: frame (failed synchr.): 0
[11:41:27.209] <TB0> INFO: idle data (no TBM trl): 0
[11:41:27.209] <TB0> INFO: no data (only TBM hdr): 0
[11:41:27.209] <TB0> INFO: TBM errors: 0
[11:41:27.209] <TB0> INFO: flawed TBM headers: 0
[11:41:27.209] <TB0> INFO: flawed TBM trailers: 0
[11:41:27.209] <TB0> INFO: event ID mismatches: 0
[11:41:27.209] <TB0> INFO: ROC errors: 0
[11:41:27.209] <TB0> INFO: missing ROC header(s): 0
[11:41:27.209] <TB0> INFO: misplaced readback start: 0
[11:41:27.209] <TB0> INFO: Pixel decoding errors: 0
[11:41:27.210] <TB0> INFO: pixel data incomplete: 0
[11:41:27.210] <TB0> INFO: pixel address: 0
[11:41:27.210] <TB0> INFO: pulse height fill bit: 0
[11:41:27.210] <TB0> INFO: buffer corruption: 0
[11:41:27.218] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C15.dat
[11:41:27.219] <TB0> INFO: ######################################################################
[11:41:27.219] <TB0> INFO: PixTestReadback::doTest()
[11:41:27.219] <TB0> INFO: ######################################################################
[11:41:27.219] <TB0> INFO: ----------------------------------------------------------------------
[11:41:27.219] <TB0> INFO: PixTestReadback::CalibrateVd()
[11:41:27.219] <TB0> INFO: ----------------------------------------------------------------------
[11:41:36.648] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C0.dat
[11:41:36.648] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C1.dat
[11:41:36.648] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C2.dat
[11:41:36.649] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C3.dat
[11:41:36.649] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C4.dat
[11:41:36.649] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C5.dat
[11:41:36.649] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C6.dat
[11:41:36.649] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C7.dat
[11:41:36.649] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C8.dat
[11:41:36.649] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C9.dat
[11:41:36.649] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C10.dat
[11:41:36.649] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C11.dat
[11:41:36.649] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C12.dat
[11:41:36.649] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C13.dat
[11:41:36.650] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C14.dat
[11:41:36.650] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C15.dat
[11:41:36.679] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[11:41:36.679] <TB0> INFO: ----------------------------------------------------------------------
[11:41:36.679] <TB0> INFO: PixTestReadback::CalibrateVa()
[11:41:36.679] <TB0> INFO: ----------------------------------------------------------------------
[11:41:46.111] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C0.dat
[11:41:46.111] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C1.dat
[11:41:46.111] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C2.dat
[11:41:46.111] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C3.dat
[11:41:46.111] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C4.dat
[11:41:46.111] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C5.dat
[11:41:46.111] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C6.dat
[11:41:46.112] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C7.dat
[11:41:46.112] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C8.dat
[11:41:46.112] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C9.dat
[11:41:46.112] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C10.dat
[11:41:46.112] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C11.dat
[11:41:46.112] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C12.dat
[11:41:46.112] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C13.dat
[11:41:46.112] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C14.dat
[11:41:46.112] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C15.dat
[11:41:46.135] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[11:41:46.135] <TB0> INFO: ----------------------------------------------------------------------
[11:41:46.135] <TB0> INFO: PixTestReadback::readbackVbg()
[11:41:46.135] <TB0> INFO: ----------------------------------------------------------------------
[11:41:53.441] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[11:41:53.441] <TB0> INFO: ----------------------------------------------------------------------
[11:41:53.441] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[11:41:53.441] <TB0> INFO: ----------------------------------------------------------------------
[11:41:53.441] <TB0> INFO: Vbg will be calibrated using Vd calibration
[11:41:53.441] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 146.5calibrated Vbg = 1.22928 :::*/*/*/*/
[11:41:53.441] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 154.4calibrated Vbg = 1.22926 :::*/*/*/*/
[11:41:53.441] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 148.4calibrated Vbg = 1.23385 :::*/*/*/*/
[11:41:53.441] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 152.5calibrated Vbg = 1.23825 :::*/*/*/*/
[11:41:53.441] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 152.2calibrated Vbg = 1.23655 :::*/*/*/*/
[11:41:53.441] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 149.2calibrated Vbg = 1.24339 :::*/*/*/*/
[11:41:53.441] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 150.4calibrated Vbg = 1.24024 :::*/*/*/*/
[11:41:53.441] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 151.7calibrated Vbg = 1.23692 :::*/*/*/*/
[11:41:53.441] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 139calibrated Vbg = 1.24714 :::*/*/*/*/
[11:41:53.441] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 152.1calibrated Vbg = 1.247 :::*/*/*/*/
[11:41:53.441] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 152.1calibrated Vbg = 1.23613 :::*/*/*/*/
[11:41:53.441] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 152.7calibrated Vbg = 1.23688 :::*/*/*/*/
[11:41:53.441] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 150.4calibrated Vbg = 1.2315 :::*/*/*/*/
[11:41:53.441] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 154calibrated Vbg = 1.23271 :::*/*/*/*/
[11:41:53.441] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 151calibrated Vbg = 1.23648 :::*/*/*/*/
[11:41:53.441] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 142.3calibrated Vbg = 1.23818 :::*/*/*/*/
[11:41:53.443] <TB0> INFO: ----------------------------------------------------------------------
[11:41:53.443] <TB0> INFO: PixTestReadback::CalibrateIa()
[11:41:53.443] <TB0> INFO: ----------------------------------------------------------------------
[11:44:28.856] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C0.dat
[11:44:28.856] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C1.dat
[11:44:28.856] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C2.dat
[11:44:28.856] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C3.dat
[11:44:28.856] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C4.dat
[11:44:28.856] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C5.dat
[11:44:28.856] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C6.dat
[11:44:28.856] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C7.dat
[11:44:28.856] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C8.dat
[11:44:28.856] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C9.dat
[11:44:28.856] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C10.dat
[11:44:28.856] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C11.dat
[11:44:28.856] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C12.dat
[11:44:28.857] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C13.dat
[11:44:28.857] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C14.dat
[11:44:28.857] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2271_FullQualification_2016-07-18_07h31m_1468819860//004_FulltestPxar_p17//readbackCal_C15.dat
[11:44:28.888] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[11:44:28.889] <TB0> INFO: PixTestReadback::doTest() done
[11:44:28.889] <TB0> INFO: Decoding statistics:
[11:44:28.889] <TB0> INFO: General information:
[11:44:28.889] <TB0> INFO: 16bit words read: 1024
[11:44:28.889] <TB0> INFO: valid events total: 128
[11:44:28.889] <TB0> INFO: empty events: 128
[11:44:28.889] <TB0> INFO: valid events with pixels: 0
[11:44:28.889] <TB0> INFO: valid pixel hits: 0
[11:44:28.889] <TB0> INFO: Event errors: 0
[11:44:28.889] <TB0> INFO: start marker: 0
[11:44:28.889] <TB0> INFO: stop marker: 0
[11:44:28.889] <TB0> INFO: overflow: 0
[11:44:28.889] <TB0> INFO: invalid 5bit words: 0
[11:44:28.889] <TB0> INFO: invalid XOR eye diagram: 0
[11:44:28.889] <TB0> INFO: frame (failed synchr.): 0
[11:44:28.889] <TB0> INFO: idle data (no TBM trl): 0
[11:44:28.889] <TB0> INFO: no data (only TBM hdr): 0
[11:44:28.889] <TB0> INFO: TBM errors: 0
[11:44:28.889] <TB0> INFO: flawed TBM headers: 0
[11:44:28.889] <TB0> INFO: flawed TBM trailers: 0
[11:44:28.889] <TB0> INFO: event ID mismatches: 0
[11:44:28.889] <TB0> INFO: ROC errors: 0
[11:44:28.889] <TB0> INFO: missing ROC header(s): 0
[11:44:28.889] <TB0> INFO: misplaced readback start: 0
[11:44:28.889] <TB0> INFO: Pixel decoding errors: 0
[11:44:28.889] <TB0> INFO: pixel data incomplete: 0
[11:44:28.889] <TB0> INFO: pixel address: 0
[11:44:28.889] <TB0> INFO: pulse height fill bit: 0
[11:44:28.889] <TB0> INFO: buffer corruption: 0
[11:44:28.908] <TB0> INFO: Decoding statistics:
[11:44:28.908] <TB0> INFO: General information:
[11:44:28.908] <TB0> INFO: 16bit words read: 2755372
[11:44:28.908] <TB0> INFO: valid events total: 176768
[11:44:28.908] <TB0> INFO: empty events: 7861
[11:44:28.908] <TB0> INFO: valid events with pixels: 168907
[11:44:28.908] <TB0> INFO: valid pixel hits: 668095
[11:44:28.908] <TB0> INFO: Event errors: 0
[11:44:28.908] <TB0> INFO: start marker: 0
[11:44:28.908] <TB0> INFO: stop marker: 0
[11:44:28.908] <TB0> INFO: overflow: 0
[11:44:28.908] <TB0> INFO: invalid 5bit words: 0
[11:44:28.908] <TB0> INFO: invalid XOR eye diagram: 0
[11:44:28.908] <TB0> INFO: frame (failed synchr.): 0
[11:44:28.908] <TB0> INFO: idle data (no TBM trl): 0
[11:44:28.908] <TB0> INFO: no data (only TBM hdr): 0
[11:44:28.908] <TB0> INFO: TBM errors: 0
[11:44:28.908] <TB0> INFO: flawed TBM headers: 0
[11:44:28.908] <TB0> INFO: flawed TBM trailers: 0
[11:44:28.908] <TB0> INFO: event ID mismatches: 0
[11:44:28.908] <TB0> INFO: ROC errors: 0
[11:44:28.908] <TB0> INFO: missing ROC header(s): 0
[11:44:28.908] <TB0> INFO: misplaced readback start: 0
[11:44:28.908] <TB0> INFO: Pixel decoding errors: 0
[11:44:28.908] <TB0> INFO: pixel data incomplete: 0
[11:44:28.908] <TB0> INFO: pixel address: 0
[11:44:28.908] <TB0> INFO: pulse height fill bit: 0
[11:44:28.908] <TB0> INFO: buffer corruption: 0
[11:44:28.908] <TB0> INFO: enter test to run
[11:44:28.908] <TB0> INFO: test: exit no parameter change
[11:44:29.028] <TB0> QUIET: Connection to board 73 closed.
[11:44:29.108] <TB0> INFO: pXar: this is the end, my friend