[08:34:50.537] <TB0> INFO: *** Welcome to pxar ***
[08:34:50.537] <TB0> INFO: *** Today: 2016/09/07
[08:34:51.034] <TB0> INFO: *** Version: fe94
[08:34:51.034] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters_C15.dat
[08:34:51.035] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//tbmParameters_C0b.dat
[08:34:51.035] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//defaultMaskFile.dat
[08:34:51.035] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//trimParameters_C15.dat
[08:34:51.102] <TB0> INFO: clk: 4
[08:34:51.102] <TB0> INFO: ctr: 4
[08:34:51.102] <TB0> INFO: sda: 19
[08:34:51.102] <TB0> INFO: tin: 9
[08:34:51.102] <TB0> INFO: level: 15
[08:34:51.102] <TB0> INFO: triggerdelay: 0
[08:34:51.102] <TB0> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[08:34:51.102] <TB0> INFO: Log level: INFO
[08:34:51.109] <TB0> INFO: Found DTB DTB_WS6AYH
[08:34:51.120] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[08:34:51.122] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[08:34:51.123] <TB0> INFO: RPC call hashes of host and DTB match: 484264910
[08:34:52.654] <TB0> INFO: DUT info:
[08:34:52.654] <TB0> INFO: The DUT currently contains the following objects:
[08:34:52.654] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[08:34:52.654] <TB0> INFO: TBM Core alpha (0): 7 registers set
[08:34:52.654] <TB0> INFO: TBM Core beta (1): 7 registers set
[08:34:52.654] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:34:52.654] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:34:52.654] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:34:52.654] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:34:52.654] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:34:52.654] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:34:52.654] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:34:52.654] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:34:52.654] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:34:52.654] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:34:52.654] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:34:52.654] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:34:52.654] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:34:52.654] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:34:52.654] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:34:52.654] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:34:52.654] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:34:53.055] <TB0> INFO: enter 'restricted' command line mode
[08:34:53.055] <TB0> INFO: enter test to run
[08:34:53.055] <TB0> INFO: test: pretest no parameter change
[08:34:53.055] <TB0> INFO: running: pretest
[08:34:53.059] <TB0> INFO: ----------------------------------------------------------------------
[08:34:53.059] <TB0> INFO: PixTestPretest::programROC()
[08:34:53.059] <TB0> INFO: ----------------------------------------------------------------------
[08:35:11.073] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[08:35:11.073] <TB0> INFO: IA differences per ROC: 16.1 17.7 18.5 16.9 18.5 17.7 17.7 20.9 19.3 16.9 19.3 18.5 20.9 18.5 16.9 16.9
[08:35:11.170] <TB0> INFO: enter test to run
[08:35:11.170] <TB0> INFO: test: pretest no parameter change
[08:35:11.170] <TB0> INFO: running: pretest
[08:35:11.171] <TB0> INFO: ----------------------------------------------------------------------
[08:35:11.171] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[08:35:11.171] <TB0> INFO: ----------------------------------------------------------------------
[08:35:32.428] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 394.7 mA = 24.6687 mA/ROC
[08:35:32.428] <TB0> INFO: i(loss) [mA/ROC]: 20.1 20.1 20.9 20.1 20.1 20.1 20.1 20.1 20.9 20.1 20.9 19.3 20.1 20.9 20.1 19.3
[08:35:32.460] <TB0> INFO: enter test to run
[08:35:32.460] <TB0> INFO: test: pretest no parameter change
[08:35:32.460] <TB0> INFO: running: pretest
[08:35:32.460] <TB0> INFO: ----------------------------------------------------------------------
[08:35:32.460] <TB0> INFO: PixTestPretest::findTiming()
[08:35:32.460] <TB0> INFO: ----------------------------------------------------------------------
[08:35:32.460] <TB0> INFO: PixTestCmd::init()

[08:37:02.844] <TB0> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[08:37:02.845] <TB0> INFO: (success/tries = 100/100), width = 4
[08:37:02.846] <TB0> INFO: enter test to run
[08:37:02.846] <TB0> INFO: test: pretest no parameter change
[08:37:02.846] <TB0> INFO: running: pretest
[08:37:02.847] <TB0> INFO: ----------------------------------------------------------------------
[08:37:02.847] <TB0> INFO: PixTestPretest::findWorkingPixel()
[08:37:02.847] <TB0> INFO: ----------------------------------------------------------------------
[08:37:02.942] <TB0> INFO: Expecting 231680 events.


[08:37:13.236] <TB0> INFO: 231680 events read in total (9738ms).
[08:37:13.240] <TB0> INFO: Test took 10387ms.
[08:37:13.465] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[08:37:13.537] <TB0> INFO: enter test to run
[08:37:13.537] <TB0> INFO: test: pretest no parameter change
[08:37:13.537] <TB0> INFO: running: pretest
[08:37:13.539] <TB0> INFO: ----------------------------------------------------------------------
[08:37:13.539] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[08:37:13.539] <TB0> INFO: ----------------------------------------------------------------------
[08:37:13.630] <TB0> INFO: Expecting 231680 events.
[08:37:23.892] <TB0> INFO: 231680 events read in total (9705ms).
[08:37:23.896] <TB0> INFO: Test took 10354ms.
[08:37:24.140] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[08:37:24.140] <TB0> INFO: CalDel: 142 133 161 174 142 133 147 135 136 149 141 144 149 153 135 144
[08:37:24.140] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[08:37:24.177] <TB0> INFO: enter test to run
[08:37:24.177] <TB0> INFO: test: pretest no parameter change
[08:37:24.177] <TB0> INFO: running: pretest
[08:37:24.178] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters_C0.dat
[08:37:24.178] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters_C1.dat
[08:37:24.178] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters_C2.dat
[08:37:24.178] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters_C3.dat
[08:37:24.178] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters_C4.dat
[08:37:24.178] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters_C5.dat
[08:37:24.178] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters_C6.dat
[08:37:24.178] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters_C7.dat
[08:37:24.178] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters_C8.dat
[08:37:24.179] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters_C9.dat
[08:37:24.179] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters_C10.dat
[08:37:24.179] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters_C11.dat
[08:37:24.179] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters_C12.dat
[08:37:24.179] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters_C13.dat
[08:37:24.179] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters_C14.dat
[08:37:24.179] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters_C15.dat
[08:37:24.179] <TB0> INFO: enter test to run
[08:37:24.179] <TB0> INFO: test: fulltest no parameter change
[08:37:24.179] <TB0> INFO: running: fulltest
[08:37:24.179] <TB0> INFO: ######################################################################
[08:37:24.179] <TB0> INFO: PixTestFullTest::doTest()
[08:37:24.179] <TB0> INFO: ######################################################################
[08:37:24.181] <TB0> INFO: ######################################################################
[08:37:24.181] <TB0> INFO: PixTestAlive::doTest()
[08:37:24.181] <TB0> INFO: ######################################################################
[08:37:24.182] <TB0> INFO: ----------------------------------------------------------------------
[08:37:24.182] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:37:24.182] <TB0> INFO: ----------------------------------------------------------------------
[08:37:24.447] <TB0> INFO: Expecting 41600 events.
[08:37:28.226] <TB0> INFO: 41600 events read in total (3222ms).
[08:37:28.227] <TB0> INFO: Test took 4044ms.
[08:37:28.453] <TB0> INFO: PixTestAlive::aliveTest() done
[08:37:28.453] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:37:28.456] <TB0> INFO: ----------------------------------------------------------------------
[08:37:28.456] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:37:28.456] <TB0> INFO: ----------------------------------------------------------------------
[08:37:28.722] <TB0> INFO: Expecting 41600 events.
[08:37:31.737] <TB0> INFO: 41600 events read in total (2459ms).
[08:37:31.737] <TB0> INFO: Test took 3280ms.
[08:37:31.738] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:37:31.981] <TB0> INFO: PixTestAlive::maskTest() done
[08:37:31.981] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:37:31.983] <TB0> INFO: ----------------------------------------------------------------------
[08:37:31.983] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:37:31.983] <TB0> INFO: ----------------------------------------------------------------------
[08:37:32.256] <TB0> INFO: Expecting 41600 events.
[08:37:36.072] <TB0> INFO: 41600 events read in total (3260ms).
[08:37:36.072] <TB0> INFO: Test took 4088ms.
[08:37:36.301] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[08:37:36.301] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:37:36.301] <TB0> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[08:37:36.301] <TB0> INFO: Decoding statistics:
[08:37:36.301] <TB0> INFO: General information:
[08:37:36.301] <TB0> INFO: 16bit words read: 0
[08:37:36.301] <TB0> INFO: valid events total: 0
[08:37:36.301] <TB0> INFO: empty events: 0
[08:37:36.301] <TB0> INFO: valid events with pixels: 0
[08:37:36.301] <TB0> INFO: valid pixel hits: 0
[08:37:36.301] <TB0> INFO: Event errors: 0
[08:37:36.301] <TB0> INFO: start marker: 0
[08:37:36.301] <TB0> INFO: stop marker: 0
[08:37:36.301] <TB0> INFO: overflow: 0
[08:37:36.301] <TB0> INFO: invalid 5bit words: 0
[08:37:36.301] <TB0> INFO: invalid XOR eye diagram: 0
[08:37:36.301] <TB0> INFO: frame (failed synchr.): 0
[08:37:36.301] <TB0> INFO: idle data (no TBM trl): 0
[08:37:36.301] <TB0> INFO: no data (only TBM hdr): 0
[08:37:36.301] <TB0> INFO: TBM errors: 0
[08:37:36.301] <TB0> INFO: flawed TBM headers: 0
[08:37:36.301] <TB0> INFO: flawed TBM trailers: 0
[08:37:36.301] <TB0> INFO: event ID mismatches: 0
[08:37:36.302] <TB0> INFO: ROC errors: 0
[08:37:36.302] <TB0> INFO: missing ROC header(s): 0
[08:37:36.302] <TB0> INFO: misplaced readback start: 0
[08:37:36.302] <TB0> INFO: Pixel decoding errors: 0
[08:37:36.302] <TB0> INFO: pixel data incomplete: 0
[08:37:36.302] <TB0> INFO: pixel address: 0
[08:37:36.302] <TB0> INFO: pulse height fill bit: 0
[08:37:36.302] <TB0> INFO: buffer corruption: 0
[08:37:36.317] <TB0> INFO: ######################################################################
[08:37:36.317] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[08:37:36.317] <TB0> INFO: ######################################################################
[08:37:36.320] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[08:37:36.340] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[08:37:36.340] <TB0> INFO: run 1 of 1
[08:37:36.606] <TB0> INFO: Expecting 3120000 events.
[08:38:17.913] <TB0> INFO: 851545 events read in total (40750ms).
[08:38:57.874] <TB0> INFO: 1691625 events read in total (80711ms).
[08:39:38.023] <TB0> INFO: 2540615 events read in total (120860ms).
[08:40:05.466] <TB0> INFO: 3120000 events read in total (148303ms).
[08:40:05.507] <TB0> INFO: Test took 149167ms.
[08:40:28.439] <TB0> INFO: PixTestBBMap::doTest() done, duration: 172 seconds
[08:40:28.439] <TB0> INFO: number of dead bumps (per ROC): 0 0 1 0 0 1 1 0 2 1 3 2 3 0 1 0
[08:40:28.440] <TB0> INFO: separation cut (per ROC): 84 99 89 78 86 86 81 90 100 89 87 86 85 93 69 76
[08:40:28.440] <TB0> INFO: Decoding statistics:
[08:40:28.440] <TB0> INFO: General information:
[08:40:28.440] <TB0> INFO: 16bit words read: 0
[08:40:28.440] <TB0> INFO: valid events total: 0
[08:40:28.440] <TB0> INFO: empty events: 0
[08:40:28.440] <TB0> INFO: valid events with pixels: 0
[08:40:28.440] <TB0> INFO: valid pixel hits: 0
[08:40:28.440] <TB0> INFO: Event errors: 0
[08:40:28.440] <TB0> INFO: start marker: 0
[08:40:28.440] <TB0> INFO: stop marker: 0
[08:40:28.440] <TB0> INFO: overflow: 0
[08:40:28.440] <TB0> INFO: invalid 5bit words: 0
[08:40:28.440] <TB0> INFO: invalid XOR eye diagram: 0
[08:40:28.440] <TB0> INFO: frame (failed synchr.): 0
[08:40:28.440] <TB0> INFO: idle data (no TBM trl): 0
[08:40:28.440] <TB0> INFO: no data (only TBM hdr): 0
[08:40:28.440] <TB0> INFO: TBM errors: 0
[08:40:28.440] <TB0> INFO: flawed TBM headers: 0
[08:40:28.440] <TB0> INFO: flawed TBM trailers: 0
[08:40:28.440] <TB0> INFO: event ID mismatches: 0
[08:40:28.440] <TB0> INFO: ROC errors: 0
[08:40:28.440] <TB0> INFO: missing ROC header(s): 0
[08:40:28.440] <TB0> INFO: misplaced readback start: 0
[08:40:28.440] <TB0> INFO: Pixel decoding errors: 0
[08:40:28.440] <TB0> INFO: pixel data incomplete: 0
[08:40:28.440] <TB0> INFO: pixel address: 0
[08:40:28.440] <TB0> INFO: pulse height fill bit: 0
[08:40:28.440] <TB0> INFO: buffer corruption: 0
[08:40:28.511] <TB0> INFO: ######################################################################
[08:40:28.511] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:40:28.511] <TB0> INFO: ######################################################################
[08:40:28.511] <TB0> INFO: ----------------------------------------------------------------------
[08:40:28.511] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:40:28.511] <TB0> INFO: ----------------------------------------------------------------------
[08:40:28.511] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[08:40:28.520] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[08:40:28.520] <TB0> INFO: run 1 of 1
[08:40:28.787] <TB0> INFO: Expecting 26208000 events.
[08:41:02.292] <TB0> INFO: 859100 events read in total (32948ms).
[08:41:35.007] <TB0> INFO: 1707350 events read in total (65663ms).
[08:42:08.086] <TB0> INFO: 2556100 events read in total (98742ms).
[08:42:41.462] <TB0> INFO: 3401850 events read in total (132118ms).
[08:43:14.676] <TB0> INFO: 4249650 events read in total (165332ms).
[08:43:48.459] <TB0> INFO: 5094200 events read in total (199115ms).
[08:44:21.102] <TB0> INFO: 5939700 events read in total (231758ms).
[08:44:53.791] <TB0> INFO: 6786350 events read in total (264447ms).
[08:45:26.391] <TB0> INFO: 7632100 events read in total (297047ms).
[08:45:58.679] <TB0> INFO: 8475000 events read in total (329335ms).
[08:46:31.210] <TB0> INFO: 9320450 events read in total (361866ms).
[08:47:03.407] <TB0> INFO: 10163600 events read in total (394063ms).
[08:47:35.618] <TB0> INFO: 11005600 events read in total (426274ms).
[08:48:08.523] <TB0> INFO: 11850900 events read in total (459179ms).
[08:48:40.830] <TB0> INFO: 12696050 events read in total (491486ms).
[08:49:13.519] <TB0> INFO: 13532500 events read in total (524175ms).
[08:49:46.391] <TB0> INFO: 14367650 events read in total (557047ms).
[08:50:18.754] <TB0> INFO: 15203350 events read in total (589410ms).
[08:50:51.871] <TB0> INFO: 16036950 events read in total (622527ms).
[08:51:24.229] <TB0> INFO: 16869800 events read in total (654885ms).
[08:51:56.823] <TB0> INFO: 17702400 events read in total (687479ms).
[08:52:29.240] <TB0> INFO: 18535250 events read in total (719896ms).
[08:53:01.892] <TB0> INFO: 19366900 events read in total (752548ms).
[08:53:34.022] <TB0> INFO: 20197800 events read in total (784678ms).
[08:54:06.644] <TB0> INFO: 21028800 events read in total (817300ms).
[08:54:39.052] <TB0> INFO: 21863300 events read in total (849708ms).
[08:55:11.558] <TB0> INFO: 22693850 events read in total (882214ms).
[08:55:43.835] <TB0> INFO: 23526500 events read in total (914491ms).
[08:56:16.402] <TB0> INFO: 24360550 events read in total (947058ms).
[08:56:49.060] <TB0> INFO: 25195150 events read in total (979716ms).
[08:57:21.498] <TB0> INFO: 26038950 events read in total (1012154ms).
[08:57:28.362] <TB0> INFO: 26208000 events read in total (1019018ms).
[08:57:28.407] <TB0> INFO: Test took 1019887ms.
[08:57:28.819] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:57:30.533] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:57:32.044] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:57:33.473] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:57:34.934] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:57:36.404] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:57:37.820] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:57:39.227] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:57:40.614] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:57:42.009] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:57:43.402] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:57:44.827] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:57:46.239] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:57:47.707] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:57:49.111] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:57:50.752] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[08:57:52.364] <TB0> INFO: PixTestScurves::scurves() done
[08:57:52.364] <TB0> INFO: Vcal mean: 83.08 87.42 85.62 76.16 80.78 81.79 79.15 85.08 97.91 85.34 76.68 80.59 74.75 85.45 74.78 72.83
[08:57:52.364] <TB0> INFO: Vcal RMS: 4.43 4.82 4.70 3.91 4.10 4.37 4.05 4.73 5.38 5.37 3.95 4.15 4.25 4.64 4.03 4.12
[08:57:52.364] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1043 seconds
[08:57:52.364] <TB0> INFO: Decoding statistics:
[08:57:52.364] <TB0> INFO: General information:
[08:57:52.364] <TB0> INFO: 16bit words read: 0
[08:57:52.364] <TB0> INFO: valid events total: 0
[08:57:52.364] <TB0> INFO: empty events: 0
[08:57:52.364] <TB0> INFO: valid events with pixels: 0
[08:57:52.364] <TB0> INFO: valid pixel hits: 0
[08:57:52.364] <TB0> INFO: Event errors: 0
[08:57:52.364] <TB0> INFO: start marker: 0
[08:57:52.364] <TB0> INFO: stop marker: 0
[08:57:52.364] <TB0> INFO: overflow: 0
[08:57:52.364] <TB0> INFO: invalid 5bit words: 0
[08:57:52.364] <TB0> INFO: invalid XOR eye diagram: 0
[08:57:52.364] <TB0> INFO: frame (failed synchr.): 0
[08:57:52.364] <TB0> INFO: idle data (no TBM trl): 0
[08:57:52.364] <TB0> INFO: no data (only TBM hdr): 0
[08:57:52.364] <TB0> INFO: TBM errors: 0
[08:57:52.364] <TB0> INFO: flawed TBM headers: 0
[08:57:52.364] <TB0> INFO: flawed TBM trailers: 0
[08:57:52.364] <TB0> INFO: event ID mismatches: 0
[08:57:52.364] <TB0> INFO: ROC errors: 0
[08:57:52.364] <TB0> INFO: missing ROC header(s): 0
[08:57:52.364] <TB0> INFO: misplaced readback start: 0
[08:57:52.364] <TB0> INFO: Pixel decoding errors: 0
[08:57:52.364] <TB0> INFO: pixel data incomplete: 0
[08:57:52.364] <TB0> INFO: pixel address: 0
[08:57:52.364] <TB0> INFO: pulse height fill bit: 0
[08:57:52.364] <TB0> INFO: buffer corruption: 0
[08:57:52.436] <TB0> INFO: ######################################################################
[08:57:52.436] <TB0> INFO: PixTestTrim::doTest()
[08:57:52.436] <TB0> INFO: ######################################################################
[08:57:52.437] <TB0> INFO: ----------------------------------------------------------------------
[08:57:52.437] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[08:57:52.437] <TB0> INFO: ----------------------------------------------------------------------
[08:57:52.517] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[08:57:52.517] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[08:57:52.526] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[08:57:52.526] <TB0> INFO: run 1 of 1
[08:57:52.787] <TB0> INFO: Expecting 6281600 events.
[08:58:31.797] <TB0> INFO: 1040220 events read in total (38453ms).
[08:59:10.429] <TB0> INFO: 2073970 events read in total (77085ms).
[08:59:49.226] <TB0> INFO: 3101290 events read in total (115882ms).
[09:00:27.829] <TB0> INFO: 4135340 events read in total (154485ms).
[09:01:06.348] <TB0> INFO: 5173690 events read in total (193004ms).
[09:01:44.867] <TB0> INFO: 6217610 events read in total (231523ms).
[09:01:47.543] <TB0> INFO: 6281600 events read in total (234199ms).
[09:01:47.573] <TB0> INFO: Test took 235047ms.
[09:02:06.973] <TB0> INFO: ROC 0 VthrComp = 90
[09:02:06.973] <TB0> INFO: ROC 1 VthrComp = 98
[09:02:06.973] <TB0> INFO: ROC 2 VthrComp = 94
[09:02:06.973] <TB0> INFO: ROC 3 VthrComp = 83
[09:02:06.973] <TB0> INFO: ROC 4 VthrComp = 88
[09:02:06.973] <TB0> INFO: ROC 5 VthrComp = 86
[09:02:06.973] <TB0> INFO: ROC 6 VthrComp = 87
[09:02:06.973] <TB0> INFO: ROC 7 VthrComp = 93
[09:02:06.973] <TB0> INFO: ROC 8 VthrComp = 105
[09:02:06.974] <TB0> INFO: ROC 9 VthrComp = 89
[09:02:06.974] <TB0> INFO: ROC 10 VthrComp = 87
[09:02:06.974] <TB0> INFO: ROC 11 VthrComp = 88
[09:02:06.974] <TB0> INFO: ROC 12 VthrComp = 83
[09:02:06.974] <TB0> INFO: ROC 13 VthrComp = 94
[09:02:06.974] <TB0> INFO: ROC 14 VthrComp = 81
[09:02:06.974] <TB0> INFO: ROC 15 VthrComp = 82
[09:02:06.974] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[09:02:06.974] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:02:06.984] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:02:06.984] <TB0> INFO: run 1 of 1
[09:02:07.249] <TB0> INFO: Expecting 6281600 events.
[09:02:42.348] <TB0> INFO: 753170 events read in total (34543ms).
[09:03:17.211] <TB0> INFO: 1504560 events read in total (69406ms).
[09:03:52.438] <TB0> INFO: 2255620 events read in total (104633ms).
[09:04:27.356] <TB0> INFO: 3007270 events read in total (139551ms).
[09:05:01.995] <TB0> INFO: 3754500 events read in total (174190ms).
[09:05:36.354] <TB0> INFO: 4498030 events read in total (208549ms).
[09:06:11.141] <TB0> INFO: 5240610 events read in total (243336ms).
[09:06:46.056] <TB0> INFO: 5982700 events read in total (278251ms).
[09:07:00.092] <TB0> INFO: 6281600 events read in total (292287ms).
[09:07:00.142] <TB0> INFO: Test took 293158ms.
[09:07:25.062] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 57.3828 for pixel 22/3 mean/min/max = 45.1409/32.7242/57.5577
[09:07:25.062] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 56.1568 for pixel 11/1 mean/min/max = 43.7564/31.2154/56.2974
[09:07:25.062] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 56.293 for pixel 24/23 mean/min/max = 44.0895/31.567/56.612
[09:07:25.063] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 55.324 for pixel 22/50 mean/min/max = 43.7613/32.1189/55.4038
[09:07:25.063] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 56.4089 for pixel 51/16 mean/min/max = 44.7718/33.0672/56.4764
[09:07:25.063] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 56.0013 for pixel 5/1 mean/min/max = 44.3022/32.4849/56.1195
[09:07:25.063] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 56.5387 for pixel 18/17 mean/min/max = 44.2802/31.8343/56.7261
[09:07:25.064] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 56.9874 for pixel 1/3 mean/min/max = 44.5802/32.0901/57.0704
[09:07:25.064] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 60.147 for pixel 12/29 mean/min/max = 46.6301/33.0859/60.1743
[09:07:25.064] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 59.7579 for pixel 1/61 mean/min/max = 46.4523/32.6822/60.2223
[09:07:25.064] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 55.0913 for pixel 17/11 mean/min/max = 43.7414/32.2687/55.2142
[09:07:25.065] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 56.9452 for pixel 14/78 mean/min/max = 44.8177/32.6693/56.9661
[09:07:25.065] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 55.9936 for pixel 16/1 mean/min/max = 44.1996/32.3511/56.0481
[09:07:25.065] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 56.4452 for pixel 4/3 mean/min/max = 44.8341/32.9096/56.7587
[09:07:25.065] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 55.0206 for pixel 23/65 mean/min/max = 43.5925/32.0128/55.1721
[09:07:25.066] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 54.9529 for pixel 23/70 mean/min/max = 43.5184/32.0364/55.0004
[09:07:25.067] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:07:25.155] <TB0> INFO: Expecting 514560 events.
[09:07:38.052] <TB0> INFO: 514560 events read in total (12341ms).
[09:07:38.058] <TB0> INFO: Expecting 514560 events.
[09:07:50.866] <TB0> INFO: 514560 events read in total (12394ms).
[09:07:50.874] <TB0> INFO: Expecting 514560 events.
[09:08:03.584] <TB0> INFO: 514560 events read in total (12298ms).
[09:08:03.593] <TB0> INFO: Expecting 514560 events.
[09:08:16.526] <TB0> INFO: 514560 events read in total (12519ms).
[09:08:16.537] <TB0> INFO: Expecting 514560 events.
[09:08:29.347] <TB0> INFO: 514560 events read in total (12403ms).
[09:08:29.360] <TB0> INFO: Expecting 514560 events.
[09:08:42.063] <TB0> INFO: 514560 events read in total (12293ms).
[09:08:42.078] <TB0> INFO: Expecting 514560 events.
[09:08:54.983] <TB0> INFO: 514560 events read in total (12500ms).
[09:08:55.000] <TB0> INFO: Expecting 514560 events.
[09:09:07.807] <TB0> INFO: 514560 events read in total (12404ms).
[09:09:07.827] <TB0> INFO: Expecting 514560 events.
[09:09:20.731] <TB0> INFO: 514560 events read in total (12502ms).
[09:09:20.755] <TB0> INFO: Expecting 514560 events.
[09:09:33.558] <TB0> INFO: 514560 events read in total (12401ms).
[09:09:33.590] <TB0> INFO: Expecting 514560 events.
[09:09:46.598] <TB0> INFO: 514560 events read in total (12607ms).
[09:09:46.629] <TB0> INFO: Expecting 514560 events.
[09:09:59.479] <TB0> INFO: 514560 events read in total (12448ms).
[09:09:59.507] <TB0> INFO: Expecting 514560 events.
[09:10:12.279] <TB0> INFO: 514560 events read in total (12370ms).
[09:10:12.311] <TB0> INFO: Expecting 514560 events.
[09:10:25.199] <TB0> INFO: 514560 events read in total (12486ms).
[09:10:25.240] <TB0> INFO: Expecting 514560 events.
[09:10:37.926] <TB0> INFO: 514560 events read in total (12284ms).
[09:10:37.970] <TB0> INFO: Expecting 514560 events.
[09:10:50.870] <TB0> INFO: 514560 events read in total (12498ms).
[09:10:50.907] <TB0> INFO: Test took 205840ms.
[09:10:52.019] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:10:52.028] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:10:52.029] <TB0> INFO: run 1 of 1
[09:10:52.293] <TB0> INFO: Expecting 6281600 events.
[09:11:27.172] <TB0> INFO: 741310 events read in total (34323ms).
[09:12:01.604] <TB0> INFO: 1481540 events read in total (68755ms).
[09:12:36.851] <TB0> INFO: 2221380 events read in total (104002ms).
[09:13:12.191] <TB0> INFO: 2961750 events read in total (139342ms).
[09:13:46.978] <TB0> INFO: 3697560 events read in total (174129ms).
[09:14:21.661] <TB0> INFO: 4429500 events read in total (208812ms).
[09:14:55.938] <TB0> INFO: 5160270 events read in total (243089ms).
[09:15:30.120] <TB0> INFO: 5890330 events read in total (277271ms).
[09:15:48.923] <TB0> INFO: 6281600 events read in total (296074ms).
[09:15:48.962] <TB0> INFO: Test took 296933ms.
[09:16:13.535] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 1.004013 .. 49.023432
[09:16:13.613] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 1 .. 59 (-1/-1) hits flags = 528 (plus default)
[09:16:13.622] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:16:13.622] <TB0> INFO: run 1 of 1
[09:16:13.887] <TB0> INFO: Expecting 2454400 events.
[09:16:53.042] <TB0> INFO: 933720 events read in total (38599ms).
[09:17:31.047] <TB0> INFO: 1860210 events read in total (76604ms).
[09:17:55.446] <TB0> INFO: 2454400 events read in total (101004ms).
[09:17:55.476] <TB0> INFO: Test took 101855ms.
[09:18:11.103] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 18.698829 .. 44.556561
[09:18:11.189] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 8 .. 54 (-1/-1) hits flags = 528 (plus default)
[09:18:11.199] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:18:11.199] <TB0> INFO: run 1 of 1
[09:18:11.487] <TB0> INFO: Expecting 1955200 events.
[09:18:50.042] <TB0> INFO: 930120 events read in total (37999ms).
[09:19:28.128] <TB0> INFO: 1856260 events read in total (76085ms).
[09:19:32.534] <TB0> INFO: 1955200 events read in total (80491ms).
[09:19:32.548] <TB0> INFO: Test took 81350ms.
[09:19:46.741] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 23.140114 .. 40.877099
[09:19:46.821] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 13 .. 50 (-1/-1) hits flags = 528 (plus default)
[09:19:46.830] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:19:46.830] <TB0> INFO: run 1 of 1
[09:19:47.104] <TB0> INFO: Expecting 1580800 events.
[09:20:25.737] <TB0> INFO: 935770 events read in total (38077ms).
[09:20:52.414] <TB0> INFO: 1580800 events read in total (64754ms).
[09:20:52.426] <TB0> INFO: Test took 65597ms.
[09:21:06.174] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 24.982267 .. 39.679035
[09:21:06.255] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 14 .. 49 (-1/-1) hits flags = 528 (plus default)
[09:21:06.264] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:21:06.264] <TB0> INFO: run 1 of 1
[09:21:06.527] <TB0> INFO: Expecting 1497600 events.
[09:21:45.836] <TB0> INFO: 938470 events read in total (38752ms).
[09:22:08.727] <TB0> INFO: 1497600 events read in total (61643ms).
[09:22:08.735] <TB0> INFO: Test took 62471ms.
[09:22:21.273] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[09:22:21.273] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:22:21.281] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:22:21.281] <TB0> INFO: run 1 of 1
[09:22:21.544] <TB0> INFO: Expecting 1705600 events.
[09:22:59.475] <TB0> INFO: 879810 events read in total (37374ms).
[09:23:34.501] <TB0> INFO: 1705600 events read in total (72400ms).
[09:23:34.524] <TB0> INFO: Test took 73244ms.
[09:23:49.304] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:23:49.323] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:23:49.323] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:23:49.323] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:23:49.323] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:23:49.323] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:23:49.323] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:23:49.323] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:23:49.323] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:23:49.323] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:23:49.323] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:23:49.324] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:23:49.324] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:23:49.324] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:23:49.324] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:23:49.324] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:23:49.324] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//trimParameters35_C0.dat
[09:23:49.331] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//trimParameters35_C1.dat
[09:23:49.338] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//trimParameters35_C2.dat
[09:23:49.344] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//trimParameters35_C3.dat
[09:23:49.351] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//trimParameters35_C4.dat
[09:23:49.358] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//trimParameters35_C5.dat
[09:23:49.365] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//trimParameters35_C6.dat
[09:23:49.372] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//trimParameters35_C7.dat
[09:23:49.382] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//trimParameters35_C8.dat
[09:23:49.393] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//trimParameters35_C9.dat
[09:23:49.404] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//trimParameters35_C10.dat
[09:23:49.415] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//trimParameters35_C11.dat
[09:23:49.426] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//trimParameters35_C12.dat
[09:23:49.437] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//trimParameters35_C13.dat
[09:23:49.448] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//trimParameters35_C14.dat
[09:23:49.454] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//trimParameters35_C15.dat
[09:23:49.461] <TB0> INFO: PixTestTrim::trimTest() done
[09:23:49.461] <TB0> INFO: vtrim: 101 107 90 89 84 95 115 100 116 102 112 104 94 104 85 95
[09:23:49.461] <TB0> INFO: vthrcomp: 90 98 94 83 88 86 87 93 105 89 87 88 83 94 81 82
[09:23:49.461] <TB0> INFO: vcal mean: 35.00 34.94 34.95 34.99 34.98 34.97 34.97 34.95 34.98 34.93 34.97 34.97 34.94 34.99 34.98 34.95
[09:23:49.461] <TB0> INFO: vcal RMS: 0.84 0.82 0.85 0.80 0.79 0.79 0.80 0.79 0.81 0.84 0.76 0.79 0.78 0.81 0.82 0.76
[09:23:49.461] <TB0> INFO: bits mean: 9.79 9.92 9.84 9.99 9.58 9.55 10.01 9.50 8.85 9.12 10.03 9.64 9.97 9.60 10.10 10.28
[09:23:49.461] <TB0> INFO: bits RMS: 2.46 2.69 2.70 2.52 2.53 2.63 2.58 2.76 2.73 2.68 2.48 2.57 2.54 2.59 2.50 2.45
[09:23:49.467] <TB0> INFO: ----------------------------------------------------------------------
[09:23:49.467] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[09:23:49.467] <TB0> INFO: ----------------------------------------------------------------------
[09:23:49.470] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[09:23:49.479] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:23:49.479] <TB0> INFO: run 1 of 1
[09:23:49.751] <TB0> INFO: Expecting 4160000 events.
[09:24:31.506] <TB0> INFO: 893240 events read in total (41198ms).
[09:25:14.123] <TB0> INFO: 1781605 events read in total (83815ms).
[09:25:55.317] <TB0> INFO: 2664040 events read in total (125010ms).
[09:26:36.156] <TB0> INFO: 3543455 events read in total (165848ms).
[09:27:04.630] <TB0> INFO: 4160000 events read in total (194322ms).
[09:27:04.686] <TB0> INFO: Test took 195207ms.
[09:27:34.769] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 154 (-1/-1) hits flags = 528 (plus default)
[09:27:34.778] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:27:34.779] <TB0> INFO: run 1 of 1
[09:27:35.041] <TB0> INFO: Expecting 3224000 events.
[09:28:18.192] <TB0> INFO: 950040 events read in total (42594ms).
[09:29:00.517] <TB0> INFO: 1890405 events read in total (84919ms).
[09:29:42.365] <TB0> INFO: 2821670 events read in total (126767ms).
[09:30:00.298] <TB0> INFO: 3224000 events read in total (144700ms).
[09:30:00.327] <TB0> INFO: Test took 145548ms.
[09:30:25.071] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 528 (plus default)
[09:30:25.080] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:30:25.080] <TB0> INFO: run 1 of 1
[09:30:25.344] <TB0> INFO: Expecting 3120000 events.
[09:31:08.273] <TB0> INFO: 963405 events read in total (42373ms).
[09:31:51.282] <TB0> INFO: 1915100 events read in total (85382ms).
[09:32:32.811] <TB0> INFO: 2858830 events read in total (126911ms).
[09:32:44.479] <TB0> INFO: 3120000 events read in total (138579ms).
[09:32:44.514] <TB0> INFO: Test took 139434ms.
[09:33:09.630] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 147 (-1/-1) hits flags = 528 (plus default)
[09:33:09.639] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:33:09.639] <TB0> INFO: run 1 of 1
[09:33:09.898] <TB0> INFO: Expecting 3078400 events.
[09:33:52.859] <TB0> INFO: 967815 events read in total (42405ms).
[09:34:34.604] <TB0> INFO: 1923950 events read in total (84150ms).
[09:35:17.156] <TB0> INFO: 2872385 events read in total (126702ms).
[09:35:26.528] <TB0> INFO: 3078400 events read in total (136074ms).
[09:35:26.559] <TB0> INFO: Test took 136920ms.
[09:35:51.007] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 146 (-1/-1) hits flags = 528 (plus default)
[09:35:51.016] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:35:51.016] <TB0> INFO: run 1 of 1
[09:35:51.303] <TB0> INFO: Expecting 3057600 events.
[09:36:33.822] <TB0> INFO: 970230 events read in total (41963ms).
[09:37:16.107] <TB0> INFO: 1928120 events read in total (84248ms).
[09:37:58.101] <TB0> INFO: 2878740 events read in total (126242ms).
[09:38:06.179] <TB0> INFO: 3057600 events read in total (134320ms).
[09:38:06.213] <TB0> INFO: Test took 135197ms.
[09:38:29.690] <TB0> INFO: PixTestTrim::trimBitTest() done
[09:38:29.691] <TB0> INFO: PixTestTrim::doTest() done, duration: 2437 seconds
[09:38:29.691] <TB0> INFO: Decoding statistics:
[09:38:29.691] <TB0> INFO: General information:
[09:38:29.691] <TB0> INFO: 16bit words read: 0
[09:38:29.691] <TB0> INFO: valid events total: 0
[09:38:29.691] <TB0> INFO: empty events: 0
[09:38:29.691] <TB0> INFO: valid events with pixels: 0
[09:38:29.691] <TB0> INFO: valid pixel hits: 0
[09:38:29.691] <TB0> INFO: Event errors: 0
[09:38:29.691] <TB0> INFO: start marker: 0
[09:38:29.691] <TB0> INFO: stop marker: 0
[09:38:29.691] <TB0> INFO: overflow: 0
[09:38:29.691] <TB0> INFO: invalid 5bit words: 0
[09:38:29.691] <TB0> INFO: invalid XOR eye diagram: 0
[09:38:29.691] <TB0> INFO: frame (failed synchr.): 0
[09:38:29.691] <TB0> INFO: idle data (no TBM trl): 0
[09:38:29.691] <TB0> INFO: no data (only TBM hdr): 0
[09:38:29.691] <TB0> INFO: TBM errors: 0
[09:38:29.691] <TB0> INFO: flawed TBM headers: 0
[09:38:29.691] <TB0> INFO: flawed TBM trailers: 0
[09:38:29.691] <TB0> INFO: event ID mismatches: 0
[09:38:29.691] <TB0> INFO: ROC errors: 0
[09:38:29.691] <TB0> INFO: missing ROC header(s): 0
[09:38:29.691] <TB0> INFO: misplaced readback start: 0
[09:38:29.691] <TB0> INFO: Pixel decoding errors: 0
[09:38:29.691] <TB0> INFO: pixel data incomplete: 0
[09:38:29.691] <TB0> INFO: pixel address: 0
[09:38:29.691] <TB0> INFO: pulse height fill bit: 0
[09:38:29.691] <TB0> INFO: buffer corruption: 0
[09:38:30.416] <TB0> INFO: ######################################################################
[09:38:30.416] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[09:38:30.416] <TB0> INFO: ######################################################################
[09:38:30.704] <TB0> INFO: Expecting 41600 events.
[09:38:34.536] <TB0> INFO: 41600 events read in total (3276ms).
[09:38:34.536] <TB0> INFO: Test took 4119ms.
[09:38:35.007] <TB0> INFO: Expecting 41600 events.
[09:38:38.876] <TB0> INFO: 41600 events read in total (3313ms).
[09:38:38.876] <TB0> INFO: Test took 4137ms.
[09:38:39.159] <TB0> INFO: Expecting 41600 events.
[09:38:43.009] <TB0> INFO: 41600 events read in total (3294ms).
[09:38:43.009] <TB0> INFO: Test took 4110ms.
[09:38:43.266] <TB0> INFO: Expecting 2560 events.
[09:38:44.118] <TB0> INFO: 2560 events read in total (296ms).
[09:38:44.118] <TB0> INFO: Test took 1094ms.
[09:38:44.430] <TB0> INFO: Expecting 2560 events.
[09:38:45.283] <TB0> INFO: 2560 events read in total (297ms).
[09:38:45.283] <TB0> INFO: Test took 1164ms.
[09:38:45.588] <TB0> INFO: Expecting 2560 events.
[09:38:46.441] <TB0> INFO: 2560 events read in total (297ms).
[09:38:46.441] <TB0> INFO: Test took 1158ms.
[09:38:46.747] <TB0> INFO: Expecting 2560 events.
[09:38:47.599] <TB0> INFO: 2560 events read in total (296ms).
[09:38:47.599] <TB0> INFO: Test took 1147ms.
[09:38:47.904] <TB0> INFO: Expecting 2560 events.
[09:38:48.757] <TB0> INFO: 2560 events read in total (297ms).
[09:38:48.757] <TB0> INFO: Test took 1158ms.
[09:38:49.063] <TB0> INFO: Expecting 2560 events.
[09:38:49.916] <TB0> INFO: 2560 events read in total (297ms).
[09:38:49.916] <TB0> INFO: Test took 1159ms.
[09:38:50.221] <TB0> INFO: Expecting 2560 events.
[09:38:51.074] <TB0> INFO: 2560 events read in total (297ms).
[09:38:51.074] <TB0> INFO: Test took 1156ms.
[09:38:51.380] <TB0> INFO: Expecting 2560 events.
[09:38:52.232] <TB0> INFO: 2560 events read in total (296ms).
[09:38:52.232] <TB0> INFO: Test took 1157ms.
[09:38:52.538] <TB0> INFO: Expecting 2560 events.
[09:38:53.393] <TB0> INFO: 2560 events read in total (299ms).
[09:38:53.393] <TB0> INFO: Test took 1161ms.
[09:38:53.699] <TB0> INFO: Expecting 2560 events.
[09:38:54.551] <TB0> INFO: 2560 events read in total (296ms).
[09:38:54.551] <TB0> INFO: Test took 1158ms.
[09:38:54.856] <TB0> INFO: Expecting 2560 events.
[09:38:55.708] <TB0> INFO: 2560 events read in total (296ms).
[09:38:55.709] <TB0> INFO: Test took 1155ms.
[09:38:56.014] <TB0> INFO: Expecting 2560 events.
[09:38:56.866] <TB0> INFO: 2560 events read in total (296ms).
[09:38:56.867] <TB0> INFO: Test took 1158ms.
[09:38:57.171] <TB0> INFO: Expecting 2560 events.
[09:38:58.023] <TB0> INFO: 2560 events read in total (296ms).
[09:38:58.024] <TB0> INFO: Test took 1156ms.
[09:38:58.328] <TB0> INFO: Expecting 2560 events.
[09:38:59.180] <TB0> INFO: 2560 events read in total (295ms).
[09:38:59.181] <TB0> INFO: Test took 1157ms.
[09:38:59.486] <TB0> INFO: Expecting 2560 events.
[09:39:00.339] <TB0> INFO: 2560 events read in total (297ms).
[09:39:00.339] <TB0> INFO: Test took 1158ms.
[09:39:00.644] <TB0> INFO: Expecting 2560 events.
[09:39:01.498] <TB0> INFO: 2560 events read in total (298ms).
[09:39:01.498] <TB0> INFO: Test took 1159ms.
[09:39:01.502] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:39:01.804] <TB0> INFO: Expecting 655360 events.
[09:39:18.554] <TB0> INFO: 655360 events read in total (16193ms).
[09:39:18.563] <TB0> INFO: Expecting 655360 events.
[09:39:35.113] <TB0> INFO: 655360 events read in total (16148ms).
[09:39:35.126] <TB0> INFO: Expecting 655360 events.
[09:39:51.745] <TB0> INFO: 655360 events read in total (16217ms).
[09:39:51.768] <TB0> INFO: Expecting 655360 events.
[09:40:08.330] <TB0> INFO: 655360 events read in total (16160ms).
[09:40:08.349] <TB0> INFO: Expecting 655360 events.
[09:40:25.195] <TB0> INFO: 655360 events read in total (16445ms).
[09:40:25.227] <TB0> INFO: Expecting 655360 events.
[09:40:42.023] <TB0> INFO: 655360 events read in total (16394ms).
[09:40:42.049] <TB0> INFO: Expecting 655360 events.
[09:40:58.926] <TB0> INFO: 655360 events read in total (16475ms).
[09:40:58.955] <TB0> INFO: Expecting 655360 events.
[09:41:15.519] <TB0> INFO: 655360 events read in total (16162ms).
[09:41:15.553] <TB0> INFO: Expecting 655360 events.
[09:41:32.241] <TB0> INFO: 655360 events read in total (16286ms).
[09:41:32.277] <TB0> INFO: Expecting 655360 events.
[09:41:48.864] <TB0> INFO: 655360 events read in total (16185ms).
[09:41:48.903] <TB0> INFO: Expecting 655360 events.
[09:42:05.510] <TB0> INFO: 655360 events read in total (16205ms).
[09:42:05.553] <TB0> INFO: Expecting 655360 events.
[09:42:22.095] <TB0> INFO: 655360 events read in total (16140ms).
[09:42:22.141] <TB0> INFO: Expecting 655360 events.
[09:42:38.712] <TB0> INFO: 655360 events read in total (16170ms).
[09:42:38.770] <TB0> INFO: Expecting 655360 events.
[09:42:55.614] <TB0> INFO: 655360 events read in total (16443ms).
[09:42:55.676] <TB0> INFO: Expecting 655360 events.
[09:43:12.562] <TB0> INFO: 655360 events read in total (16485ms).
[09:43:12.618] <TB0> INFO: Expecting 655360 events.
[09:43:29.294] <TB0> INFO: 655360 events read in total (16275ms).
[09:43:29.375] <TB0> INFO: Test took 267873ms.
[09:43:29.481] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:43:29.708] <TB0> INFO: Expecting 655360 events.
[09:43:46.568] <TB0> INFO: 655360 events read in total (16304ms).
[09:43:46.578] <TB0> INFO: Expecting 655360 events.
[09:44:03.311] <TB0> INFO: 655360 events read in total (16332ms).
[09:44:03.323] <TB0> INFO: Expecting 655360 events.
[09:44:19.847] <TB0> INFO: 655360 events read in total (16122ms).
[09:44:19.863] <TB0> INFO: Expecting 655360 events.
[09:44:36.451] <TB0> INFO: 655360 events read in total (16186ms).
[09:44:36.473] <TB0> INFO: Expecting 655360 events.
[09:44:53.130] <TB0> INFO: 655360 events read in total (16256ms).
[09:44:53.153] <TB0> INFO: Expecting 655360 events.
[09:45:09.795] <TB0> INFO: 655360 events read in total (16240ms).
[09:45:09.823] <TB0> INFO: Expecting 655360 events.
[09:45:26.268] <TB0> INFO: 655360 events read in total (16043ms).
[09:45:26.297] <TB0> INFO: Expecting 655360 events.
[09:45:42.963] <TB0> INFO: 655360 events read in total (16264ms).
[09:45:42.995] <TB0> INFO: Expecting 655360 events.
[09:45:59.736] <TB0> INFO: 655360 events read in total (16339ms).
[09:45:59.773] <TB0> INFO: Expecting 655360 events.
[09:46:16.224] <TB0> INFO: 655360 events read in total (16050ms).
[09:46:16.269] <TB0> INFO: Expecting 655360 events.
[09:46:32.929] <TB0> INFO: 655360 events read in total (16259ms).
[09:46:32.973] <TB0> INFO: Expecting 655360 events.
[09:46:49.703] <TB0> INFO: 655360 events read in total (16328ms).
[09:46:49.755] <TB0> INFO: Expecting 655360 events.
[09:47:06.385] <TB0> INFO: 655360 events read in total (16229ms).
[09:47:06.440] <TB0> INFO: Expecting 655360 events.
[09:47:23.095] <TB0> INFO: 655360 events read in total (16253ms).
[09:47:23.150] <TB0> INFO: Expecting 655360 events.
[09:47:39.800] <TB0> INFO: 655360 events read in total (16249ms).
[09:47:39.857] <TB0> INFO: Expecting 655360 events.
[09:47:56.577] <TB0> INFO: 655360 events read in total (16318ms).
[09:47:56.643] <TB0> INFO: Test took 267162ms.
[09:47:56.838] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:47:56.845] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:47:56.852] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:47:56.859] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:47:56.866] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:47:56.872] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:47:56.880] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:47:56.887] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:47:56.894] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:47:56.901] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:47:56.908] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:47:56.915] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:47:56.922] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:47:56.928] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:47:56.935] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:47:56.942] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[09:47:56.977] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:47:56.977] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:47:56.977] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:47:56.977] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:47:56.977] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:47:56.977] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:47:56.977] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:47:56.977] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:47:56.978] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:47:56.978] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:47:56.978] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:47:56.978] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:47:56.978] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:47:56.978] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:47:56.978] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:47:56.978] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:47:57.244] <TB0> INFO: Expecting 41600 events.
[09:48:00.756] <TB0> INFO: 41600 events read in total (2956ms).
[09:48:00.757] <TB0> INFO: Test took 3776ms.
[09:48:01.204] <TB0> INFO: Expecting 41600 events.
[09:48:04.698] <TB0> INFO: 41600 events read in total (2938ms).
[09:48:04.698] <TB0> INFO: Test took 3765ms.
[09:48:05.151] <TB0> INFO: Expecting 41600 events.
[09:48:08.627] <TB0> INFO: 41600 events read in total (2920ms).
[09:48:08.628] <TB0> INFO: Test took 3754ms.
[09:48:08.808] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:08.897] <TB0> INFO: Expecting 2560 events.
[09:48:09.751] <TB0> INFO: 2560 events read in total (298ms).
[09:48:09.751] <TB0> INFO: Test took 943ms.
[09:48:09.753] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:10.057] <TB0> INFO: Expecting 2560 events.
[09:48:10.910] <TB0> INFO: 2560 events read in total (297ms).
[09:48:10.910] <TB0> INFO: Test took 1157ms.
[09:48:10.912] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:11.217] <TB0> INFO: Expecting 2560 events.
[09:48:12.070] <TB0> INFO: 2560 events read in total (297ms).
[09:48:12.070] <TB0> INFO: Test took 1158ms.
[09:48:12.072] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:12.377] <TB0> INFO: Expecting 2560 events.
[09:48:13.230] <TB0> INFO: 2560 events read in total (297ms).
[09:48:13.230] <TB0> INFO: Test took 1158ms.
[09:48:13.232] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:13.537] <TB0> INFO: Expecting 2560 events.
[09:48:14.390] <TB0> INFO: 2560 events read in total (297ms).
[09:48:14.390] <TB0> INFO: Test took 1158ms.
[09:48:14.392] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:14.696] <TB0> INFO: Expecting 2560 events.
[09:48:15.549] <TB0> INFO: 2560 events read in total (296ms).
[09:48:15.549] <TB0> INFO: Test took 1157ms.
[09:48:15.550] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:15.855] <TB0> INFO: Expecting 2560 events.
[09:48:16.709] <TB0> INFO: 2560 events read in total (297ms).
[09:48:16.709] <TB0> INFO: Test took 1159ms.
[09:48:16.712] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:17.015] <TB0> INFO: Expecting 2560 events.
[09:48:17.869] <TB0> INFO: 2560 events read in total (298ms).
[09:48:17.869] <TB0> INFO: Test took 1157ms.
[09:48:17.871] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:18.175] <TB0> INFO: Expecting 2560 events.
[09:48:19.029] <TB0> INFO: 2560 events read in total (297ms).
[09:48:19.030] <TB0> INFO: Test took 1159ms.
[09:48:19.031] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:19.336] <TB0> INFO: Expecting 2560 events.
[09:48:20.194] <TB0> INFO: 2560 events read in total (302ms).
[09:48:20.195] <TB0> INFO: Test took 1164ms.
[09:48:20.197] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:20.500] <TB0> INFO: Expecting 2560 events.
[09:48:21.354] <TB0> INFO: 2560 events read in total (298ms).
[09:48:21.355] <TB0> INFO: Test took 1158ms.
[09:48:21.357] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:21.661] <TB0> INFO: Expecting 2560 events.
[09:48:22.518] <TB0> INFO: 2560 events read in total (301ms).
[09:48:22.518] <TB0> INFO: Test took 1161ms.
[09:48:22.521] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:22.823] <TB0> INFO: Expecting 2560 events.
[09:48:23.677] <TB0> INFO: 2560 events read in total (297ms).
[09:48:23.677] <TB0> INFO: Test took 1156ms.
[09:48:23.678] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:23.982] <TB0> INFO: Expecting 2560 events.
[09:48:24.835] <TB0> INFO: 2560 events read in total (296ms).
[09:48:24.835] <TB0> INFO: Test took 1157ms.
[09:48:24.837] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:25.141] <TB0> INFO: Expecting 2560 events.
[09:48:25.994] <TB0> INFO: 2560 events read in total (297ms).
[09:48:25.995] <TB0> INFO: Test took 1159ms.
[09:48:25.997] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:26.300] <TB0> INFO: Expecting 2560 events.
[09:48:27.153] <TB0> INFO: 2560 events read in total (297ms).
[09:48:27.153] <TB0> INFO: Test took 1156ms.
[09:48:27.155] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:27.459] <TB0> INFO: Expecting 2560 events.
[09:48:28.316] <TB0> INFO: 2560 events read in total (301ms).
[09:48:28.317] <TB0> INFO: Test took 1162ms.
[09:48:28.318] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:28.622] <TB0> INFO: Expecting 2560 events.
[09:48:29.476] <TB0> INFO: 2560 events read in total (297ms).
[09:48:29.476] <TB0> INFO: Test took 1158ms.
[09:48:29.478] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:29.782] <TB0> INFO: Expecting 2560 events.
[09:48:30.642] <TB0> INFO: 2560 events read in total (304ms).
[09:48:30.643] <TB0> INFO: Test took 1165ms.
[09:48:30.645] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:30.948] <TB0> INFO: Expecting 2560 events.
[09:48:31.807] <TB0> INFO: 2560 events read in total (303ms).
[09:48:31.808] <TB0> INFO: Test took 1163ms.
[09:48:31.811] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:32.113] <TB0> INFO: Expecting 2560 events.
[09:48:32.972] <TB0> INFO: 2560 events read in total (302ms).
[09:48:32.972] <TB0> INFO: Test took 1161ms.
[09:48:32.975] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:33.278] <TB0> INFO: Expecting 2560 events.
[09:48:34.131] <TB0> INFO: 2560 events read in total (297ms).
[09:48:34.131] <TB0> INFO: Test took 1156ms.
[09:48:34.134] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:34.436] <TB0> INFO: Expecting 2560 events.
[09:48:35.291] <TB0> INFO: 2560 events read in total (299ms).
[09:48:35.291] <TB0> INFO: Test took 1157ms.
[09:48:35.293] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:35.597] <TB0> INFO: Expecting 2560 events.
[09:48:36.449] <TB0> INFO: 2560 events read in total (296ms).
[09:48:36.449] <TB0> INFO: Test took 1156ms.
[09:48:36.451] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:36.755] <TB0> INFO: Expecting 2560 events.
[09:48:37.608] <TB0> INFO: 2560 events read in total (297ms).
[09:48:37.608] <TB0> INFO: Test took 1158ms.
[09:48:37.611] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:37.914] <TB0> INFO: Expecting 2560 events.
[09:48:38.768] <TB0> INFO: 2560 events read in total (298ms).
[09:48:38.768] <TB0> INFO: Test took 1157ms.
[09:48:38.771] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:39.075] <TB0> INFO: Expecting 2560 events.
[09:48:39.929] <TB0> INFO: 2560 events read in total (298ms).
[09:48:39.929] <TB0> INFO: Test took 1158ms.
[09:48:39.931] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:40.235] <TB0> INFO: Expecting 2560 events.
[09:48:41.088] <TB0> INFO: 2560 events read in total (297ms).
[09:48:41.088] <TB0> INFO: Test took 1157ms.
[09:48:41.091] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:41.394] <TB0> INFO: Expecting 2560 events.
[09:48:42.249] <TB0> INFO: 2560 events read in total (298ms).
[09:48:42.249] <TB0> INFO: Test took 1158ms.
[09:48:42.252] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:42.555] <TB0> INFO: Expecting 2560 events.
[09:48:43.409] <TB0> INFO: 2560 events read in total (298ms).
[09:48:43.409] <TB0> INFO: Test took 1157ms.
[09:48:43.411] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:43.715] <TB0> INFO: Expecting 2560 events.
[09:48:44.570] <TB0> INFO: 2560 events read in total (299ms).
[09:48:44.570] <TB0> INFO: Test took 1160ms.
[09:48:44.573] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:48:44.876] <TB0> INFO: Expecting 2560 events.
[09:48:45.734] <TB0> INFO: 2560 events read in total (302ms).
[09:48:45.734] <TB0> INFO: Test took 1161ms.
[09:48:46.188] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 615 seconds
[09:48:46.188] <TB0> INFO: PH scale (per ROC): 106 107 89 87 84 83 84 87 80 86 100 98 93 80 91 89
[09:48:46.188] <TB0> INFO: PH offset (per ROC): 158 150 144 140 147 163 147 154 165 160 142 151 126 140 146 148
[09:48:46.197] <TB0> INFO: Decoding statistics:
[09:48:46.197] <TB0> INFO: General information:
[09:48:46.197] <TB0> INFO: 16bit words read: 91928
[09:48:46.197] <TB0> INFO: valid events total: 10240
[09:48:46.197] <TB0> INFO: empty events: 7738
[09:48:46.197] <TB0> INFO: valid events with pixels: 2502
[09:48:46.197] <TB0> INFO: valid pixel hits: 2502
[09:48:46.197] <TB0> INFO: Event errors: 0
[09:48:46.197] <TB0> INFO: start marker: 0
[09:48:46.197] <TB0> INFO: stop marker: 0
[09:48:46.197] <TB0> INFO: overflow: 0
[09:48:46.197] <TB0> INFO: invalid 5bit words: 0
[09:48:46.197] <TB0> INFO: invalid XOR eye diagram: 0
[09:48:46.197] <TB0> INFO: frame (failed synchr.): 0
[09:48:46.197] <TB0> INFO: idle data (no TBM trl): 0
[09:48:46.197] <TB0> INFO: no data (only TBM hdr): 0
[09:48:46.197] <TB0> INFO: TBM errors: 0
[09:48:46.197] <TB0> INFO: flawed TBM headers: 0
[09:48:46.197] <TB0> INFO: flawed TBM trailers: 0
[09:48:46.197] <TB0> INFO: event ID mismatches: 0
[09:48:46.197] <TB0> INFO: ROC errors: 0
[09:48:46.197] <TB0> INFO: missing ROC header(s): 0
[09:48:46.197] <TB0> INFO: misplaced readback start: 0
[09:48:46.197] <TB0> INFO: Pixel decoding errors: 0
[09:48:46.197] <TB0> INFO: pixel data incomplete: 0
[09:48:46.197] <TB0> INFO: pixel address: 0
[09:48:46.197] <TB0> INFO: pulse height fill bit: 0
[09:48:46.197] <TB0> INFO: buffer corruption: 0
[09:48:46.383] <TB0> INFO: ######################################################################
[09:48:46.383] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[09:48:46.383] <TB0> INFO: ######################################################################
[09:48:46.393] <TB0> INFO: scanning low vcal = 10
[09:48:46.656] <TB0> INFO: Expecting 41600 events.
[09:48:50.197] <TB0> INFO: 41600 events read in total (2985ms).
[09:48:50.198] <TB0> INFO: Test took 3805ms.
[09:48:50.199] <TB0> INFO: scanning low vcal = 20
[09:48:50.503] <TB0> INFO: Expecting 41600 events.
[09:48:54.041] <TB0> INFO: 41600 events read in total (2982ms).
[09:48:54.041] <TB0> INFO: Test took 3842ms.
[09:48:54.042] <TB0> INFO: scanning low vcal = 30
[09:48:54.346] <TB0> INFO: Expecting 41600 events.
[09:48:57.893] <TB0> INFO: 41600 events read in total (2991ms).
[09:48:57.893] <TB0> INFO: Test took 3851ms.
[09:48:57.895] <TB0> INFO: scanning low vcal = 40
[09:48:58.195] <TB0> INFO: Expecting 41600 events.
[09:49:02.312] <TB0> INFO: 41600 events read in total (3560ms).
[09:49:02.312] <TB0> INFO: Test took 4417ms.
[09:49:02.315] <TB0> INFO: scanning low vcal = 50
[09:49:02.587] <TB0> INFO: Expecting 41600 events.
[09:49:06.790] <TB0> INFO: 41600 events read in total (3646ms).
[09:49:06.790] <TB0> INFO: Test took 4475ms.
[09:49:06.793] <TB0> INFO: scanning low vcal = 60
[09:49:07.065] <TB0> INFO: Expecting 41600 events.
[09:49:11.269] <TB0> INFO: 41600 events read in total (3648ms).
[09:49:11.270] <TB0> INFO: Test took 4477ms.
[09:49:11.272] <TB0> INFO: scanning low vcal = 70
[09:49:11.548] <TB0> INFO: Expecting 41600 events.
[09:49:15.727] <TB0> INFO: 41600 events read in total (3623ms).
[09:49:15.727] <TB0> INFO: Test took 4455ms.
[09:49:15.730] <TB0> INFO: scanning low vcal = 80
[09:49:15.989] <TB0> INFO: Expecting 41600 events.
[09:49:20.181] <TB0> INFO: 41600 events read in total (3635ms).
[09:49:20.181] <TB0> INFO: Test took 4451ms.
[09:49:20.184] <TB0> INFO: scanning low vcal = 90
[09:49:20.455] <TB0> INFO: Expecting 41600 events.
[09:49:24.632] <TB0> INFO: 41600 events read in total (3621ms).
[09:49:24.633] <TB0> INFO: Test took 4449ms.
[09:49:24.635] <TB0> INFO: scanning low vcal = 100
[09:49:24.899] <TB0> INFO: Expecting 41600 events.
[09:49:29.109] <TB0> INFO: 41600 events read in total (3654ms).
[09:49:29.110] <TB0> INFO: Test took 4474ms.
[09:49:29.113] <TB0> INFO: scanning low vcal = 110
[09:49:29.378] <TB0> INFO: Expecting 41600 events.
[09:49:33.579] <TB0> INFO: 41600 events read in total (3644ms).
[09:49:33.580] <TB0> INFO: Test took 4467ms.
[09:49:33.595] <TB0> INFO: scanning low vcal = 120
[09:49:33.865] <TB0> INFO: Expecting 41600 events.
[09:49:38.064] <TB0> INFO: 41600 events read in total (3643ms).
[09:49:38.065] <TB0> INFO: Test took 4470ms.
[09:49:38.068] <TB0> INFO: scanning low vcal = 130
[09:49:38.331] <TB0> INFO: Expecting 41600 events.
[09:49:42.518] <TB0> INFO: 41600 events read in total (3631ms).
[09:49:42.519] <TB0> INFO: Test took 4451ms.
[09:49:42.521] <TB0> INFO: scanning low vcal = 140
[09:49:42.786] <TB0> INFO: Expecting 41600 events.
[09:49:46.978] <TB0> INFO: 41600 events read in total (3636ms).
[09:49:46.978] <TB0> INFO: Test took 4457ms.
[09:49:46.981] <TB0> INFO: scanning low vcal = 150
[09:49:47.241] <TB0> INFO: Expecting 41600 events.
[09:49:51.457] <TB0> INFO: 41600 events read in total (3660ms).
[09:49:51.457] <TB0> INFO: Test took 4476ms.
[09:49:51.460] <TB0> INFO: scanning low vcal = 160
[09:49:51.734] <TB0> INFO: Expecting 41600 events.
[09:49:55.926] <TB0> INFO: 41600 events read in total (3636ms).
[09:49:55.927] <TB0> INFO: Test took 4467ms.
[09:49:55.929] <TB0> INFO: scanning low vcal = 170
[09:49:56.193] <TB0> INFO: Expecting 41600 events.
[09:50:00.366] <TB0> INFO: 41600 events read in total (3617ms).
[09:50:00.366] <TB0> INFO: Test took 4438ms.
[09:50:00.370] <TB0> INFO: scanning low vcal = 180
[09:50:00.634] <TB0> INFO: Expecting 41600 events.
[09:50:04.832] <TB0> INFO: 41600 events read in total (3642ms).
[09:50:04.832] <TB0> INFO: Test took 4462ms.
[09:50:04.835] <TB0> INFO: scanning low vcal = 190
[09:50:05.097] <TB0> INFO: Expecting 41600 events.
[09:50:09.319] <TB0> INFO: 41600 events read in total (3666ms).
[09:50:09.320] <TB0> INFO: Test took 4485ms.
[09:50:09.322] <TB0> INFO: scanning low vcal = 200
[09:50:09.604] <TB0> INFO: Expecting 41600 events.
[09:50:13.831] <TB0> INFO: 41600 events read in total (3671ms).
[09:50:13.831] <TB0> INFO: Test took 4508ms.
[09:50:13.834] <TB0> INFO: scanning low vcal = 210
[09:50:14.114] <TB0> INFO: Expecting 41600 events.
[09:50:18.320] <TB0> INFO: 41600 events read in total (3649ms).
[09:50:18.321] <TB0> INFO: Test took 4487ms.
[09:50:18.323] <TB0> INFO: scanning low vcal = 220
[09:50:18.598] <TB0> INFO: Expecting 41600 events.
[09:50:22.813] <TB0> INFO: 41600 events read in total (3659ms).
[09:50:22.814] <TB0> INFO: Test took 4491ms.
[09:50:22.817] <TB0> INFO: scanning low vcal = 230
[09:50:23.086] <TB0> INFO: Expecting 41600 events.
[09:50:27.278] <TB0> INFO: 41600 events read in total (3636ms).
[09:50:27.279] <TB0> INFO: Test took 4462ms.
[09:50:27.281] <TB0> INFO: scanning low vcal = 240
[09:50:27.546] <TB0> INFO: Expecting 41600 events.
[09:50:31.773] <TB0> INFO: 41600 events read in total (3671ms).
[09:50:31.773] <TB0> INFO: Test took 4492ms.
[09:50:31.776] <TB0> INFO: scanning low vcal = 250
[09:50:32.048] <TB0> INFO: Expecting 41600 events.
[09:50:36.236] <TB0> INFO: 41600 events read in total (3632ms).
[09:50:36.237] <TB0> INFO: Test took 4461ms.
[09:50:36.241] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[09:50:36.526] <TB0> INFO: Expecting 41600 events.
[09:50:40.762] <TB0> INFO: 41600 events read in total (3680ms).
[09:50:40.762] <TB0> INFO: Test took 4521ms.
[09:50:40.765] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[09:50:41.044] <TB0> INFO: Expecting 41600 events.
[09:50:45.261] <TB0> INFO: 41600 events read in total (3662ms).
[09:50:45.262] <TB0> INFO: Test took 4497ms.
[09:50:45.264] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[09:50:45.529] <TB0> INFO: Expecting 41600 events.
[09:50:49.760] <TB0> INFO: 41600 events read in total (3675ms).
[09:50:49.760] <TB0> INFO: Test took 4496ms.
[09:50:49.763] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[09:50:50.036] <TB0> INFO: Expecting 41600 events.
[09:50:54.235] <TB0> INFO: 41600 events read in total (3643ms).
[09:50:54.235] <TB0> INFO: Test took 4472ms.
[09:50:54.237] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[09:50:54.501] <TB0> INFO: Expecting 41600 events.
[09:50:58.698] <TB0> INFO: 41600 events read in total (3640ms).
[09:50:58.698] <TB0> INFO: Test took 4460ms.
[09:50:59.154] <TB0> INFO: PixTestGainPedestal::measure() done
[09:51:31.371] <TB0> INFO: PixTestGainPedestal::fit() done
[09:51:31.371] <TB0> INFO: non-linearity mean: 0.954 0.953 0.952 0.956 0.948 0.953 0.955 0.959 0.959 0.958 0.952 0.954 0.948 0.958 0.955 0.947
[09:51:31.371] <TB0> INFO: non-linearity RMS: 0.006 0.006 0.007 0.006 0.006 0.005 0.006 0.005 0.005 0.005 0.005 0.005 0.006 0.004 0.006 0.008
[09:51:31.371] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[09:51:31.390] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[09:51:31.409] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[09:51:31.428] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[09:51:31.447] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[09:51:31.466] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[09:51:31.513] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[09:51:31.534] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[09:51:31.554] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[09:51:31.581] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[09:51:31.601] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[09:51:31.622] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[09:51:31.643] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[09:51:31.664] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[09:51:31.689] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[09:51:31.710] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[09:51:31.731] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 165 seconds
[09:51:31.731] <TB0> INFO: Decoding statistics:
[09:51:31.731] <TB0> INFO: General information:
[09:51:31.731] <TB0> INFO: 16bit words read: 2662400
[09:51:31.731] <TB0> INFO: valid events total: 166400
[09:51:31.731] <TB0> INFO: empty events: 0
[09:51:31.731] <TB0> INFO: valid events with pixels: 166400
[09:51:31.731] <TB0> INFO: valid pixel hits: 665600
[09:51:31.731] <TB0> INFO: Event errors: 0
[09:51:31.731] <TB0> INFO: start marker: 0
[09:51:31.731] <TB0> INFO: stop marker: 0
[09:51:31.731] <TB0> INFO: overflow: 0
[09:51:31.731] <TB0> INFO: invalid 5bit words: 0
[09:51:31.731] <TB0> INFO: invalid XOR eye diagram: 0
[09:51:31.731] <TB0> INFO: frame (failed synchr.): 0
[09:51:31.731] <TB0> INFO: idle data (no TBM trl): 0
[09:51:31.731] <TB0> INFO: no data (only TBM hdr): 0
[09:51:31.731] <TB0> INFO: TBM errors: 0
[09:51:31.731] <TB0> INFO: flawed TBM headers: 0
[09:51:31.731] <TB0> INFO: flawed TBM trailers: 0
[09:51:31.731] <TB0> INFO: event ID mismatches: 0
[09:51:31.731] <TB0> INFO: ROC errors: 0
[09:51:31.731] <TB0> INFO: missing ROC header(s): 0
[09:51:31.731] <TB0> INFO: misplaced readback start: 0
[09:51:31.731] <TB0> INFO: Pixel decoding errors: 0
[09:51:31.731] <TB0> INFO: pixel data incomplete: 0
[09:51:31.731] <TB0> INFO: pixel address: 0
[09:51:31.731] <TB0> INFO: pulse height fill bit: 0
[09:51:31.731] <TB0> INFO: buffer corruption: 0
[09:51:31.739] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C15.dat
[09:51:31.740] <TB0> INFO: ######################################################################
[09:51:31.740] <TB0> INFO: PixTestReadback::doTest()
[09:51:31.740] <TB0> INFO: ######################################################################
[09:51:31.740] <TB0> INFO: ----------------------------------------------------------------------
[09:51:31.740] <TB0> INFO: PixTestReadback::CalibrateVd()
[09:51:31.740] <TB0> INFO: ----------------------------------------------------------------------
[09:51:41.217] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C0.dat
[09:51:41.217] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C1.dat
[09:51:41.217] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C2.dat
[09:51:41.217] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C3.dat
[09:51:41.217] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C4.dat
[09:51:41.217] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C5.dat
[09:51:41.217] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C6.dat
[09:51:41.217] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C7.dat
[09:51:41.217] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C8.dat
[09:51:41.217] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C9.dat
[09:51:41.218] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C10.dat
[09:51:41.218] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C11.dat
[09:51:41.218] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C12.dat
[09:51:41.218] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C13.dat
[09:51:41.218] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C14.dat
[09:51:41.218] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C15.dat
[09:51:41.246] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[09:51:41.246] <TB0> INFO: ----------------------------------------------------------------------
[09:51:41.246] <TB0> INFO: PixTestReadback::CalibrateVa()
[09:51:41.246] <TB0> INFO: ----------------------------------------------------------------------
[09:51:51.771] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C0.dat
[09:51:51.771] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C1.dat
[09:51:51.771] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C2.dat
[09:51:51.771] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C3.dat
[09:51:51.771] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C4.dat
[09:51:51.771] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C5.dat
[09:51:51.771] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C6.dat
[09:51:51.771] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C7.dat
[09:51:51.771] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C8.dat
[09:51:51.771] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C9.dat
[09:51:51.772] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C10.dat
[09:51:51.772] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C11.dat
[09:51:51.772] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C12.dat
[09:51:51.772] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C13.dat
[09:51:51.772] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C14.dat
[09:51:51.772] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C15.dat
[09:51:51.799] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[09:51:51.799] <TB0> INFO: ----------------------------------------------------------------------
[09:51:51.799] <TB0> INFO: PixTestReadback::readbackVbg()
[09:51:51.799] <TB0> INFO: ----------------------------------------------------------------------
[09:51:59.097] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[09:51:59.097] <TB0> INFO: ----------------------------------------------------------------------
[09:51:59.097] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[09:51:59.097] <TB0> INFO: ----------------------------------------------------------------------
[09:51:59.097] <TB0> INFO: Vbg will be calibrated using Vd calibration
[09:51:59.097] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 154.1calibrated Vbg = 1.21112 :::*/*/*/*/
[09:51:59.097] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 153calibrated Vbg = 1.21209 :::*/*/*/*/
[09:51:59.097] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 153.9calibrated Vbg = 1.2183 :::*/*/*/*/
[09:51:59.097] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 146.2calibrated Vbg = 1.22691 :::*/*/*/*/
[09:51:59.097] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 154.7calibrated Vbg = 1.23216 :::*/*/*/*/
[09:51:59.097] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 149.8calibrated Vbg = 1.22924 :::*/*/*/*/
[09:51:59.097] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 147.4calibrated Vbg = 1.23475 :::*/*/*/*/
[09:51:59.097] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 156.7calibrated Vbg = 1.23388 :::*/*/*/*/
[09:51:59.097] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 145.3calibrated Vbg = 1.2285 :::*/*/*/*/
[09:51:59.097] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 159.9calibrated Vbg = 1.23699 :::*/*/*/*/
[09:51:59.097] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 146.5calibrated Vbg = 1.22719 :::*/*/*/*/
[09:51:59.097] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 148.6calibrated Vbg = 1.22256 :::*/*/*/*/
[09:51:59.097] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 147.6calibrated Vbg = 1.22073 :::*/*/*/*/
[09:51:59.097] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 152.2calibrated Vbg = 1.21596 :::*/*/*/*/
[09:51:59.097] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 147calibrated Vbg = 1.21514 :::*/*/*/*/
[09:51:59.097] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 154.4calibrated Vbg = 1.2165 :::*/*/*/*/
[09:51:59.100] <TB0> INFO: ----------------------------------------------------------------------
[09:51:59.100] <TB0> INFO: PixTestReadback::CalibrateIa()
[09:51:59.100] <TB0> INFO: ----------------------------------------------------------------------
[09:54:34.153] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C0.dat
[09:54:34.153] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C1.dat
[09:54:34.153] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C2.dat
[09:54:34.153] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C3.dat
[09:54:34.153] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C4.dat
[09:54:34.153] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C5.dat
[09:54:34.154] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C6.dat
[09:54:34.154] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C7.dat
[09:54:34.154] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C8.dat
[09:54:34.154] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C9.dat
[09:54:34.154] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C10.dat
[09:54:34.154] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C11.dat
[09:54:34.154] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C12.dat
[09:54:34.154] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C13.dat
[09:54:34.154] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C14.dat
[09:54:34.154] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2310_FullQualification_2016-09-07_10h23m_1473236586//000_FulltestPxar_m20//readbackCal_C15.dat
[09:54:34.178] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[09:54:34.179] <TB0> INFO: PixTestReadback::doTest() done
[09:54:34.179] <TB0> INFO: Decoding statistics:
[09:54:34.179] <TB0> INFO: General information:
[09:54:34.179] <TB0> INFO: 16bit words read: 1024
[09:54:34.179] <TB0> INFO: valid events total: 128
[09:54:34.179] <TB0> INFO: empty events: 128
[09:54:34.179] <TB0> INFO: valid events with pixels: 0
[09:54:34.179] <TB0> INFO: valid pixel hits: 0
[09:54:34.179] <TB0> INFO: Event errors: 0
[09:54:34.179] <TB0> INFO: start marker: 0
[09:54:34.179] <TB0> INFO: stop marker: 0
[09:54:34.179] <TB0> INFO: overflow: 0
[09:54:34.179] <TB0> INFO: invalid 5bit words: 0
[09:54:34.179] <TB0> INFO: invalid XOR eye diagram: 0
[09:54:34.179] <TB0> INFO: frame (failed synchr.): 0
[09:54:34.179] <TB0> INFO: idle data (no TBM trl): 0
[09:54:34.179] <TB0> INFO: no data (only TBM hdr): 0
[09:54:34.179] <TB0> INFO: TBM errors: 0
[09:54:34.179] <TB0> INFO: flawed TBM headers: 0
[09:54:34.179] <TB0> INFO: flawed TBM trailers: 0
[09:54:34.179] <TB0> INFO: event ID mismatches: 0
[09:54:34.179] <TB0> INFO: ROC errors: 0
[09:54:34.179] <TB0> INFO: missing ROC header(s): 0
[09:54:34.179] <TB0> INFO: misplaced readback start: 0
[09:54:34.179] <TB0> INFO: Pixel decoding errors: 0
[09:54:34.179] <TB0> INFO: pixel data incomplete: 0
[09:54:34.179] <TB0> INFO: pixel address: 0
[09:54:34.179] <TB0> INFO: pulse height fill bit: 0
[09:54:34.179] <TB0> INFO: buffer corruption: 0
[09:54:34.199] <TB0> INFO: Decoding statistics:
[09:54:34.199] <TB0> INFO: General information:
[09:54:34.199] <TB0> INFO: 16bit words read: 2755352
[09:54:34.199] <TB0> INFO: valid events total: 176768
[09:54:34.199] <TB0> INFO: empty events: 7866
[09:54:34.199] <TB0> INFO: valid events with pixels: 168902
[09:54:34.199] <TB0> INFO: valid pixel hits: 668102
[09:54:34.199] <TB0> INFO: Event errors: 0
[09:54:34.199] <TB0> INFO: start marker: 0
[09:54:34.199] <TB0> INFO: stop marker: 0
[09:54:34.199] <TB0> INFO: overflow: 0
[09:54:34.199] <TB0> INFO: invalid 5bit words: 0
[09:54:34.199] <TB0> INFO: invalid XOR eye diagram: 0
[09:54:34.199] <TB0> INFO: frame (failed synchr.): 0
[09:54:34.199] <TB0> INFO: idle data (no TBM trl): 0
[09:54:34.199] <TB0> INFO: no data (only TBM hdr): 0
[09:54:34.199] <TB0> INFO: TBM errors: 0
[09:54:34.199] <TB0> INFO: flawed TBM headers: 0
[09:54:34.199] <TB0> INFO: flawed TBM trailers: 0
[09:54:34.199] <TB0> INFO: event ID mismatches: 0
[09:54:34.199] <TB0> INFO: ROC errors: 0
[09:54:34.199] <TB0> INFO: missing ROC header(s): 0
[09:54:34.199] <TB0> INFO: misplaced readback start: 0
[09:54:34.199] <TB0> INFO: Pixel decoding errors: 0
[09:54:34.199] <TB0> INFO: pixel data incomplete: 0
[09:54:34.199] <TB0> INFO: pixel address: 0
[09:54:34.199] <TB0> INFO: pulse height fill bit: 0
[09:54:34.199] <TB0> INFO: buffer corruption: 0
[09:54:34.199] <TB0> INFO: enter test to run
[09:54:34.199] <TB0> INFO: test: exit no parameter change
[09:54:34.385] <TB0> QUIET: Connection to board 73 closed.
[09:54:34.464] <TB0> INFO: pXar: this is the end, my friend