[12:17:46.079] <TB1> INFO: *** Welcome to pxar ***
[12:17:46.079] <TB1> INFO: *** Today: 2016/09/08
[12:17:46.181] <TB1> INFO: *** Version: fe94
[12:17:46.181] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C15.dat
[12:17:46.183] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//tbmParameters_C0b.dat
[12:17:46.183] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//defaultMaskFile.dat
[12:17:46.183] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters_C15.dat
[12:17:46.262] <TB1> INFO: clk: 4
[12:17:46.262] <TB1> INFO: ctr: 4
[12:17:46.262] <TB1> INFO: sda: 19
[12:17:46.262] <TB1> INFO: tin: 9
[12:17:46.262] <TB1> INFO: level: 15
[12:17:46.262] <TB1> INFO: triggerdelay: 0
[12:17:46.262] <TB1> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[12:17:46.262] <TB1> INFO: Log level: INFO
[12:17:46.269] <TB1> INFO: Found DTB DTB_WXBYFL
[12:17:46.280] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[12:17:46.282] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[12:17:46.284] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[12:17:47.825] <TB1> INFO: DUT info:
[12:17:47.825] <TB1> INFO: The DUT currently contains the following objects:
[12:17:47.825] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[12:17:47.825] <TB1> INFO: TBM Core alpha (0): 7 registers set
[12:17:47.825] <TB1> INFO: TBM Core beta (1): 7 registers set
[12:17:47.825] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:17:47.825] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:47.825] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:47.825] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:47.825] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:47.825] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:47.825] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:47.825] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:47.825] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:47.826] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:47.826] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:47.826] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:47.826] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:47.826] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:47.826] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:47.826] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:47.826] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:17:48.227] <TB1> INFO: enter 'restricted' command line mode
[12:17:48.227] <TB1> INFO: enter test to run
[12:17:48.227] <TB1> INFO: test: pretest no parameter change
[12:17:48.227] <TB1> INFO: running: pretest
[12:17:48.238] <TB1> INFO: ----------------------------------------------------------------------
[12:17:48.238] <TB1> INFO: PixTestPretest::programROC()
[12:17:48.238] <TB1> INFO: ----------------------------------------------------------------------
[12:18:06.253] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:18:06.253] <TB1> INFO: IA differences per ROC: 19.3 18.5 19.3 19.3 19.3 23.3 17.7 20.1 19.3 19.3 16.1 19.3 20.1 19.3 20.9 19.3
[12:18:06.353] <TB1> INFO: enter test to run
[12:18:06.353] <TB1> INFO: test: pretest no parameter change
[12:18:06.353] <TB1> INFO: running: pretest
[12:18:06.354] <TB1> INFO: ----------------------------------------------------------------------
[12:18:06.354] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:18:06.354] <TB1> INFO: ----------------------------------------------------------------------
[12:18:12.150] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 379.4 mA = 23.7125 mA/ROC
[12:18:12.150] <TB1> INFO: i(loss) [mA/ROC]: 19.3 19.3 19.3 19.3 19.3 19.3 19.3 18.5 19.3 18.5 18.5 19.3 19.3 18.5 18.5 18.5
[12:18:12.188] <TB1> INFO: enter test to run
[12:18:12.188] <TB1> INFO: test: pretest no parameter change
[12:18:12.188] <TB1> INFO: running: pretest
[12:18:12.188] <TB1> INFO: ----------------------------------------------------------------------
[12:18:12.188] <TB1> INFO: PixTestPretest::findTiming()
[12:18:12.188] <TB1> INFO: ----------------------------------------------------------------------
[12:18:12.188] <TB1> INFO: PixTestCmd::init()

[12:19:41.581] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[12:19:41.581] <TB1> INFO: (success/tries = 100/100), width = 4
[12:19:41.582] <TB1> INFO: enter test to run
[12:19:41.582] <TB1> INFO: test: pretest no parameter change
[12:19:41.582] <TB1> INFO: running: pretest
[12:19:41.583] <TB1> INFO: ----------------------------------------------------------------------
[12:19:41.583] <TB1> INFO: PixTestPretest::findWorkingPixel()
[12:19:41.583] <TB1> INFO: ----------------------------------------------------------------------
[12:19:41.678] <TB1> INFO: Expecting 231680 events.


[12:19:51.976] <TB1> INFO: 231680 events read in total (9741ms).
[12:19:51.981] <TB1> INFO: Test took 10391ms.
[12:19:52.217] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[12:19:52.286] <TB1> INFO: enter test to run
[12:19:52.286] <TB1> INFO: test: pretest no parameter change
[12:19:52.286] <TB1> INFO: running: pretest
[12:19:52.288] <TB1> INFO: ----------------------------------------------------------------------
[12:19:52.288] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[12:19:52.288] <TB1> INFO: ----------------------------------------------------------------------
[12:19:52.381] <TB1> INFO: Expecting 231680 events.
[12:20:02.655] <TB1> INFO: 231680 events read in total (9718ms).
[12:20:02.659] <TB1> INFO: Test took 10366ms.
[12:20:02.904] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[12:20:02.904] <TB1> INFO: CalDel: 173 158 133 133 155 155 146 114 129 140 145 137 154 144 139 153
[12:20:02.904] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[12:20:02.940] <TB1> INFO: enter test to run
[12:20:02.940] <TB1> INFO: test: pretest no parameter change
[12:20:02.940] <TB1> INFO: running: pretest
[12:20:02.940] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C0.dat
[12:20:02.940] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C1.dat
[12:20:02.940] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C2.dat
[12:20:02.940] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C3.dat
[12:20:02.940] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C4.dat
[12:20:02.940] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C5.dat
[12:20:02.940] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C6.dat
[12:20:02.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C7.dat
[12:20:02.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C8.dat
[12:20:02.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C9.dat
[12:20:02.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C10.dat
[12:20:02.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C11.dat
[12:20:02.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C12.dat
[12:20:02.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C13.dat
[12:20:02.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C14.dat
[12:20:02.941] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters_C15.dat
[12:20:02.941] <TB1> INFO: enter test to run
[12:20:02.942] <TB1> INFO: test: fulltest no parameter change
[12:20:02.942] <TB1> INFO: running: fulltest
[12:20:02.942] <TB1> INFO: ######################################################################
[12:20:02.942] <TB1> INFO: PixTestFullTest::doTest()
[12:20:02.942] <TB1> INFO: ######################################################################
[12:20:02.943] <TB1> INFO: ######################################################################
[12:20:02.943] <TB1> INFO: PixTestAlive::doTest()
[12:20:02.943] <TB1> INFO: ######################################################################
[12:20:02.944] <TB1> INFO: ----------------------------------------------------------------------
[12:20:02.944] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:20:02.944] <TB1> INFO: ----------------------------------------------------------------------
[12:20:03.209] <TB1> INFO: Expecting 41600 events.
[12:20:06.974] <TB1> INFO: 41600 events read in total (3209ms).
[12:20:06.975] <TB1> INFO: Test took 4030ms.
[12:20:07.202] <TB1> INFO: PixTestAlive::aliveTest() done
[12:20:07.202] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:20:07.203] <TB1> INFO: ----------------------------------------------------------------------
[12:20:07.203] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:20:07.203] <TB1> INFO: ----------------------------------------------------------------------
[12:20:07.467] <TB1> INFO: Expecting 41600 events.
[12:20:10.433] <TB1> INFO: 41600 events read in total (2409ms).
[12:20:10.433] <TB1> INFO: Test took 3228ms.
[12:20:10.434] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:20:10.675] <TB1> INFO: PixTestAlive::maskTest() done
[12:20:10.675] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:20:10.676] <TB1> INFO: ----------------------------------------------------------------------
[12:20:10.676] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:20:10.676] <TB1> INFO: ----------------------------------------------------------------------
[12:20:10.941] <TB1> INFO: Expecting 41600 events.
[12:20:14.756] <TB1> INFO: 41600 events read in total (3258ms).
[12:20:14.756] <TB1> INFO: Test took 4079ms.
[12:20:14.990] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[12:20:14.990] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:20:14.990] <TB1> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[12:20:14.990] <TB1> INFO: Decoding statistics:
[12:20:14.990] <TB1> INFO: General information:
[12:20:14.990] <TB1> INFO: 16bit words read: 0
[12:20:14.990] <TB1> INFO: valid events total: 0
[12:20:14.990] <TB1> INFO: empty events: 0
[12:20:14.990] <TB1> INFO: valid events with pixels: 0
[12:20:14.990] <TB1> INFO: valid pixel hits: 0
[12:20:14.990] <TB1> INFO: Event errors: 0
[12:20:14.990] <TB1> INFO: start marker: 0
[12:20:14.990] <TB1> INFO: stop marker: 0
[12:20:14.990] <TB1> INFO: overflow: 0
[12:20:14.990] <TB1> INFO: invalid 5bit words: 0
[12:20:14.990] <TB1> INFO: invalid XOR eye diagram: 0
[12:20:14.990] <TB1> INFO: frame (failed synchr.): 0
[12:20:14.990] <TB1> INFO: idle data (no TBM trl): 0
[12:20:14.990] <TB1> INFO: no data (only TBM hdr): 0
[12:20:14.990] <TB1> INFO: TBM errors: 0
[12:20:14.990] <TB1> INFO: flawed TBM headers: 0
[12:20:14.990] <TB1> INFO: flawed TBM trailers: 0
[12:20:14.990] <TB1> INFO: event ID mismatches: 0
[12:20:14.990] <TB1> INFO: ROC errors: 0
[12:20:14.990] <TB1> INFO: missing ROC header(s): 0
[12:20:14.990] <TB1> INFO: misplaced readback start: 0
[12:20:14.990] <TB1> INFO: Pixel decoding errors: 0
[12:20:14.990] <TB1> INFO: pixel data incomplete: 0
[12:20:14.990] <TB1> INFO: pixel address: 0
[12:20:14.991] <TB1> INFO: pulse height fill bit: 0
[12:20:14.991] <TB1> INFO: buffer corruption: 0
[12:20:15.001] <TB1> INFO: ######################################################################
[12:20:15.001] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[12:20:15.001] <TB1> INFO: ######################################################################
[12:20:15.004] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[12:20:15.015] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[12:20:15.015] <TB1> INFO: run 1 of 1
[12:20:15.280] <TB1> INFO: Expecting 3120000 events.
[12:20:56.571] <TB1> INFO: 840535 events read in total (40735ms).
[12:21:36.955] <TB1> INFO: 1673020 events read in total (81120ms).
[12:22:17.525] <TB1> INFO: 2520550 events read in total (121690ms).
[12:22:46.021] <TB1> INFO: 3120000 events read in total (150185ms).
[12:22:46.075] <TB1> INFO: Test took 151060ms.
[12:23:10.442] <TB1> INFO: PixTestBBMap::doTest() done, duration: 175 seconds
[12:23:10.442] <TB1> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1
[12:23:10.442] <TB1> INFO: separation cut (per ROC): 71 76 94 68 84 88 71 87 76 65 75 78 80 76 70 68
[12:23:10.442] <TB1> INFO: Decoding statistics:
[12:23:10.442] <TB1> INFO: General information:
[12:23:10.442] <TB1> INFO: 16bit words read: 0
[12:23:10.442] <TB1> INFO: valid events total: 0
[12:23:10.442] <TB1> INFO: empty events: 0
[12:23:10.442] <TB1> INFO: valid events with pixels: 0
[12:23:10.442] <TB1> INFO: valid pixel hits: 0
[12:23:10.442] <TB1> INFO: Event errors: 0
[12:23:10.442] <TB1> INFO: start marker: 0
[12:23:10.442] <TB1> INFO: stop marker: 0
[12:23:10.442] <TB1> INFO: overflow: 0
[12:23:10.442] <TB1> INFO: invalid 5bit words: 0
[12:23:10.442] <TB1> INFO: invalid XOR eye diagram: 0
[12:23:10.442] <TB1> INFO: frame (failed synchr.): 0
[12:23:10.442] <TB1> INFO: idle data (no TBM trl): 0
[12:23:10.442] <TB1> INFO: no data (only TBM hdr): 0
[12:23:10.442] <TB1> INFO: TBM errors: 0
[12:23:10.442] <TB1> INFO: flawed TBM headers: 0
[12:23:10.442] <TB1> INFO: flawed TBM trailers: 0
[12:23:10.442] <TB1> INFO: event ID mismatches: 0
[12:23:10.442] <TB1> INFO: ROC errors: 0
[12:23:10.442] <TB1> INFO: missing ROC header(s): 0
[12:23:10.442] <TB1> INFO: misplaced readback start: 0
[12:23:10.442] <TB1> INFO: Pixel decoding errors: 0
[12:23:10.442] <TB1> INFO: pixel data incomplete: 0
[12:23:10.442] <TB1> INFO: pixel address: 0
[12:23:10.442] <TB1> INFO: pulse height fill bit: 0
[12:23:10.442] <TB1> INFO: buffer corruption: 0
[12:23:10.514] <TB1> INFO: ######################################################################
[12:23:10.514] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:23:10.514] <TB1> INFO: ######################################################################
[12:23:10.514] <TB1> INFO: ----------------------------------------------------------------------
[12:23:10.514] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:23:10.514] <TB1> INFO: ----------------------------------------------------------------------
[12:23:10.514] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:23:10.522] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[12:23:10.522] <TB1> INFO: run 1 of 1
[12:23:10.781] <TB1> INFO: Expecting 26208000 events.
[12:23:44.082] <TB1> INFO: 854250 events read in total (32744ms).
[12:24:16.657] <TB1> INFO: 1697900 events read in total (65319ms).
[12:24:49.530] <TB1> INFO: 2539050 events read in total (98192ms).
[12:25:22.500] <TB1> INFO: 3380550 events read in total (131162ms).
[12:25:55.291] <TB1> INFO: 4220750 events read in total (163953ms).
[12:26:28.029] <TB1> INFO: 5058800 events read in total (196692ms).
[12:27:00.739] <TB1> INFO: 5898400 events read in total (229401ms).
[12:27:33.606] <TB1> INFO: 6738350 events read in total (262268ms).
[12:28:06.352] <TB1> INFO: 7576600 events read in total (295014ms).
[12:28:39.320] <TB1> INFO: 8414350 events read in total (327982ms).
[12:29:12.041] <TB1> INFO: 9252750 events read in total (360703ms).
[12:29:44.983] <TB1> INFO: 10089550 events read in total (393645ms).
[12:30:18.231] <TB1> INFO: 10925600 events read in total (426893ms).
[12:30:51.234] <TB1> INFO: 11761750 events read in total (459896ms).
[12:31:24.235] <TB1> INFO: 12597300 events read in total (492897ms).
[12:31:57.142] <TB1> INFO: 13426500 events read in total (525804ms).
[12:32:29.953] <TB1> INFO: 14253900 events read in total (558615ms).
[12:33:02.526] <TB1> INFO: 15078700 events read in total (591188ms).
[12:33:35.122] <TB1> INFO: 15904550 events read in total (623784ms).
[12:34:07.678] <TB1> INFO: 16728800 events read in total (656340ms).
[12:34:40.209] <TB1> INFO: 17550350 events read in total (688871ms).
[12:35:13.179] <TB1> INFO: 18373700 events read in total (721841ms).
[12:35:45.964] <TB1> INFO: 19195000 events read in total (754626ms).
[12:36:18.689] <TB1> INFO: 20015450 events read in total (787351ms).
[12:36:51.461] <TB1> INFO: 20837300 events read in total (820123ms).
[12:37:24.124] <TB1> INFO: 21657250 events read in total (852786ms).
[12:37:56.862] <TB1> INFO: 22476000 events read in total (885524ms).
[12:38:29.664] <TB1> INFO: 23296550 events read in total (918326ms).
[12:39:02.409] <TB1> INFO: 24115950 events read in total (951071ms).
[12:39:35.367] <TB1> INFO: 24937100 events read in total (984029ms).
[12:40:08.930] <TB1> INFO: 25757850 events read in total (1017592ms).
[12:40:26.620] <TB1> INFO: 26208000 events read in total (1035282ms).
[12:40:26.650] <TB1> INFO: Test took 1036128ms.
[12:40:26.982] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:40:28.550] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:40:30.053] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:40:31.457] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:40:33.205] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:40:34.685] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:40:36.114] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:40:37.530] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:40:38.910] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:40:40.306] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:40:41.794] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:40:43.214] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:40:44.617] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:40:46.024] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:40:47.407] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:40:48.834] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:40:50.465] <TB1> INFO: PixTestScurves::scurves() done
[12:40:50.465] <TB1> INFO: Vcal mean: 72.67 75.53 86.79 68.17 78.79 82.73 81.99 87.01 81.27 68.94 75.79 80.01 81.15 89.51 72.55 68.23
[12:40:50.465] <TB1> INFO: Vcal RMS: 4.44 4.67 4.55 4.28 4.17 4.42 5.42 5.18 3.89 4.50 4.73 4.36 4.83 5.55 4.33 4.62
[12:40:50.465] <TB1> INFO: PixTestScurves::fullTest() done, duration: 1059 seconds
[12:40:50.465] <TB1> INFO: Decoding statistics:
[12:40:50.465] <TB1> INFO: General information:
[12:40:50.465] <TB1> INFO: 16bit words read: 0
[12:40:50.465] <TB1> INFO: valid events total: 0
[12:40:50.465] <TB1> INFO: empty events: 0
[12:40:50.465] <TB1> INFO: valid events with pixels: 0
[12:40:50.465] <TB1> INFO: valid pixel hits: 0
[12:40:50.465] <TB1> INFO: Event errors: 0
[12:40:50.465] <TB1> INFO: start marker: 0
[12:40:50.465] <TB1> INFO: stop marker: 0
[12:40:50.466] <TB1> INFO: overflow: 0
[12:40:50.466] <TB1> INFO: invalid 5bit words: 0
[12:40:50.466] <TB1> INFO: invalid XOR eye diagram: 0
[12:40:50.466] <TB1> INFO: frame (failed synchr.): 0
[12:40:50.466] <TB1> INFO: idle data (no TBM trl): 0
[12:40:50.466] <TB1> INFO: no data (only TBM hdr): 0
[12:40:50.466] <TB1> INFO: TBM errors: 0
[12:40:50.466] <TB1> INFO: flawed TBM headers: 0
[12:40:50.466] <TB1> INFO: flawed TBM trailers: 0
[12:40:50.466] <TB1> INFO: event ID mismatches: 0
[12:40:50.466] <TB1> INFO: ROC errors: 0
[12:40:50.466] <TB1> INFO: missing ROC header(s): 0
[12:40:50.466] <TB1> INFO: misplaced readback start: 0
[12:40:50.466] <TB1> INFO: Pixel decoding errors: 0
[12:40:50.466] <TB1> INFO: pixel data incomplete: 0
[12:40:50.466] <TB1> INFO: pixel address: 0
[12:40:50.466] <TB1> INFO: pulse height fill bit: 0
[12:40:50.466] <TB1> INFO: buffer corruption: 0
[12:40:50.540] <TB1> INFO: ######################################################################
[12:40:50.540] <TB1> INFO: PixTestTrim::doTest()
[12:40:50.540] <TB1> INFO: ######################################################################
[12:40:50.542] <TB1> INFO: ----------------------------------------------------------------------
[12:40:50.542] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[12:40:50.542] <TB1> INFO: ----------------------------------------------------------------------
[12:40:50.625] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[12:40:50.625] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:40:50.634] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[12:40:50.634] <TB1> INFO: run 1 of 1
[12:40:50.914] <TB1> INFO: Expecting 6281600 events.
[12:41:29.472] <TB1> INFO: 1032990 events read in total (37998ms).
[12:42:07.706] <TB1> INFO: 2065070 events read in total (76232ms).
[12:42:46.768] <TB1> INFO: 3093480 events read in total (115294ms).
[12:43:26.706] <TB1> INFO: 4131820 events read in total (155232ms).
[12:44:05.700] <TB1> INFO: 5176970 events read in total (194226ms).
[12:44:44.601] <TB1> INFO: 6228250 events read in total (233127ms).
[12:44:46.962] <TB1> INFO: 6281600 events read in total (235488ms).
[12:44:46.995] <TB1> INFO: Test took 236362ms.
[12:45:06.840] <TB1> INFO: ROC 0 VthrComp = 79
[12:45:06.840] <TB1> INFO: ROC 1 VthrComp = 80
[12:45:06.840] <TB1> INFO: ROC 2 VthrComp = 96
[12:45:06.840] <TB1> INFO: ROC 3 VthrComp = 76
[12:45:06.840] <TB1> INFO: ROC 4 VthrComp = 86
[12:45:06.840] <TB1> INFO: ROC 5 VthrComp = 89
[12:45:06.840] <TB1> INFO: ROC 6 VthrComp = 85
[12:45:06.840] <TB1> INFO: ROC 7 VthrComp = 94
[12:45:06.840] <TB1> INFO: ROC 8 VthrComp = 90
[12:45:06.841] <TB1> INFO: ROC 9 VthrComp = 76
[12:45:06.841] <TB1> INFO: ROC 10 VthrComp = 81
[12:45:06.841] <TB1> INFO: ROC 11 VthrComp = 86
[12:45:06.841] <TB1> INFO: ROC 12 VthrComp = 84
[12:45:06.841] <TB1> INFO: ROC 13 VthrComp = 94
[12:45:06.841] <TB1> INFO: ROC 14 VthrComp = 81
[12:45:06.841] <TB1> INFO: ROC 15 VthrComp = 76
[12:45:06.841] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[12:45:06.841] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:45:06.849] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[12:45:06.850] <TB1> INFO: run 1 of 1
[12:45:07.137] <TB1> INFO: Expecting 6281600 events.
[12:45:42.790] <TB1> INFO: 756460 events read in total (35096ms).
[12:46:17.982] <TB1> INFO: 1510760 events read in total (70288ms).
[12:46:53.463] <TB1> INFO: 2264740 events read in total (105769ms).
[12:47:28.740] <TB1> INFO: 3019260 events read in total (141046ms).
[12:48:04.039] <TB1> INFO: 3768040 events read in total (176345ms).
[12:48:39.215] <TB1> INFO: 4513310 events read in total (211521ms).
[12:49:14.898] <TB1> INFO: 5256130 events read in total (247204ms).
[12:49:49.514] <TB1> INFO: 5998020 events read in total (281820ms).
[12:50:03.114] <TB1> INFO: 6281600 events read in total (295420ms).
[12:50:03.155] <TB1> INFO: Test took 296305ms.
[12:50:26.512] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 56.3476 for pixel 0/58 mean/min/max = 45.4141/34.2927/56.5355
[12:50:26.512] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 57.9927 for pixel 4/17 mean/min/max = 45.1589/32.3051/58.0127
[12:50:26.513] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 55.2499 for pixel 24/6 mean/min/max = 43.6122/31.9714/55.253
[12:50:26.513] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 56.0327 for pixel 51/77 mean/min/max = 45.6715/35.1465/56.1966
[12:50:26.513] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 56.2463 for pixel 11/75 mean/min/max = 44.1839/32.0695/56.2983
[12:50:26.513] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 57.3296 for pixel 15/8 mean/min/max = 45.2628/33.1549/57.3706
[12:50:26.514] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 61.6798 for pixel 10/21 mean/min/max = 46.4153/30.9242/61.9064
[12:50:26.514] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 58.6281 for pixel 20/6 mean/min/max = 45.2315/31.5897/58.8732
[12:50:26.514] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 55.7917 for pixel 23/10 mean/min/max = 44.524/33.1926/55.8553
[12:50:26.514] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 57.6415 for pixel 7/0 mean/min/max = 46.4454/35.1265/57.7644
[12:50:26.515] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 59.3316 for pixel 10/3 mean/min/max = 45.6482/31.9329/59.3634
[12:50:26.515] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 58.4821 for pixel 14/75 mean/min/max = 44.9662/31.4276/58.5047
[12:50:26.515] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 61.3201 for pixel 0/16 mean/min/max = 46.6414/31.8983/61.3845
[12:50:26.515] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 60.2951 for pixel 3/1 mean/min/max = 45.8413/30.7938/60.8889
[12:50:26.516] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 54.8515 for pixel 0/16 mean/min/max = 43.7753/32.5125/55.0381
[12:50:26.516] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 55.7623 for pixel 12/44 mean/min/max = 44.9924/33.9444/56.0404
[12:50:26.516] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:50:26.604] <TB1> INFO: Expecting 514560 events.
[12:50:39.594] <TB1> INFO: 514560 events read in total (12433ms).
[12:50:39.601] <TB1> INFO: Expecting 514560 events.
[12:50:52.436] <TB1> INFO: 514560 events read in total (12420ms).
[12:50:52.443] <TB1> INFO: Expecting 514560 events.
[12:51:05.335] <TB1> INFO: 514560 events read in total (12474ms).
[12:51:05.344] <TB1> INFO: Expecting 514560 events.
[12:51:18.121] <TB1> INFO: 514560 events read in total (12369ms).
[12:51:18.132] <TB1> INFO: Expecting 514560 events.
[12:51:30.933] <TB1> INFO: 514560 events read in total (12380ms).
[12:51:30.947] <TB1> INFO: Expecting 514560 events.
[12:51:43.851] <TB1> INFO: 514560 events read in total (12494ms).
[12:51:43.870] <TB1> INFO: Expecting 514560 events.
[12:51:56.722] <TB1> INFO: 514560 events read in total (12447ms).
[12:51:56.738] <TB1> INFO: Expecting 514560 events.
[12:52:09.632] <TB1> INFO: 514560 events read in total (12484ms).
[12:52:09.651] <TB1> INFO: Expecting 514560 events.
[12:52:22.559] <TB1> INFO: 514560 events read in total (12506ms).
[12:52:22.579] <TB1> INFO: Expecting 514560 events.
[12:52:35.491] <TB1> INFO: 514560 events read in total (12506ms).
[12:52:35.515] <TB1> INFO: Expecting 514560 events.
[12:52:48.484] <TB1> INFO: 514560 events read in total (12567ms).
[12:52:48.509] <TB1> INFO: Expecting 514560 events.
[12:53:01.348] <TB1> INFO: 514560 events read in total (12437ms).
[12:53:01.376] <TB1> INFO: Expecting 514560 events.
[12:53:14.214] <TB1> INFO: 514560 events read in total (12436ms).
[12:53:14.247] <TB1> INFO: Expecting 514560 events.
[12:53:27.025] <TB1> INFO: 514560 events read in total (12376ms).
[12:53:27.058] <TB1> INFO: Expecting 514560 events.
[12:53:39.903] <TB1> INFO: 514560 events read in total (12443ms).
[12:53:39.935] <TB1> INFO: Expecting 514560 events.
[12:53:52.811] <TB1> INFO: 514560 events read in total (12474ms).
[12:53:52.851] <TB1> INFO: Test took 206335ms.
[12:53:53.963] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:53:53.971] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[12:53:53.972] <TB1> INFO: run 1 of 1
[12:53:54.245] <TB1> INFO: Expecting 6281600 events.
[12:54:30.383] <TB1> INFO: 745400 events read in total (35582ms).
[12:55:05.374] <TB1> INFO: 1488440 events read in total (70573ms).
[12:55:40.025] <TB1> INFO: 2231250 events read in total (105224ms).
[12:56:15.474] <TB1> INFO: 2974000 events read in total (140673ms).
[12:56:50.820] <TB1> INFO: 3710850 events read in total (176019ms).
[12:57:26.170] <TB1> INFO: 4443820 events read in total (211369ms).
[12:58:01.111] <TB1> INFO: 5174160 events read in total (246310ms).
[12:58:35.753] <TB1> INFO: 5903190 events read in total (280952ms).
[12:58:54.054] <TB1> INFO: 6281600 events read in total (299253ms).
[12:58:54.102] <TB1> INFO: Test took 300130ms.
[12:59:18.143] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.102668 .. 50.126769
[12:59:18.238] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 60 (-1/-1) hits flags = 528 (plus default)
[12:59:18.247] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[12:59:18.247] <TB1> INFO: run 1 of 1
[12:59:18.515] <TB1> INFO: Expecting 2537600 events.
[12:59:56.664] <TB1> INFO: 931220 events read in total (37593ms).
[13:00:34.564] <TB1> INFO: 1859950 events read in total (75493ms).
[13:01:02.552] <TB1> INFO: 2537600 events read in total (103481ms).
[13:01:02.570] <TB1> INFO: Test took 104323ms.
[13:01:16.975] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 15.630623 .. 45.541094
[13:01:17.050] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 5 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:01:17.059] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:01:17.059] <TB1> INFO: run 1 of 1
[13:01:17.324] <TB1> INFO: Expecting 2121600 events.
[13:01:55.818] <TB1> INFO: 939200 events read in total (37938ms).
[13:02:33.875] <TB1> INFO: 1876670 events read in total (75995ms).
[13:02:44.387] <TB1> INFO: 2121600 events read in total (86508ms).
[13:02:44.407] <TB1> INFO: Test took 87349ms.
[13:02:58.979] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 19.861184 .. 42.178724
[13:02:59.057] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 9 .. 52 (-1/-1) hits flags = 528 (plus default)
[13:02:59.066] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:02:59.066] <TB1> INFO: run 1 of 1
[13:02:59.346] <TB1> INFO: Expecting 1830400 events.
[13:03:38.059] <TB1> INFO: 943610 events read in total (38156ms).
[13:04:13.900] <TB1> INFO: 1830400 events read in total (73997ms).
[13:04:13.917] <TB1> INFO: Test took 74851ms.
[13:04:27.254] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 22.817900 .. 42.178724
[13:04:27.346] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 12 .. 52 (-1/-1) hits flags = 528 (plus default)
[13:04:27.355] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:04:27.355] <TB1> INFO: run 1 of 1
[13:04:27.640] <TB1> INFO: Expecting 1705600 events.
[13:05:06.444] <TB1> INFO: 923950 events read in total (38247ms).
[13:05:38.975] <TB1> INFO: 1705600 events read in total (70778ms).
[13:05:38.992] <TB1> INFO: Test took 71637ms.
[13:05:52.378] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[13:05:52.378] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:05:52.386] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:05:52.386] <TB1> INFO: run 1 of 1
[13:05:52.647] <TB1> INFO: Expecting 1705600 events.
[13:06:31.036] <TB1> INFO: 879920 events read in total (37832ms).
[13:07:07.663] <TB1> INFO: 1705600 events read in total (74459ms).
[13:07:07.682] <TB1> INFO: Test took 75297ms.
[13:07:21.636] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C0.dat
[13:07:21.636] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C1.dat
[13:07:21.636] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C2.dat
[13:07:21.636] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C3.dat
[13:07:21.636] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C4.dat
[13:07:21.636] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C5.dat
[13:07:21.637] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C6.dat
[13:07:21.637] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C7.dat
[13:07:21.637] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C8.dat
[13:07:21.637] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C9.dat
[13:07:21.637] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C10.dat
[13:07:21.637] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C11.dat
[13:07:21.637] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C12.dat
[13:07:21.637] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C13.dat
[13:07:21.638] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C14.dat
[13:07:21.638] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C15.dat
[13:07:21.638] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C0.dat
[13:07:21.646] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C1.dat
[13:07:21.654] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C2.dat
[13:07:21.662] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C3.dat
[13:07:21.671] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C4.dat
[13:07:21.677] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C5.dat
[13:07:21.683] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C6.dat
[13:07:21.689] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C7.dat
[13:07:21.695] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C8.dat
[13:07:21.702] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C9.dat
[13:07:21.708] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C10.dat
[13:07:21.714] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C11.dat
[13:07:21.721] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C12.dat
[13:07:21.727] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C13.dat
[13:07:21.735] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C14.dat
[13:07:21.744] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//trimParameters35_C15.dat
[13:07:21.752] <TB1> INFO: PixTestTrim::trimTest() done
[13:07:21.752] <TB1> INFO: vtrim: 86 100 97 87 91 102 104 102 92 102 107 102 97 97 88 86
[13:07:21.752] <TB1> INFO: vthrcomp: 79 80 96 76 86 89 85 94 90 76 81 86 84 94 81 76
[13:07:21.752] <TB1> INFO: vcal mean: 35.02 35.02 35.00 34.96 34.98 34.97 34.95 35.02 34.99 35.04 34.97 34.96 35.00 34.95 34.94 34.98
[13:07:21.752] <TB1> INFO: vcal RMS: 0.71 0.81 0.80 0.77 0.78 0.80 0.92 0.82 0.76 0.78 0.84 0.86 0.86 0.92 0.77 0.78
[13:07:21.752] <TB1> INFO: bits mean: 8.59 9.63 10.00 8.47 9.75 9.32 9.86 9.57 9.37 8.93 9.34 9.83 8.92 9.49 9.45 9.13
[13:07:21.752] <TB1> INFO: bits RMS: 2.69 2.62 2.56 2.59 2.68 2.62 2.58 2.77 2.63 2.47 2.81 2.67 2.87 2.79 2.77 2.58
[13:07:21.759] <TB1> INFO: ----------------------------------------------------------------------
[13:07:21.759] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[13:07:21.759] <TB1> INFO: ----------------------------------------------------------------------
[13:07:21.761] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[13:07:21.770] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:07:21.770] <TB1> INFO: run 1 of 1
[13:07:22.050] <TB1> INFO: Expecting 4160000 events.
[13:08:04.126] <TB1> INFO: 890285 events read in total (41519ms).
[13:08:45.618] <TB1> INFO: 1775255 events read in total (83012ms).
[13:09:26.399] <TB1> INFO: 2652515 events read in total (123792ms).
[13:10:07.252] <TB1> INFO: 3524900 events read in total (164645ms).
[13:10:37.876] <TB1> INFO: 4160000 events read in total (195269ms).
[13:10:37.919] <TB1> INFO: Test took 196148ms.
[13:11:07.097] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 528 (plus default)
[13:11:07.105] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:11:07.105] <TB1> INFO: run 1 of 1
[13:11:07.372] <TB1> INFO: Expecting 3120000 events.
[13:11:50.413] <TB1> INFO: 958935 events read in total (42485ms).
[13:12:33.127] <TB1> INFO: 1904955 events read in total (85200ms).
[13:13:16.045] <TB1> INFO: 2840220 events read in total (128117ms).
[13:13:29.234] <TB1> INFO: 3120000 events read in total (141306ms).
[13:13:29.268] <TB1> INFO: Test took 142162ms.
[13:13:55.843] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 137 (-1/-1) hits flags = 528 (plus default)
[13:13:55.851] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:13:55.851] <TB1> INFO: run 1 of 1
[13:13:56.115] <TB1> INFO: Expecting 2870400 events.
[13:14:39.793] <TB1> INFO: 996150 events read in total (43122ms).
[13:15:23.091] <TB1> INFO: 1974590 events read in total (86420ms).
[13:16:02.581] <TB1> INFO: 2870400 events read in total (125910ms).
[13:16:02.608] <TB1> INFO: Test took 126756ms.
[13:16:26.785] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 138 (-1/-1) hits flags = 528 (plus default)
[13:16:26.793] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:16:26.793] <TB1> INFO: run 1 of 1
[13:16:27.054] <TB1> INFO: Expecting 2891200 events.
[13:17:11.082] <TB1> INFO: 991680 events read in total (43472ms).
[13:17:53.681] <TB1> INFO: 1966370 events read in total (86071ms).
[13:18:34.252] <TB1> INFO: 2891200 events read in total (126642ms).
[13:18:34.283] <TB1> INFO: Test took 127490ms.
[13:18:58.399] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 138 (-1/-1) hits flags = 528 (plus default)
[13:18:58.408] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:18:58.408] <TB1> INFO: run 1 of 1
[13:18:58.696] <TB1> INFO: Expecting 2891200 events.
[13:19:42.560] <TB1> INFO: 990900 events read in total (43308ms).
[13:20:26.079] <TB1> INFO: 1964965 events read in total (86827ms).
[13:21:07.347] <TB1> INFO: 2891200 events read in total (128096ms).
[13:21:07.381] <TB1> INFO: Test took 128974ms.
[13:21:30.634] <TB1> INFO: PixTestTrim::trimBitTest() done
[13:21:30.635] <TB1> INFO: PixTestTrim::doTest() done, duration: 2440 seconds
[13:21:30.635] <TB1> INFO: Decoding statistics:
[13:21:30.635] <TB1> INFO: General information:
[13:21:30.635] <TB1> INFO: 16bit words read: 0
[13:21:30.635] <TB1> INFO: valid events total: 0
[13:21:30.635] <TB1> INFO: empty events: 0
[13:21:30.635] <TB1> INFO: valid events with pixels: 0
[13:21:30.635] <TB1> INFO: valid pixel hits: 0
[13:21:30.635] <TB1> INFO: Event errors: 0
[13:21:30.635] <TB1> INFO: start marker: 0
[13:21:30.635] <TB1> INFO: stop marker: 0
[13:21:30.635] <TB1> INFO: overflow: 0
[13:21:30.635] <TB1> INFO: invalid 5bit words: 0
[13:21:30.635] <TB1> INFO: invalid XOR eye diagram: 0
[13:21:30.635] <TB1> INFO: frame (failed synchr.): 0
[13:21:30.635] <TB1> INFO: idle data (no TBM trl): 0
[13:21:30.635] <TB1> INFO: no data (only TBM hdr): 0
[13:21:30.635] <TB1> INFO: TBM errors: 0
[13:21:30.636] <TB1> INFO: flawed TBM headers: 0
[13:21:30.636] <TB1> INFO: flawed TBM trailers: 0
[13:21:30.636] <TB1> INFO: event ID mismatches: 0
[13:21:30.636] <TB1> INFO: ROC errors: 0
[13:21:30.636] <TB1> INFO: missing ROC header(s): 0
[13:21:30.636] <TB1> INFO: misplaced readback start: 0
[13:21:30.636] <TB1> INFO: Pixel decoding errors: 0
[13:21:30.636] <TB1> INFO: pixel data incomplete: 0
[13:21:30.636] <TB1> INFO: pixel address: 0
[13:21:30.636] <TB1> INFO: pulse height fill bit: 0
[13:21:30.636] <TB1> INFO: buffer corruption: 0
[13:21:31.332] <TB1> INFO: ######################################################################
[13:21:31.332] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[13:21:31.332] <TB1> INFO: ######################################################################
[13:21:31.597] <TB1> INFO: Expecting 41600 events.
[13:21:35.410] <TB1> INFO: 41600 events read in total (3257ms).
[13:21:35.411] <TB1> INFO: Test took 4077ms.
[13:21:35.907] <TB1> INFO: Expecting 41600 events.
[13:21:39.784] <TB1> INFO: 41600 events read in total (3321ms).
[13:21:39.784] <TB1> INFO: Test took 4168ms.
[13:21:40.079] <TB1> INFO: Expecting 41600 events.
[13:21:43.904] <TB1> INFO: 41600 events read in total (3268ms).
[13:21:43.904] <TB1> INFO: Test took 4098ms.
[13:21:44.159] <TB1> INFO: Expecting 2560 events.
[13:21:45.015] <TB1> INFO: 2560 events read in total (300ms).
[13:21:45.015] <TB1> INFO: Test took 1098ms.
[13:21:45.323] <TB1> INFO: Expecting 2560 events.
[13:21:46.176] <TB1> INFO: 2560 events read in total (297ms).
[13:21:46.177] <TB1> INFO: Test took 1162ms.
[13:21:46.482] <TB1> INFO: Expecting 2560 events.
[13:21:47.336] <TB1> INFO: 2560 events read in total (297ms).
[13:21:47.336] <TB1> INFO: Test took 1159ms.
[13:21:47.641] <TB1> INFO: Expecting 2560 events.
[13:21:48.495] <TB1> INFO: 2560 events read in total (297ms).
[13:21:48.495] <TB1> INFO: Test took 1159ms.
[13:21:48.801] <TB1> INFO: Expecting 2560 events.
[13:21:49.657] <TB1> INFO: 2560 events read in total (300ms).
[13:21:49.658] <TB1> INFO: Test took 1163ms.
[13:21:49.963] <TB1> INFO: Expecting 2560 events.
[13:21:50.818] <TB1> INFO: 2560 events read in total (299ms).
[13:21:50.818] <TB1> INFO: Test took 1160ms.
[13:21:51.124] <TB1> INFO: Expecting 2560 events.
[13:21:51.980] <TB1> INFO: 2560 events read in total (300ms).
[13:21:51.980] <TB1> INFO: Test took 1161ms.
[13:21:52.285] <TB1> INFO: Expecting 2560 events.
[13:21:53.138] <TB1> INFO: 2560 events read in total (297ms).
[13:21:53.138] <TB1> INFO: Test took 1158ms.
[13:21:53.443] <TB1> INFO: Expecting 2560 events.
[13:21:54.296] <TB1> INFO: 2560 events read in total (296ms).
[13:21:54.296] <TB1> INFO: Test took 1158ms.
[13:21:54.601] <TB1> INFO: Expecting 2560 events.
[13:21:55.456] <TB1> INFO: 2560 events read in total (298ms).
[13:21:55.456] <TB1> INFO: Test took 1159ms.
[13:21:55.760] <TB1> INFO: Expecting 2560 events.
[13:21:56.615] <TB1> INFO: 2560 events read in total (298ms).
[13:21:56.615] <TB1> INFO: Test took 1158ms.
[13:21:56.921] <TB1> INFO: Expecting 2560 events.
[13:21:57.775] <TB1> INFO: 2560 events read in total (298ms).
[13:21:57.775] <TB1> INFO: Test took 1159ms.
[13:21:58.080] <TB1> INFO: Expecting 2560 events.
[13:21:58.932] <TB1> INFO: 2560 events read in total (296ms).
[13:21:58.932] <TB1> INFO: Test took 1156ms.
[13:21:59.238] <TB1> INFO: Expecting 2560 events.
[13:22:00.094] <TB1> INFO: 2560 events read in total (300ms).
[13:22:00.094] <TB1> INFO: Test took 1161ms.
[13:22:00.399] <TB1> INFO: Expecting 2560 events.
[13:22:01.252] <TB1> INFO: 2560 events read in total (296ms).
[13:22:01.253] <TB1> INFO: Test took 1159ms.
[13:22:01.558] <TB1> INFO: Expecting 2560 events.
[13:22:02.416] <TB1> INFO: 2560 events read in total (302ms).
[13:22:02.416] <TB1> INFO: Test took 1163ms.
[13:22:02.420] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:22:02.724] <TB1> INFO: Expecting 655360 events.
[13:22:19.759] <TB1> INFO: 655360 events read in total (16479ms).
[13:22:19.768] <TB1> INFO: Expecting 655360 events.
[13:22:36.459] <TB1> INFO: 655360 events read in total (16289ms).
[13:22:36.471] <TB1> INFO: Expecting 655360 events.
[13:22:53.131] <TB1> INFO: 655360 events read in total (16258ms).
[13:22:53.149] <TB1> INFO: Expecting 655360 events.
[13:23:09.639] <TB1> INFO: 655360 events read in total (16089ms).
[13:23:09.658] <TB1> INFO: Expecting 655360 events.
[13:23:26.349] <TB1> INFO: 655360 events read in total (16289ms).
[13:23:26.371] <TB1> INFO: Expecting 655360 events.
[13:23:43.167] <TB1> INFO: 655360 events read in total (16394ms).
[13:23:43.194] <TB1> INFO: Expecting 655360 events.
[13:23:59.769] <TB1> INFO: 655360 events read in total (16173ms).
[13:23:59.803] <TB1> INFO: Expecting 655360 events.
[13:24:16.628] <TB1> INFO: 655360 events read in total (16423ms).
[13:24:16.660] <TB1> INFO: Expecting 655360 events.
[13:24:33.231] <TB1> INFO: 655360 events read in total (16169ms).
[13:24:33.268] <TB1> INFO: Expecting 655360 events.
[13:24:50.042] <TB1> INFO: 655360 events read in total (16373ms).
[13:24:50.089] <TB1> INFO: Expecting 655360 events.
[13:25:06.803] <TB1> INFO: 655360 events read in total (16312ms).
[13:25:06.847] <TB1> INFO: Expecting 655360 events.
[13:25:23.452] <TB1> INFO: 655360 events read in total (16199ms).
[13:25:23.499] <TB1> INFO: Expecting 655360 events.
[13:25:40.174] <TB1> INFO: 655360 events read in total (16273ms).
[13:25:40.224] <TB1> INFO: Expecting 655360 events.
[13:25:57.001] <TB1> INFO: 655360 events read in total (16375ms).
[13:25:57.069] <TB1> INFO: Expecting 655360 events.
[13:26:13.847] <TB1> INFO: 655360 events read in total (16376ms).
[13:26:13.911] <TB1> INFO: Expecting 655360 events.
[13:26:30.625] <TB1> INFO: 655360 events read in total (16312ms).
[13:26:30.689] <TB1> INFO: Test took 268269ms.
[13:26:30.769] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:26:31.000] <TB1> INFO: Expecting 655360 events.
[13:26:47.748] <TB1> INFO: 655360 events read in total (16192ms).
[13:26:47.757] <TB1> INFO: Expecting 655360 events.
[13:27:04.599] <TB1> INFO: 655360 events read in total (16440ms).
[13:27:04.610] <TB1> INFO: Expecting 655360 events.
[13:27:21.224] <TB1> INFO: 655360 events read in total (16212ms).
[13:27:21.247] <TB1> INFO: Expecting 655360 events.
[13:27:37.930] <TB1> INFO: 655360 events read in total (16281ms).
[13:27:37.948] <TB1> INFO: Expecting 655360 events.
[13:27:54.653] <TB1> INFO: 655360 events read in total (16303ms).
[13:27:54.677] <TB1> INFO: Expecting 655360 events.
[13:28:11.297] <TB1> INFO: 655360 events read in total (16218ms).
[13:28:11.324] <TB1> INFO: Expecting 655360 events.
[13:28:27.996] <TB1> INFO: 655360 events read in total (16270ms).
[13:28:28.024] <TB1> INFO: Expecting 655360 events.
[13:28:44.848] <TB1> INFO: 655360 events read in total (16422ms).
[13:28:44.897] <TB1> INFO: Expecting 655360 events.
[13:29:01.606] <TB1> INFO: 655360 events read in total (16307ms).
[13:29:01.642] <TB1> INFO: Expecting 655360 events.
[13:29:18.671] <TB1> INFO: 655360 events read in total (16627ms).
[13:29:18.716] <TB1> INFO: Expecting 655360 events.
[13:29:35.520] <TB1> INFO: 655360 events read in total (16402ms).
[13:29:35.568] <TB1> INFO: Expecting 655360 events.
[13:29:52.320] <TB1> INFO: 655360 events read in total (16350ms).
[13:29:52.376] <TB1> INFO: Expecting 655360 events.
[13:30:09.183] <TB1> INFO: 655360 events read in total (16405ms).
[13:30:09.232] <TB1> INFO: Expecting 655360 events.
[13:30:25.978] <TB1> INFO: 655360 events read in total (16345ms).
[13:30:26.031] <TB1> INFO: Expecting 655360 events.
[13:30:42.828] <TB1> INFO: 655360 events read in total (16395ms).
[13:30:42.890] <TB1> INFO: Expecting 655360 events.
[13:30:59.688] <TB1> INFO: 655360 events read in total (16396ms).
[13:30:59.750] <TB1> INFO: Test took 268981ms.
[13:30:59.934] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:59.941] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:59.949] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:59.955] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:59.962] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:59.969] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:59.975] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:59.982] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:59.989] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:30:59.995] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:31:00.002] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:31:00.009] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:31:00.016] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:31:00.022] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:31:00.029] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:31:00.036] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:31:00.042] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[13:31:00.049] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[13:31:00.056] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[13:31:00.089] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C0.dat
[13:31:00.089] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C1.dat
[13:31:00.089] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C2.dat
[13:31:00.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C3.dat
[13:31:00.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C4.dat
[13:31:00.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C5.dat
[13:31:00.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C6.dat
[13:31:00.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C7.dat
[13:31:00.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C8.dat
[13:31:00.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C9.dat
[13:31:00.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C10.dat
[13:31:00.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C11.dat
[13:31:00.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C12.dat
[13:31:00.090] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C13.dat
[13:31:00.091] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C14.dat
[13:31:00.091] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//dacParameters35_C15.dat
[13:31:00.364] <TB1> INFO: Expecting 41600 events.
[13:31:03.848] <TB1> INFO: 41600 events read in total (2927ms).
[13:31:03.849] <TB1> INFO: Test took 3755ms.
[13:31:04.331] <TB1> INFO: Expecting 41600 events.
[13:31:07.850] <TB1> INFO: 41600 events read in total (2963ms).
[13:31:07.850] <TB1> INFO: Test took 3823ms.
[13:31:08.321] <TB1> INFO: Expecting 41600 events.
[13:31:11.810] <TB1> INFO: 41600 events read in total (2933ms).
[13:31:11.811] <TB1> INFO: Test took 3777ms.
[13:31:11.996] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:12.085] <TB1> INFO: Expecting 2560 events.
[13:31:12.939] <TB1> INFO: 2560 events read in total (298ms).
[13:31:12.940] <TB1> INFO: Test took 944ms.
[13:31:12.948] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:13.245] <TB1> INFO: Expecting 2560 events.
[13:31:14.104] <TB1> INFO: 2560 events read in total (303ms).
[13:31:14.104] <TB1> INFO: Test took 1156ms.
[13:31:14.106] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:14.410] <TB1> INFO: Expecting 2560 events.
[13:31:15.267] <TB1> INFO: 2560 events read in total (301ms).
[13:31:15.267] <TB1> INFO: Test took 1161ms.
[13:31:15.270] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:15.573] <TB1> INFO: Expecting 2560 events.
[13:31:16.429] <TB1> INFO: 2560 events read in total (299ms).
[13:31:16.429] <TB1> INFO: Test took 1159ms.
[13:31:16.432] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:16.734] <TB1> INFO: Expecting 2560 events.
[13:31:17.590] <TB1> INFO: 2560 events read in total (299ms).
[13:31:17.590] <TB1> INFO: Test took 1158ms.
[13:31:17.592] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:17.895] <TB1> INFO: Expecting 2560 events.
[13:31:18.748] <TB1> INFO: 2560 events read in total (297ms).
[13:31:18.749] <TB1> INFO: Test took 1157ms.
[13:31:18.751] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:19.055] <TB1> INFO: Expecting 2560 events.
[13:31:19.909] <TB1> INFO: 2560 events read in total (298ms).
[13:31:19.909] <TB1> INFO: Test took 1158ms.
[13:31:19.910] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:20.215] <TB1> INFO: Expecting 2560 events.
[13:31:21.070] <TB1> INFO: 2560 events read in total (298ms).
[13:31:21.070] <TB1> INFO: Test took 1160ms.
[13:31:21.073] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:21.375] <TB1> INFO: Expecting 2560 events.
[13:31:22.234] <TB1> INFO: 2560 events read in total (302ms).
[13:31:22.234] <TB1> INFO: Test took 1161ms.
[13:31:22.236] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:22.541] <TB1> INFO: Expecting 2560 events.
[13:31:23.398] <TB1> INFO: 2560 events read in total (300ms).
[13:31:23.398] <TB1> INFO: Test took 1162ms.
[13:31:23.401] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:23.704] <TB1> INFO: Expecting 2560 events.
[13:31:24.562] <TB1> INFO: 2560 events read in total (301ms).
[13:31:24.563] <TB1> INFO: Test took 1162ms.
[13:31:24.566] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:24.869] <TB1> INFO: Expecting 2560 events.
[13:31:25.722] <TB1> INFO: 2560 events read in total (297ms).
[13:31:25.723] <TB1> INFO: Test took 1158ms.
[13:31:25.726] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:26.028] <TB1> INFO: Expecting 2560 events.
[13:31:26.882] <TB1> INFO: 2560 events read in total (297ms).
[13:31:26.882] <TB1> INFO: Test took 1157ms.
[13:31:26.884] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:27.188] <TB1> INFO: Expecting 2560 events.
[13:31:28.046] <TB1> INFO: 2560 events read in total (301ms).
[13:31:28.046] <TB1> INFO: Test took 1163ms.
[13:31:28.049] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:28.352] <TB1> INFO: Expecting 2560 events.
[13:31:29.212] <TB1> INFO: 2560 events read in total (304ms).
[13:31:29.213] <TB1> INFO: Test took 1164ms.
[13:31:29.216] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:29.519] <TB1> INFO: Expecting 2560 events.
[13:31:30.372] <TB1> INFO: 2560 events read in total (297ms).
[13:31:30.372] <TB1> INFO: Test took 1156ms.
[13:31:30.375] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:30.678] <TB1> INFO: Expecting 2560 events.
[13:31:31.533] <TB1> INFO: 2560 events read in total (298ms).
[13:31:31.534] <TB1> INFO: Test took 1160ms.
[13:31:31.535] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:31.839] <TB1> INFO: Expecting 2560 events.
[13:31:32.693] <TB1> INFO: 2560 events read in total (297ms).
[13:31:32.693] <TB1> INFO: Test took 1158ms.
[13:31:32.695] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:32.999] <TB1> INFO: Expecting 2560 events.
[13:31:33.853] <TB1> INFO: 2560 events read in total (297ms).
[13:31:33.854] <TB1> INFO: Test took 1159ms.
[13:31:33.856] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:34.159] <TB1> INFO: Expecting 2560 events.
[13:31:35.017] <TB1> INFO: 2560 events read in total (301ms).
[13:31:35.017] <TB1> INFO: Test took 1161ms.
[13:31:35.019] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:35.323] <TB1> INFO: Expecting 2560 events.
[13:31:36.178] <TB1> INFO: 2560 events read in total (299ms).
[13:31:36.178] <TB1> INFO: Test took 1159ms.
[13:31:36.181] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:36.484] <TB1> INFO: Expecting 2560 events.
[13:31:37.339] <TB1> INFO: 2560 events read in total (298ms).
[13:31:37.339] <TB1> INFO: Test took 1158ms.
[13:31:37.341] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:37.645] <TB1> INFO: Expecting 2560 events.
[13:31:38.499] <TB1> INFO: 2560 events read in total (298ms).
[13:31:38.499] <TB1> INFO: Test took 1158ms.
[13:31:38.501] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:38.805] <TB1> INFO: Expecting 2560 events.
[13:31:39.661] <TB1> INFO: 2560 events read in total (299ms).
[13:31:39.661] <TB1> INFO: Test took 1160ms.
[13:31:39.664] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:39.967] <TB1> INFO: Expecting 2560 events.
[13:31:40.821] <TB1> INFO: 2560 events read in total (298ms).
[13:31:40.821] <TB1> INFO: Test took 1157ms.
[13:31:40.824] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:41.127] <TB1> INFO: Expecting 2560 events.
[13:31:41.984] <TB1> INFO: 2560 events read in total (300ms).
[13:31:41.984] <TB1> INFO: Test took 1160ms.
[13:31:41.987] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:42.290] <TB1> INFO: Expecting 2560 events.
[13:31:43.148] <TB1> INFO: 2560 events read in total (301ms).
[13:31:43.149] <TB1> INFO: Test took 1162ms.
[13:31:43.152] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:43.454] <TB1> INFO: Expecting 2560 events.
[13:31:44.308] <TB1> INFO: 2560 events read in total (298ms).
[13:31:44.308] <TB1> INFO: Test took 1157ms.
[13:31:44.310] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:44.614] <TB1> INFO: Expecting 2560 events.
[13:31:45.473] <TB1> INFO: 2560 events read in total (302ms).
[13:31:45.473] <TB1> INFO: Test took 1163ms.
[13:31:45.476] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:45.779] <TB1> INFO: Expecting 2560 events.
[13:31:46.633] <TB1> INFO: 2560 events read in total (298ms).
[13:31:46.633] <TB1> INFO: Test took 1158ms.
[13:31:46.636] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:46.940] <TB1> INFO: Expecting 2560 events.
[13:31:47.794] <TB1> INFO: 2560 events read in total (298ms).
[13:31:47.795] <TB1> INFO: Test took 1160ms.
[13:31:47.796] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:31:48.101] <TB1> INFO: Expecting 2560 events.
[13:31:48.957] <TB1> INFO: 2560 events read in total (300ms).
[13:31:48.957] <TB1> INFO: Test took 1161ms.
[13:31:49.442] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 618 seconds
[13:31:49.442] <TB1> INFO: PH scale (per ROC): 94 87 90 105 99 89 79 98 91 96 86 93 90 90 94 102
[13:31:49.442] <TB1> INFO: PH offset (per ROC): 134 151 145 143 134 149 172 155 153 155 157 143 157 155 144 144
[13:31:49.446] <TB1> INFO: Decoding statistics:
[13:31:49.446] <TB1> INFO: General information:
[13:31:49.446] <TB1> INFO: 16bit words read: 91928
[13:31:49.446] <TB1> INFO: valid events total: 10240
[13:31:49.446] <TB1> INFO: empty events: 7738
[13:31:49.446] <TB1> INFO: valid events with pixels: 2502
[13:31:49.446] <TB1> INFO: valid pixel hits: 2502
[13:31:49.446] <TB1> INFO: Event errors: 0
[13:31:49.446] <TB1> INFO: start marker: 0
[13:31:49.446] <TB1> INFO: stop marker: 0
[13:31:49.446] <TB1> INFO: overflow: 0
[13:31:49.446] <TB1> INFO: invalid 5bit words: 0
[13:31:49.446] <TB1> INFO: invalid XOR eye diagram: 0
[13:31:49.446] <TB1> INFO: frame (failed synchr.): 0
[13:31:49.446] <TB1> INFO: idle data (no TBM trl): 0
[13:31:49.446] <TB1> INFO: no data (only TBM hdr): 0
[13:31:49.446] <TB1> INFO: TBM errors: 0
[13:31:49.446] <TB1> INFO: flawed TBM headers: 0
[13:31:49.446] <TB1> INFO: flawed TBM trailers: 0
[13:31:49.446] <TB1> INFO: event ID mismatches: 0
[13:31:49.446] <TB1> INFO: ROC errors: 0
[13:31:49.446] <TB1> INFO: missing ROC header(s): 0
[13:31:49.446] <TB1> INFO: misplaced readback start: 0
[13:31:49.446] <TB1> INFO: Pixel decoding errors: 0
[13:31:49.446] <TB1> INFO: pixel data incomplete: 0
[13:31:49.446] <TB1> INFO: pixel address: 0
[13:31:49.446] <TB1> INFO: pulse height fill bit: 0
[13:31:49.446] <TB1> INFO: buffer corruption: 0
[13:31:49.625] <TB1> INFO: ######################################################################
[13:31:49.625] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[13:31:49.625] <TB1> INFO: ######################################################################
[13:31:49.634] <TB1> INFO: scanning low vcal = 10
[13:31:49.922] <TB1> INFO: Expecting 41600 events.
[13:31:53.458] <TB1> INFO: 41600 events read in total (2980ms).
[13:31:53.458] <TB1> INFO: Test took 3823ms.
[13:31:53.459] <TB1> INFO: scanning low vcal = 20
[13:31:53.807] <TB1> INFO: Expecting 41600 events.
[13:31:57.371] <TB1> INFO: 41600 events read in total (3008ms).
[13:31:57.371] <TB1> INFO: Test took 3912ms.
[13:31:57.374] <TB1> INFO: scanning low vcal = 30
[13:31:57.720] <TB1> INFO: Expecting 41600 events.
[13:32:01.276] <TB1> INFO: 41600 events read in total (2999ms).
[13:32:01.277] <TB1> INFO: Test took 3903ms.
[13:32:01.278] <TB1> INFO: scanning low vcal = 40
[13:32:01.577] <TB1> INFO: Expecting 41600 events.
[13:32:05.704] <TB1> INFO: 41600 events read in total (3570ms).
[13:32:05.705] <TB1> INFO: Test took 4426ms.
[13:32:05.707] <TB1> INFO: scanning low vcal = 50
[13:32:05.972] <TB1> INFO: Expecting 41600 events.
[13:32:10.178] <TB1> INFO: 41600 events read in total (3649ms).
[13:32:10.178] <TB1> INFO: Test took 4470ms.
[13:32:10.181] <TB1> INFO: scanning low vcal = 60
[13:32:10.456] <TB1> INFO: Expecting 41600 events.
[13:32:14.682] <TB1> INFO: 41600 events read in total (3669ms).
[13:32:14.683] <TB1> INFO: Test took 4502ms.
[13:32:14.685] <TB1> INFO: scanning low vcal = 70
[13:32:14.955] <TB1> INFO: Expecting 41600 events.
[13:32:19.162] <TB1> INFO: 41600 events read in total (3651ms).
[13:32:19.163] <TB1> INFO: Test took 4478ms.
[13:32:19.165] <TB1> INFO: scanning low vcal = 80
[13:32:19.451] <TB1> INFO: Expecting 41600 events.
[13:32:23.677] <TB1> INFO: 41600 events read in total (3669ms).
[13:32:23.677] <TB1> INFO: Test took 4512ms.
[13:32:23.680] <TB1> INFO: scanning low vcal = 90
[13:32:23.967] <TB1> INFO: Expecting 41600 events.
[13:32:28.168] <TB1> INFO: 41600 events read in total (3645ms).
[13:32:28.168] <TB1> INFO: Test took 4488ms.
[13:32:28.172] <TB1> INFO: scanning low vcal = 100
[13:32:28.435] <TB1> INFO: Expecting 41600 events.
[13:32:32.671] <TB1> INFO: 41600 events read in total (3680ms).
[13:32:32.671] <TB1> INFO: Test took 4499ms.
[13:32:32.674] <TB1> INFO: scanning low vcal = 110
[13:32:32.939] <TB1> INFO: Expecting 41600 events.
[13:32:37.137] <TB1> INFO: 41600 events read in total (3642ms).
[13:32:37.137] <TB1> INFO: Test took 4463ms.
[13:32:37.139] <TB1> INFO: scanning low vcal = 120
[13:32:37.415] <TB1> INFO: Expecting 41600 events.
[13:32:41.634] <TB1> INFO: 41600 events read in total (3663ms).
[13:32:41.635] <TB1> INFO: Test took 4495ms.
[13:32:41.637] <TB1> INFO: scanning low vcal = 130
[13:32:41.916] <TB1> INFO: Expecting 41600 events.
[13:32:46.131] <TB1> INFO: 41600 events read in total (3658ms).
[13:32:46.131] <TB1> INFO: Test took 4493ms.
[13:32:46.134] <TB1> INFO: scanning low vcal = 140
[13:32:46.397] <TB1> INFO: Expecting 41600 events.
[13:32:50.600] <TB1> INFO: 41600 events read in total (3646ms).
[13:32:50.600] <TB1> INFO: Test took 4466ms.
[13:32:50.603] <TB1> INFO: scanning low vcal = 150
[13:32:50.868] <TB1> INFO: Expecting 41600 events.
[13:32:55.065] <TB1> INFO: 41600 events read in total (3641ms).
[13:32:55.065] <TB1> INFO: Test took 4462ms.
[13:32:55.068] <TB1> INFO: scanning low vcal = 160
[13:32:55.342] <TB1> INFO: Expecting 41600 events.
[13:32:59.550] <TB1> INFO: 41600 events read in total (3652ms).
[13:32:59.551] <TB1> INFO: Test took 4483ms.
[13:32:59.553] <TB1> INFO: scanning low vcal = 170
[13:32:59.818] <TB1> INFO: Expecting 41600 events.
[13:33:04.033] <TB1> INFO: 41600 events read in total (3658ms).
[13:33:04.033] <TB1> INFO: Test took 4480ms.
[13:33:04.037] <TB1> INFO: scanning low vcal = 180
[13:33:04.330] <TB1> INFO: Expecting 41600 events.
[13:33:08.517] <TB1> INFO: 41600 events read in total (3631ms).
[13:33:08.517] <TB1> INFO: Test took 4480ms.
[13:33:08.520] <TB1> INFO: scanning low vcal = 190
[13:33:08.792] <TB1> INFO: Expecting 41600 events.
[13:33:13.000] <TB1> INFO: 41600 events read in total (3652ms).
[13:33:13.000] <TB1> INFO: Test took 4480ms.
[13:33:13.003] <TB1> INFO: scanning low vcal = 200
[13:33:13.280] <TB1> INFO: Expecting 41600 events.
[13:33:17.493] <TB1> INFO: 41600 events read in total (3657ms).
[13:33:17.494] <TB1> INFO: Test took 4491ms.
[13:33:17.497] <TB1> INFO: scanning low vcal = 210
[13:33:17.763] <TB1> INFO: Expecting 41600 events.
[13:33:21.971] <TB1> INFO: 41600 events read in total (3652ms).
[13:33:21.972] <TB1> INFO: Test took 4475ms.
[13:33:21.975] <TB1> INFO: scanning low vcal = 220
[13:33:22.244] <TB1> INFO: Expecting 41600 events.
[13:33:26.445] <TB1> INFO: 41600 events read in total (3645ms).
[13:33:26.446] <TB1> INFO: Test took 4471ms.
[13:33:26.448] <TB1> INFO: scanning low vcal = 230
[13:33:26.712] <TB1> INFO: Expecting 41600 events.
[13:33:30.918] <TB1> INFO: 41600 events read in total (3650ms).
[13:33:30.918] <TB1> INFO: Test took 4469ms.
[13:33:30.921] <TB1> INFO: scanning low vcal = 240
[13:33:31.185] <TB1> INFO: Expecting 41600 events.
[13:33:35.410] <TB1> INFO: 41600 events read in total (3669ms).
[13:33:35.410] <TB1> INFO: Test took 4489ms.
[13:33:35.413] <TB1> INFO: scanning low vcal = 250
[13:33:35.703] <TB1> INFO: Expecting 41600 events.
[13:33:39.940] <TB1> INFO: 41600 events read in total (3680ms).
[13:33:39.941] <TB1> INFO: Test took 4528ms.
[13:33:39.945] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[13:33:40.208] <TB1> INFO: Expecting 41600 events.
[13:33:44.417] <TB1> INFO: 41600 events read in total (3652ms).
[13:33:44.418] <TB1> INFO: Test took 4473ms.
[13:33:44.420] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[13:33:44.691] <TB1> INFO: Expecting 41600 events.
[13:33:48.907] <TB1> INFO: 41600 events read in total (3660ms).
[13:33:48.908] <TB1> INFO: Test took 4488ms.
[13:33:48.910] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[13:33:49.190] <TB1> INFO: Expecting 41600 events.
[13:33:53.437] <TB1> INFO: 41600 events read in total (3690ms).
[13:33:53.437] <TB1> INFO: Test took 4527ms.
[13:33:53.440] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[13:33:53.716] <TB1> INFO: Expecting 41600 events.
[13:33:57.974] <TB1> INFO: 41600 events read in total (3702ms).
[13:33:57.974] <TB1> INFO: Test took 4533ms.
[13:33:57.977] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[13:33:58.267] <TB1> INFO: Expecting 41600 events.
[13:34:02.443] <TB1> INFO: 41600 events read in total (3619ms).
[13:34:02.444] <TB1> INFO: Test took 4467ms.
[13:34:02.970] <TB1> INFO: PixTestGainPedestal::measure() done
[13:34:36.184] <TB1> INFO: PixTestGainPedestal::fit() done
[13:34:36.184] <TB1> INFO: non-linearity mean: 0.956 0.948 0.953 0.952 0.953 0.957 0.949 0.953 0.953 0.954 0.954 0.960 0.951 0.958 0.954 0.958
[13:34:36.184] <TB1> INFO: non-linearity RMS: 0.005 0.007 0.005 0.005 0.006 0.006 0.007 0.005 0.005 0.006 0.006 0.006 0.006 0.005 0.005 0.004
[13:34:36.184] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[13:34:36.204] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[13:34:36.224] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[13:34:36.248] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[13:34:36.268] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[13:34:36.287] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[13:34:36.326] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[13:34:36.346] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[13:34:36.371] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[13:34:36.395] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[13:34:36.421] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[13:34:36.445] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[13:34:36.466] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[13:34:36.492] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[13:34:36.516] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[13:34:36.543] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[13:34:36.568] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 166 seconds
[13:34:36.568] <TB1> INFO: Decoding statistics:
[13:34:36.568] <TB1> INFO: General information:
[13:34:36.568] <TB1> INFO: 16bit words read: 2662400
[13:34:36.568] <TB1> INFO: valid events total: 166400
[13:34:36.568] <TB1> INFO: empty events: 0
[13:34:36.568] <TB1> INFO: valid events with pixels: 166400
[13:34:36.568] <TB1> INFO: valid pixel hits: 665600
[13:34:36.568] <TB1> INFO: Event errors: 0
[13:34:36.568] <TB1> INFO: start marker: 0
[13:34:36.568] <TB1> INFO: stop marker: 0
[13:34:36.568] <TB1> INFO: overflow: 0
[13:34:36.568] <TB1> INFO: invalid 5bit words: 0
[13:34:36.568] <TB1> INFO: invalid XOR eye diagram: 0
[13:34:36.568] <TB1> INFO: frame (failed synchr.): 0
[13:34:36.568] <TB1> INFO: idle data (no TBM trl): 0
[13:34:36.568] <TB1> INFO: no data (only TBM hdr): 0
[13:34:36.568] <TB1> INFO: TBM errors: 0
[13:34:36.568] <TB1> INFO: flawed TBM headers: 0
[13:34:36.568] <TB1> INFO: flawed TBM trailers: 0
[13:34:36.568] <TB1> INFO: event ID mismatches: 0
[13:34:36.568] <TB1> INFO: ROC errors: 0
[13:34:36.568] <TB1> INFO: missing ROC header(s): 0
[13:34:36.568] <TB1> INFO: misplaced readback start: 0
[13:34:36.568] <TB1> INFO: Pixel decoding errors: 0
[13:34:36.568] <TB1> INFO: pixel data incomplete: 0
[13:34:36.568] <TB1> INFO: pixel address: 0
[13:34:36.568] <TB1> INFO: pulse height fill bit: 0
[13:34:36.568] <TB1> INFO: buffer corruption: 0
[13:34:36.575] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C15.dat
[13:34:36.576] <TB1> INFO: ######################################################################
[13:34:36.576] <TB1> INFO: PixTestReadback::doTest()
[13:34:36.576] <TB1> INFO: ######################################################################
[13:34:36.576] <TB1> INFO: ----------------------------------------------------------------------
[13:34:36.576] <TB1> INFO: PixTestReadback::CalibrateVd()
[13:34:36.576] <TB1> INFO: ----------------------------------------------------------------------
[13:34:46.081] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C0.dat
[13:34:46.081] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C1.dat
[13:34:46.081] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C2.dat
[13:34:46.081] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C3.dat
[13:34:46.082] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C4.dat
[13:34:46.082] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C5.dat
[13:34:46.082] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C6.dat
[13:34:46.082] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C7.dat
[13:34:46.084] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C8.dat
[13:34:46.084] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C9.dat
[13:34:46.084] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C10.dat
[13:34:46.085] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C11.dat
[13:34:46.085] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C12.dat
[13:34:46.085] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C13.dat
[13:34:46.085] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C14.dat
[13:34:46.085] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C15.dat
[13:34:46.120] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[13:34:46.120] <TB1> INFO: ----------------------------------------------------------------------
[13:34:46.120] <TB1> INFO: PixTestReadback::CalibrateVa()
[13:34:46.120] <TB1> INFO: ----------------------------------------------------------------------
[13:34:55.609] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C0.dat
[13:34:55.609] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C1.dat
[13:34:55.609] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C2.dat
[13:34:55.609] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C3.dat
[13:34:55.610] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C4.dat
[13:34:55.610] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C5.dat
[13:34:55.610] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C6.dat
[13:34:55.610] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C7.dat
[13:34:55.610] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C8.dat
[13:34:55.610] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C9.dat
[13:34:55.610] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C10.dat
[13:34:55.610] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C11.dat
[13:34:55.610] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C12.dat
[13:34:55.610] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C13.dat
[13:34:55.610] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C14.dat
[13:34:55.610] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C15.dat
[13:34:55.639] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[13:34:55.639] <TB1> INFO: ----------------------------------------------------------------------
[13:34:55.639] <TB1> INFO: PixTestReadback::readbackVbg()
[13:34:55.639] <TB1> INFO: ----------------------------------------------------------------------
[13:35:02.955] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[13:35:02.955] <TB1> INFO: ----------------------------------------------------------------------
[13:35:02.955] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[13:35:02.955] <TB1> INFO: ----------------------------------------------------------------------
[13:35:02.955] <TB1> INFO: Vbg will be calibrated using Vd calibration
[13:35:02.955] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 143.4calibrated Vbg = 1.18202 :::*/*/*/*/
[13:35:02.955] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 142calibrated Vbg = 1.17605 :::*/*/*/*/
[13:35:02.955] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 147.5calibrated Vbg = 1.17805 :::*/*/*/*/
[13:35:02.955] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 152.5calibrated Vbg = 1.18665 :::*/*/*/*/
[13:35:02.955] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 150.3calibrated Vbg = 1.18748 :::*/*/*/*/
[13:35:02.955] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 154calibrated Vbg = 1.18844 :::*/*/*/*/
[13:35:02.955] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 153.3calibrated Vbg = 1.19819 :::*/*/*/*/
[13:35:02.955] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 158.1calibrated Vbg = 1.18712 :::*/*/*/*/
[13:35:02.955] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 150.1calibrated Vbg = 1.19962 :::*/*/*/*/
[13:35:02.955] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 146calibrated Vbg = 1.19363 :::*/*/*/*/
[13:35:02.955] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 156.8calibrated Vbg = 1.19094 :::*/*/*/*/
[13:35:02.955] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 146.9calibrated Vbg = 1.19041 :::*/*/*/*/
[13:35:02.955] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 148.7calibrated Vbg = 1.18586 :::*/*/*/*/
[13:35:02.955] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 158calibrated Vbg = 1.17706 :::*/*/*/*/
[13:35:02.955] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 152.1calibrated Vbg = 1.17771 :::*/*/*/*/
[13:35:02.955] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 152.1calibrated Vbg = 1.17806 :::*/*/*/*/
[13:35:02.958] <TB1> INFO: ----------------------------------------------------------------------
[13:35:02.958] <TB1> INFO: PixTestReadback::CalibrateIa()
[13:35:02.958] <TB1> INFO: ----------------------------------------------------------------------
[13:37:38.454] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C0.dat
[13:37:38.454] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C1.dat
[13:37:38.454] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C2.dat
[13:37:38.454] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C3.dat
[13:37:38.454] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C4.dat
[13:37:38.454] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C5.dat
[13:37:38.455] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C6.dat
[13:37:38.455] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C7.dat
[13:37:38.455] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C8.dat
[13:37:38.455] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C9.dat
[13:37:38.455] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C10.dat
[13:37:38.455] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C11.dat
[13:37:38.455] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C12.dat
[13:37:38.456] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C13.dat
[13:37:38.456] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C14.dat
[13:37:38.456] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2316_FullQualification_2016-09-08_09h56m_1473321409//002_FulltestPxar_m20//readbackCal_C15.dat
[13:37:38.487] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[13:37:38.488] <TB1> INFO: PixTestReadback::doTest() done
[13:37:38.488] <TB1> INFO: Decoding statistics:
[13:37:38.488] <TB1> INFO: General information:
[13:37:38.488] <TB1> INFO: 16bit words read: 1024
[13:37:38.488] <TB1> INFO: valid events total: 128
[13:37:38.488] <TB1> INFO: empty events: 128
[13:37:38.488] <TB1> INFO: valid events with pixels: 0
[13:37:38.488] <TB1> INFO: valid pixel hits: 0
[13:37:38.488] <TB1> INFO: Event errors: 0
[13:37:38.488] <TB1> INFO: start marker: 0
[13:37:38.488] <TB1> INFO: stop marker: 0
[13:37:38.488] <TB1> INFO: overflow: 0
[13:37:38.488] <TB1> INFO: invalid 5bit words: 0
[13:37:38.488] <TB1> INFO: invalid XOR eye diagram: 0
[13:37:38.488] <TB1> INFO: frame (failed synchr.): 0
[13:37:38.488] <TB1> INFO: idle data (no TBM trl): 0
[13:37:38.488] <TB1> INFO: no data (only TBM hdr): 0
[13:37:38.488] <TB1> INFO: TBM errors: 0
[13:37:38.488] <TB1> INFO: flawed TBM headers: 0
[13:37:38.488] <TB1> INFO: flawed TBM trailers: 0
[13:37:38.488] <TB1> INFO: event ID mismatches: 0
[13:37:38.488] <TB1> INFO: ROC errors: 0
[13:37:38.488] <TB1> INFO: missing ROC header(s): 0
[13:37:38.488] <TB1> INFO: misplaced readback start: 0
[13:37:38.488] <TB1> INFO: Pixel decoding errors: 0
[13:37:38.488] <TB1> INFO: pixel data incomplete: 0
[13:37:38.488] <TB1> INFO: pixel address: 0
[13:37:38.488] <TB1> INFO: pulse height fill bit: 0
[13:37:38.488] <TB1> INFO: buffer corruption: 0
[13:37:38.536] <TB1> INFO: Decoding statistics:
[13:37:38.536] <TB1> INFO: General information:
[13:37:38.536] <TB1> INFO: 16bit words read: 2755352
[13:37:38.536] <TB1> INFO: valid events total: 176768
[13:37:38.536] <TB1> INFO: empty events: 7866
[13:37:38.536] <TB1> INFO: valid events with pixels: 168902
[13:37:38.536] <TB1> INFO: valid pixel hits: 668102
[13:37:38.536] <TB1> INFO: Event errors: 0
[13:37:38.536] <TB1> INFO: start marker: 0
[13:37:38.536] <TB1> INFO: stop marker: 0
[13:37:38.536] <TB1> INFO: overflow: 0
[13:37:38.536] <TB1> INFO: invalid 5bit words: 0
[13:37:38.536] <TB1> INFO: invalid XOR eye diagram: 0
[13:37:38.536] <TB1> INFO: frame (failed synchr.): 0
[13:37:38.536] <TB1> INFO: idle data (no TBM trl): 0
[13:37:38.536] <TB1> INFO: no data (only TBM hdr): 0
[13:37:38.536] <TB1> INFO: TBM errors: 0
[13:37:38.536] <TB1> INFO: flawed TBM headers: 0
[13:37:38.536] <TB1> INFO: flawed TBM trailers: 0
[13:37:38.536] <TB1> INFO: event ID mismatches: 0
[13:37:38.536] <TB1> INFO: ROC errors: 0
[13:37:38.536] <TB1> INFO: missing ROC header(s): 0
[13:37:38.536] <TB1> INFO: misplaced readback start: 0
[13:37:38.536] <TB1> INFO: Pixel decoding errors: 0
[13:37:38.536] <TB1> INFO: pixel data incomplete: 0
[13:37:38.536] <TB1> INFO: pixel address: 0
[13:37:38.536] <TB1> INFO: pulse height fill bit: 0
[13:37:38.536] <TB1> INFO: buffer corruption: 0
[13:37:38.536] <TB1> INFO: enter test to run
[13:37:38.536] <TB1> INFO: test: exit no parameter change
[13:37:38.712] <TB1> QUIET: Connection to board 153 closed.
[13:37:38.792] <TB1> INFO: pXar: this is the end, my friend