[09:56:04.857] <TB3> INFO: *** Welcome to pxar ***
[09:56:04.857] <TB3> INFO: *** Today: 2016/08/19
[09:56:04.965] <TB3> INFO: *** Version: fe94
[09:56:04.965] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters_C15.dat
[09:56:04.967] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//tbmParameters_C0b.dat
[09:56:04.968] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//defaultMaskFile.dat
[09:56:04.968] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//trimParameters_C15.dat
[09:56:05.048] <TB3> INFO: clk: 4
[09:56:05.048] <TB3> INFO: ctr: 4
[09:56:05.048] <TB3> INFO: sda: 19
[09:56:05.048] <TB3> INFO: tin: 9
[09:56:05.048] <TB3> INFO: level: 15
[09:56:05.048] <TB3> INFO: triggerdelay: 0
[09:56:05.048] <TB3> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[09:56:05.048] <TB3> INFO: Log level: INFO
[09:56:05.055] <TB3> INFO: Found DTB DTB_WZ4I6J
[09:56:05.063] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[09:56:05.064] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[09:56:05.066] <TB3> INFO: RPC call hashes of host and DTB match: 484264910
[09:56:06.602] <TB3> INFO: DUT info:
[09:56:06.602] <TB3> INFO: The DUT currently contains the following objects:
[09:56:06.602] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:56:06.602] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:56:06.602] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:56:06.602] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:56:06.602] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:06.602] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:06.602] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:06.602] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:06.602] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:06.602] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:06.602] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:06.603] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:06.603] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:06.603] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:06.603] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:06.603] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:06.603] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:06.603] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:06.603] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:06.603] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:56:07.003] <TB3> INFO: enter 'restricted' command line mode
[09:56:07.003] <TB3> INFO: enter test to run
[09:56:07.003] <TB3> INFO: test: pretest no parameter change
[09:56:07.003] <TB3> INFO: running: pretest
[09:56:07.011] <TB3> INFO: ----------------------------------------------------------------------
[09:56:07.011] <TB3> INFO: PixTestPretest::programROC()
[09:56:07.011] <TB3> INFO: ----------------------------------------------------------------------
[09:56:25.025] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[09:56:25.025] <TB3> INFO: IA differences per ROC: 19.3 20.1 16.9 17.7 18.5 17.7 17.7 19.3 20.1 20.9 20.1 20.9 20.1 17.7 18.5 20.1
[09:56:25.130] <TB3> INFO: enter test to run
[09:56:25.130] <TB3> INFO: test: pretest no parameter change
[09:56:25.130] <TB3> INFO: running: pretest
[09:56:25.131] <TB3> INFO: ----------------------------------------------------------------------
[09:56:25.131] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[09:56:25.131] <TB3> INFO: ----------------------------------------------------------------------
[09:56:46.392] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 390.7 mA = 24.4187 mA/ROC
[09:56:46.392] <TB3> INFO: i(loss) [mA/ROC]: 20.1 20.1 19.3 20.1 20.1 20.1 20.1 19.3 18.5 20.1 19.3 19.3 19.3 19.3 19.3 18.5
[09:56:46.426] <TB3> INFO: enter test to run
[09:56:46.426] <TB3> INFO: test: pretest no parameter change
[09:56:46.426] <TB3> INFO: running: pretest
[09:56:46.426] <TB3> INFO: ----------------------------------------------------------------------
[09:56:46.426] <TB3> INFO: PixTestPretest::findTiming()
[09:56:46.426] <TB3> INFO: ----------------------------------------------------------------------
[09:56:46.427] <TB3> INFO: PixTestCmd::init()

[09:58:17.113] <TB3> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[09:58:17.113] <TB3> INFO: (success/tries = 100/100), width = 4
[09:58:17.116] <TB3> INFO: enter test to run
[09:58:17.116] <TB3> INFO: test: pretest no parameter change
[09:58:17.116] <TB3> INFO: running: pretest
[09:58:17.118] <TB3> INFO: ----------------------------------------------------------------------
[09:58:17.118] <TB3> INFO: PixTestPretest::findWorkingPixel()
[09:58:17.118] <TB3> INFO: ----------------------------------------------------------------------
[09:58:17.212] <TB3> INFO: Expecting 231680 events.


[09:58:27.362] <TB3> INFO: 231680 events read in total (9594ms).
[09:58:27.365] <TB3> INFO: Test took 10241ms.
[09:58:27.604] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[09:58:27.658] <TB3> INFO: enter test to run
[09:58:27.658] <TB3> INFO: test: pretest no parameter change
[09:58:27.658] <TB3> INFO: running: pretest
[09:58:27.659] <TB3> INFO: ----------------------------------------------------------------------
[09:58:27.660] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[09:58:27.660] <TB3> INFO: ----------------------------------------------------------------------
[09:58:27.751] <TB3> INFO: Expecting 231680 events.
[09:58:37.899] <TB3> INFO: 231680 events read in total (9592ms).
[09:58:37.903] <TB3> INFO: Test took 10240ms.
[09:58:38.138] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[09:58:38.138] <TB3> INFO: CalDel: 144 156 141 138 144 153 160 127 143 153 145 119 132 143 146 159
[09:58:38.138] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[09:58:38.175] <TB3> INFO: enter test to run
[09:58:38.175] <TB3> INFO: test: pretest no parameter change
[09:58:38.175] <TB3> INFO: running: pretest
[09:58:38.175] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters_C0.dat
[09:58:38.175] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters_C1.dat
[09:58:38.175] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters_C2.dat
[09:58:38.175] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters_C3.dat
[09:58:38.175] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters_C4.dat
[09:58:38.175] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters_C5.dat
[09:58:38.175] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters_C6.dat
[09:58:38.176] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters_C7.dat
[09:58:38.176] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters_C8.dat
[09:58:38.176] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters_C9.dat
[09:58:38.176] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters_C10.dat
[09:58:38.176] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters_C11.dat
[09:58:38.176] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters_C12.dat
[09:58:38.176] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters_C13.dat
[09:58:38.176] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters_C14.dat
[09:58:38.176] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters_C15.dat
[09:58:38.176] <TB3> INFO: enter test to run
[09:58:38.176] <TB3> INFO: test: fulltest no parameter change
[09:58:38.177] <TB3> INFO: running: fulltest
[09:58:38.177] <TB3> INFO: ######################################################################
[09:58:38.177] <TB3> INFO: PixTestFullTest::doTest()
[09:58:38.177] <TB3> INFO: ######################################################################
[09:58:38.178] <TB3> INFO: ######################################################################
[09:58:38.178] <TB3> INFO: PixTestAlive::doTest()
[09:58:38.178] <TB3> INFO: ######################################################################
[09:58:38.179] <TB3> INFO: ----------------------------------------------------------------------
[09:58:38.179] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:58:38.179] <TB3> INFO: ----------------------------------------------------------------------
[09:58:38.475] <TB3> INFO: Expecting 41600 events.
[09:58:42.323] <TB3> INFO: 41600 events read in total (3292ms).
[09:58:42.323] <TB3> INFO: Test took 4133ms.
[09:58:42.552] <TB3> INFO: PixTestAlive::aliveTest() done
[09:58:42.552] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 3 1 0 0 0 0 0 0 0
[09:58:42.554] <TB3> INFO: ----------------------------------------------------------------------
[09:58:42.554] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:58:42.554] <TB3> INFO: ----------------------------------------------------------------------
[09:58:42.824] <TB3> INFO: Expecting 41600 events.
[09:58:45.843] <TB3> INFO: 41600 events read in total (2463ms).
[09:58:45.843] <TB3> INFO: Test took 3287ms.
[09:58:45.843] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:58:46.085] <TB3> INFO: PixTestAlive::maskTest() done
[09:58:46.085] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:58:46.086] <TB3> INFO: ----------------------------------------------------------------------
[09:58:46.086] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:58:46.086] <TB3> INFO: ----------------------------------------------------------------------
[09:58:46.374] <TB3> INFO: Expecting 41600 events.
[09:58:50.112] <TB3> INFO: 41600 events read in total (3182ms).
[09:58:50.113] <TB3> INFO: Test took 4025ms.
[09:58:50.337] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[09:58:50.337] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:58:50.337] <TB3> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[09:58:50.337] <TB3> INFO: Decoding statistics:
[09:58:50.337] <TB3> INFO: General information:
[09:58:50.337] <TB3> INFO: 16bit words read: 0
[09:58:50.337] <TB3> INFO: valid events total: 0
[09:58:50.337] <TB3> INFO: empty events: 0
[09:58:50.337] <TB3> INFO: valid events with pixels: 0
[09:58:50.337] <TB3> INFO: valid pixel hits: 0
[09:58:50.337] <TB3> INFO: Event errors: 0
[09:58:50.337] <TB3> INFO: start marker: 0
[09:58:50.337] <TB3> INFO: stop marker: 0
[09:58:50.337] <TB3> INFO: overflow: 0
[09:58:50.337] <TB3> INFO: invalid 5bit words: 0
[09:58:50.337] <TB3> INFO: invalid XOR eye diagram: 0
[09:58:50.337] <TB3> INFO: frame (failed synchr.): 0
[09:58:50.337] <TB3> INFO: idle data (no TBM trl): 0
[09:58:50.337] <TB3> INFO: no data (only TBM hdr): 0
[09:58:50.337] <TB3> INFO: TBM errors: 0
[09:58:50.337] <TB3> INFO: flawed TBM headers: 0
[09:58:50.337] <TB3> INFO: flawed TBM trailers: 0
[09:58:50.337] <TB3> INFO: event ID mismatches: 0
[09:58:50.337] <TB3> INFO: ROC errors: 0
[09:58:50.337] <TB3> INFO: missing ROC header(s): 0
[09:58:50.337] <TB3> INFO: misplaced readback start: 0
[09:58:50.337] <TB3> INFO: Pixel decoding errors: 0
[09:58:50.337] <TB3> INFO: pixel data incomplete: 0
[09:58:50.337] <TB3> INFO: pixel address: 0
[09:58:50.337] <TB3> INFO: pulse height fill bit: 0
[09:58:50.337] <TB3> INFO: buffer corruption: 0
[09:58:50.343] <TB3> INFO: ######################################################################
[09:58:50.343] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[09:58:50.343] <TB3> INFO: ######################################################################
[09:58:50.345] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[09:58:50.356] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[09:58:50.356] <TB3> INFO: run 1 of 1
[09:58:50.620] <TB3> INFO: Expecting 3120000 events.
[09:59:31.306] <TB3> INFO: 823925 events read in total (40130ms).
[10:00:11.115] <TB3> INFO: 1639795 events read in total (79939ms).
[10:00:50.955] <TB3> INFO: 2462930 events read in total (119779ms).
[10:01:22.320] <TB3> INFO: 3120000 events read in total (151144ms).
[10:01:22.374] <TB3> INFO: Test took 152019ms.
[10:01:48.248] <TB3> INFO: PixTestBBMap::doTest() done, duration: 177 seconds
[10:01:48.248] <TB3> INFO: number of dead bumps (per ROC): 0 0 0 1 0 0 0 1 1 0 0 0 0 0 0 1
[10:01:48.248] <TB3> INFO: separation cut (per ROC): 67 87 84 82 84 67 91 102 101 80 90 72 91 75 75 70
[10:01:48.249] <TB3> INFO: Decoding statistics:
[10:01:48.249] <TB3> INFO: General information:
[10:01:48.249] <TB3> INFO: 16bit words read: 0
[10:01:48.249] <TB3> INFO: valid events total: 0
[10:01:48.249] <TB3> INFO: empty events: 0
[10:01:48.249] <TB3> INFO: valid events with pixels: 0
[10:01:48.249] <TB3> INFO: valid pixel hits: 0
[10:01:48.249] <TB3> INFO: Event errors: 0
[10:01:48.249] <TB3> INFO: start marker: 0
[10:01:48.249] <TB3> INFO: stop marker: 0
[10:01:48.249] <TB3> INFO: overflow: 0
[10:01:48.249] <TB3> INFO: invalid 5bit words: 0
[10:01:48.249] <TB3> INFO: invalid XOR eye diagram: 0
[10:01:48.249] <TB3> INFO: frame (failed synchr.): 0
[10:01:48.249] <TB3> INFO: idle data (no TBM trl): 0
[10:01:48.249] <TB3> INFO: no data (only TBM hdr): 0
[10:01:48.249] <TB3> INFO: TBM errors: 0
[10:01:48.249] <TB3> INFO: flawed TBM headers: 0
[10:01:48.249] <TB3> INFO: flawed TBM trailers: 0
[10:01:48.249] <TB3> INFO: event ID mismatches: 0
[10:01:48.249] <TB3> INFO: ROC errors: 0
[10:01:48.249] <TB3> INFO: missing ROC header(s): 0
[10:01:48.249] <TB3> INFO: misplaced readback start: 0
[10:01:48.249] <TB3> INFO: Pixel decoding errors: 0
[10:01:48.249] <TB3> INFO: pixel data incomplete: 0
[10:01:48.249] <TB3> INFO: pixel address: 0
[10:01:48.249] <TB3> INFO: pulse height fill bit: 0
[10:01:48.249] <TB3> INFO: buffer corruption: 0
[10:01:48.323] <TB3> INFO: ######################################################################
[10:01:48.323] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:01:48.323] <TB3> INFO: ######################################################################
[10:01:48.323] <TB3> INFO: ----------------------------------------------------------------------
[10:01:48.323] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[10:01:48.323] <TB3> INFO: ----------------------------------------------------------------------
[10:01:48.323] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:01:48.331] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[10:01:48.331] <TB3> INFO: run 1 of 1
[10:01:48.595] <TB3> INFO: Expecting 26208000 events.
[10:02:22.351] <TB3> INFO: 893250 events read in total (33200ms).
[10:02:55.150] <TB3> INFO: 1771000 events read in total (65999ms).
[10:03:28.254] <TB3> INFO: 2648350 events read in total (99104ms).
[10:04:01.523] <TB3> INFO: 3523750 events read in total (132372ms).
[10:04:34.257] <TB3> INFO: 4396800 events read in total (165107ms).
[10:05:07.101] <TB3> INFO: 5271650 events read in total (197950ms).
[10:05:39.701] <TB3> INFO: 6146850 events read in total (230550ms).
[10:06:12.526] <TB3> INFO: 7020100 events read in total (263375ms).
[10:06:45.638] <TB3> INFO: 7893450 events read in total (296487ms).
[10:07:18.598] <TB3> INFO: 8767500 events read in total (329447ms).
[10:07:51.738] <TB3> INFO: 9642350 events read in total (362587ms).
[10:08:24.550] <TB3> INFO: 10513200 events read in total (395399ms).
[10:08:57.385] <TB3> INFO: 11384150 events read in total (428234ms).
[10:09:30.215] <TB3> INFO: 12257300 events read in total (461064ms).
[10:10:03.197] <TB3> INFO: 13126750 events read in total (494046ms).
[10:10:36.243] <TB3> INFO: 13989400 events read in total (527092ms).
[10:11:10.509] <TB3> INFO: 14853300 events read in total (561358ms).
[10:11:44.311] <TB3> INFO: 15717950 events read in total (595160ms).
[10:12:17.545] <TB3> INFO: 16578000 events read in total (628394ms).
[10:12:50.467] <TB3> INFO: 17437550 events read in total (661316ms).
[10:13:23.133] <TB3> INFO: 18298750 events read in total (693982ms).
[10:13:56.050] <TB3> INFO: 19157300 events read in total (726899ms).
[10:14:29.028] <TB3> INFO: 20015200 events read in total (759877ms).
[10:15:01.625] <TB3> INFO: 20874750 events read in total (792474ms).
[10:15:34.647] <TB3> INFO: 21733850 events read in total (825496ms).
[10:16:07.469] <TB3> INFO: 22589600 events read in total (858318ms).
[10:16:39.982] <TB3> INFO: 23447750 events read in total (890831ms).
[10:17:12.434] <TB3> INFO: 24305300 events read in total (923283ms).
[10:17:45.058] <TB3> INFO: 25164350 events read in total (955907ms).
[10:18:18.839] <TB3> INFO: 26033800 events read in total (989688ms).
[10:18:25.418] <TB3> INFO: 26208000 events read in total (996267ms).
[10:18:25.446] <TB3> INFO: Test took 997114ms.
[10:18:25.696] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:18:27.263] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:18:28.661] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:18:30.135] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:18:31.569] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:18:32.963] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:18:34.460] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:18:35.867] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:18:37.272] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:18:38.636] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:18:40.022] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:18:41.399] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:18:42.784] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:18:44.136] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:18:45.529] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:18:46.937] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[10:18:48.349] <TB3> INFO: PixTestScurves::scurves() done
[10:18:48.349] <TB3> INFO: Vcal mean: 76.63 93.93 83.59 80.24 95.72 80.16 94.80 89.99 95.55 83.39 95.53 83.54 100.42 81.50 83.93 83.01
[10:18:48.349] <TB3> INFO: Vcal RMS: 4.00 4.92 4.43 4.44 5.67 3.76 4.87 5.88 5.43 4.36 5.40 4.71 5.08 4.57 4.62 4.39
[10:18:48.349] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1020 seconds
[10:18:48.349] <TB3> INFO: Decoding statistics:
[10:18:48.349] <TB3> INFO: General information:
[10:18:48.349] <TB3> INFO: 16bit words read: 0
[10:18:48.349] <TB3> INFO: valid events total: 0
[10:18:48.349] <TB3> INFO: empty events: 0
[10:18:48.349] <TB3> INFO: valid events with pixels: 0
[10:18:48.349] <TB3> INFO: valid pixel hits: 0
[10:18:48.349] <TB3> INFO: Event errors: 0
[10:18:48.349] <TB3> INFO: start marker: 0
[10:18:48.349] <TB3> INFO: stop marker: 0
[10:18:48.349] <TB3> INFO: overflow: 0
[10:18:48.349] <TB3> INFO: invalid 5bit words: 0
[10:18:48.349] <TB3> INFO: invalid XOR eye diagram: 0
[10:18:48.349] <TB3> INFO: frame (failed synchr.): 0
[10:18:48.349] <TB3> INFO: idle data (no TBM trl): 0
[10:18:48.349] <TB3> INFO: no data (only TBM hdr): 0
[10:18:48.349] <TB3> INFO: TBM errors: 0
[10:18:48.349] <TB3> INFO: flawed TBM headers: 0
[10:18:48.349] <TB3> INFO: flawed TBM trailers: 0
[10:18:48.349] <TB3> INFO: event ID mismatches: 0
[10:18:48.349] <TB3> INFO: ROC errors: 0
[10:18:48.349] <TB3> INFO: missing ROC header(s): 0
[10:18:48.349] <TB3> INFO: misplaced readback start: 0
[10:18:48.349] <TB3> INFO: Pixel decoding errors: 0
[10:18:48.349] <TB3> INFO: pixel data incomplete: 0
[10:18:48.349] <TB3> INFO: pixel address: 0
[10:18:48.349] <TB3> INFO: pulse height fill bit: 0
[10:18:48.349] <TB3> INFO: buffer corruption: 0
[10:18:48.426] <TB3> INFO: ######################################################################
[10:18:48.426] <TB3> INFO: PixTestTrim::doTest()
[10:18:48.426] <TB3> INFO: ######################################################################
[10:18:48.427] <TB3> INFO: ----------------------------------------------------------------------
[10:18:48.427] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[10:18:48.427] <TB3> INFO: ----------------------------------------------------------------------
[10:18:48.505] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[10:18:48.505] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:18:48.513] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:18:48.513] <TB3> INFO: run 1 of 1
[10:18:48.779] <TB3> INFO: Expecting 6281600 events.
[10:19:28.570] <TB3> INFO: 1057160 events read in total (39233ms).
[10:20:07.615] <TB3> INFO: 2108300 events read in total (78278ms).
[10:20:46.626] <TB3> INFO: 3152620 events read in total (117289ms).
[10:21:25.334] <TB3> INFO: 4200800 events read in total (155997ms).
[10:22:04.266] <TB3> INFO: 5248450 events read in total (194929ms).
[10:22:42.652] <TB3> INFO: 6281600 events read in total (233315ms).
[10:22:42.684] <TB3> INFO: Test took 234171ms.
[10:23:04.488] <TB3> INFO: ROC 0 VthrComp = 84
[10:23:04.488] <TB3> INFO: ROC 1 VthrComp = 102
[10:23:04.488] <TB3> INFO: ROC 2 VthrComp = 92
[10:23:04.488] <TB3> INFO: ROC 3 VthrComp = 87
[10:23:04.488] <TB3> INFO: ROC 4 VthrComp = 100
[10:23:04.488] <TB3> INFO: ROC 5 VthrComp = 89
[10:23:04.488] <TB3> INFO: ROC 6 VthrComp = 100
[10:23:04.488] <TB3> INFO: ROC 7 VthrComp = 98
[10:23:04.489] <TB3> INFO: ROC 8 VthrComp = 99
[10:23:04.489] <TB3> INFO: ROC 9 VthrComp = 90
[10:23:04.489] <TB3> INFO: ROC 10 VthrComp = 98
[10:23:04.489] <TB3> INFO: ROC 11 VthrComp = 91
[10:23:04.489] <TB3> INFO: ROC 12 VthrComp = 107
[10:23:04.489] <TB3> INFO: ROC 13 VthrComp = 90
[10:23:04.489] <TB3> INFO: ROC 14 VthrComp = 93
[10:23:04.489] <TB3> INFO: ROC 15 VthrComp = 88
[10:23:04.489] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[10:23:04.489] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[10:23:04.497] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:23:04.497] <TB3> INFO: run 1 of 1
[10:23:04.793] <TB3> INFO: Expecting 6281600 events.
[10:23:40.869] <TB3> INFO: 754540 events read in total (35520ms).
[10:24:15.785] <TB3> INFO: 1507260 events read in total (70436ms).
[10:24:50.989] <TB3> INFO: 2260090 events read in total (105640ms).
[10:25:25.910] <TB3> INFO: 3013360 events read in total (140561ms).
[10:26:01.612] <TB3> INFO: 3762260 events read in total (176263ms).
[10:26:36.741] <TB3> INFO: 4507580 events read in total (211392ms).
[10:27:11.814] <TB3> INFO: 5250980 events read in total (246465ms).
[10:27:47.063] <TB3> INFO: 5993620 events read in total (281714ms).
[10:28:00.443] <TB3> INFO: 6281600 events read in total (295094ms).
[10:28:00.483] <TB3> INFO: Test took 295985ms.
[10:28:25.018] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 56.1706 for pixel 10/46 mean/min/max = 44.0933/31.92/56.2665
[10:28:25.019] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 56.9842 for pixel 20/3 mean/min/max = 44.7659/32.5366/56.9951
[10:28:25.019] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 56.9454 for pixel 34/1 mean/min/max = 44.9317/32.8855/56.9778
[10:28:25.019] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 57.1892 for pixel 18/78 mean/min/max = 44.453/31.6748/57.2311
[10:28:25.019] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 58.6855 for pixel 0/12 mean/min/max = 45.7725/32.3349/59.2101
[10:28:25.020] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 55.8577 for pixel 50/2 mean/min/max = 44.5863/33.2889/55.8837
[10:28:25.020] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 56.9036 for pixel 21/5 mean/min/max = 44.5081/32.0894/56.9267
[10:28:25.020] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 59.0463 for pixel 13/9 mean/min/max = 45.7374/32.2706/59.2043
[10:28:25.020] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 59.5446 for pixel 0/6 mean/min/max = 45.7446/31.8822/59.6071
[10:28:25.021] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 56.7324 for pixel 38/53 mean/min/max = 45.2639/33.4112/57.1166
[10:28:25.021] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 58.3298 for pixel 45/73 mean/min/max = 45.4652/32.4913/58.439
[10:28:25.021] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 57.0923 for pixel 47/77 mean/min/max = 45.1483/33.0829/57.2138
[10:28:25.021] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 59.8922 for pixel 24/2 mean/min/max = 46.8332/33.7581/59.9084
[10:28:25.022] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 58.0667 for pixel 32/21 mean/min/max = 45.4215/32.604/58.2391
[10:28:25.022] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 56.7945 for pixel 0/69 mean/min/max = 44.6569/32.4432/56.8707
[10:28:25.022] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 56.725 for pixel 5/10 mean/min/max = 44.914/32.942/56.8859
[10:28:25.023] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:28:25.110] <TB3> INFO: Expecting 514560 events.
[10:28:38.244] <TB3> INFO: 514560 events read in total (12578ms).
[10:28:38.249] <TB3> INFO: Expecting 514560 events.
[10:28:51.291] <TB3> INFO: 514560 events read in total (12626ms).
[10:28:51.300] <TB3> INFO: Expecting 514560 events.
[10:29:04.221] <TB3> INFO: 514560 events read in total (12519ms).
[10:29:04.234] <TB3> INFO: Expecting 514560 events.
[10:29:17.300] <TB3> INFO: 514560 events read in total (12658ms).
[10:29:17.314] <TB3> INFO: Expecting 514560 events.
[10:29:29.976] <TB3> INFO: 514560 events read in total (12259ms).
[10:29:29.989] <TB3> INFO: Expecting 514560 events.
[10:29:43.013] <TB3> INFO: 514560 events read in total (12612ms).
[10:29:43.028] <TB3> INFO: Expecting 514560 events.
[10:29:55.858] <TB3> INFO: 514560 events read in total (12421ms).
[10:29:55.876] <TB3> INFO: Expecting 514560 events.
[10:30:08.901] <TB3> INFO: 514560 events read in total (12618ms).
[10:30:08.924] <TB3> INFO: Expecting 514560 events.
[10:30:21.863] <TB3> INFO: 514560 events read in total (12537ms).
[10:30:21.886] <TB3> INFO: Expecting 514560 events.
[10:30:34.873] <TB3> INFO: 514560 events read in total (12585ms).
[10:30:34.896] <TB3> INFO: Expecting 514560 events.
[10:30:47.763] <TB3> INFO: 514560 events read in total (12465ms).
[10:30:47.789] <TB3> INFO: Expecting 514560 events.
[10:31:00.837] <TB3> INFO: 514560 events read in total (12646ms).
[10:31:00.869] <TB3> INFO: Expecting 514560 events.
[10:31:13.848] <TB3> INFO: 514560 events read in total (12577ms).
[10:31:13.879] <TB3> INFO: Expecting 514560 events.
[10:31:26.686] <TB3> INFO: 514560 events read in total (12405ms).
[10:31:26.719] <TB3> INFO: Expecting 514560 events.
[10:31:39.356] <TB3> INFO: 514560 events read in total (12235ms).
[10:31:39.404] <TB3> INFO: Expecting 514560 events.
[10:31:52.324] <TB3> INFO: 514560 events read in total (12518ms).
[10:31:52.377] <TB3> INFO: Test took 207354ms.
[10:31:53.531] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[10:31:53.539] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:31:53.539] <TB3> INFO: run 1 of 1
[10:31:53.829] <TB3> INFO: Expecting 6281600 events.
[10:32:29.969] <TB3> INFO: 742680 events read in total (35584ms).
[10:33:05.056] <TB3> INFO: 1483890 events read in total (70672ms).
[10:33:40.640] <TB3> INFO: 2225390 events read in total (106255ms).
[10:34:16.029] <TB3> INFO: 2967050 events read in total (141644ms).
[10:34:50.941] <TB3> INFO: 3704320 events read in total (176556ms).
[10:35:26.050] <TB3> INFO: 4437640 events read in total (211665ms).
[10:36:01.191] <TB3> INFO: 5169250 events read in total (246806ms).
[10:36:36.653] <TB3> INFO: 5899650 events read in total (282268ms).
[10:36:54.985] <TB3> INFO: 6281600 events read in total (300600ms).
[10:36:55.031] <TB3> INFO: Test took 301492ms.
[10:37:20.136] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 0.065764 .. 53.311069
[10:37:20.214] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 63 (-1/-1) hits flags = 528 (plus default)
[10:37:20.223] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:37:20.223] <TB3> INFO: run 1 of 1
[10:37:20.485] <TB3> INFO: Expecting 2662400 events.
[10:37:58.089] <TB3> INFO: 913080 events read in total (37046ms).
[10:38:35.886] <TB3> INFO: 1825120 events read in total (74843ms).
[10:39:10.652] <TB3> INFO: 2662400 events read in total (109610ms).
[10:39:10.674] <TB3> INFO: Test took 110452ms.
[10:39:25.340] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 14.609181 .. 46.932895
[10:39:25.418] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 4 .. 56 (-1/-1) hits flags = 528 (plus default)
[10:39:25.427] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:39:25.427] <TB3> INFO: run 1 of 1
[10:39:25.687] <TB3> INFO: Expecting 2204800 events.
[10:40:04.353] <TB3> INFO: 936300 events read in total (38110ms).
[10:40:42.575] <TB3> INFO: 1871790 events read in total (76332ms).
[10:40:56.461] <TB3> INFO: 2204800 events read in total (90218ms).
[10:40:56.482] <TB3> INFO: Test took 91054ms.
[10:41:10.405] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 19.505120 .. 44.795639
[10:41:10.480] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 9 .. 54 (-1/-1) hits flags = 528 (plus default)
[10:41:10.488] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:41:10.488] <TB3> INFO: run 1 of 1
[10:41:10.763] <TB3> INFO: Expecting 1913600 events.
[10:41:50.478] <TB3> INFO: 926350 events read in total (39159ms).
[10:42:28.484] <TB3> INFO: 1851030 events read in total (77166ms).
[10:42:31.368] <TB3> INFO: 1913600 events read in total (80049ms).
[10:42:31.381] <TB3> INFO: Test took 80892ms.
[10:42:44.874] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 22.686181 .. 43.952285
[10:42:44.964] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 12 .. 53 (-1/-1) hits flags = 528 (plus default)
[10:42:44.974] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:42:44.974] <TB3> INFO: run 1 of 1
[10:42:45.249] <TB3> INFO: Expecting 1747200 events.
[10:43:23.749] <TB3> INFO: 914930 events read in total (37944ms).
[10:43:58.058] <TB3> INFO: 1747200 events read in total (72253ms).
[10:43:58.070] <TB3> INFO: Test took 73096ms.
[10:44:11.167] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[10:44:11.167] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[10:44:11.176] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[10:44:11.176] <TB3> INFO: run 1 of 1
[10:44:11.437] <TB3> INFO: Expecting 1705600 events.
[10:44:49.278] <TB3> INFO: 879240 events read in total (37285ms).
[10:45:24.076] <TB3> INFO: 1705600 events read in total (72084ms).
[10:45:24.100] <TB3> INFO: Test took 72924ms.
[10:45:38.247] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C0.dat
[10:45:38.247] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C1.dat
[10:45:38.247] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C2.dat
[10:45:38.247] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C3.dat
[10:45:38.247] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C4.dat
[10:45:38.247] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C5.dat
[10:45:38.248] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C6.dat
[10:45:38.248] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C7.dat
[10:45:38.248] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C8.dat
[10:45:38.248] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C9.dat
[10:45:38.248] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C10.dat
[10:45:38.248] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C11.dat
[10:45:38.248] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C12.dat
[10:45:38.248] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C13.dat
[10:45:38.248] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C14.dat
[10:45:38.248] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C15.dat
[10:45:38.249] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//trimParameters35_C0.dat
[10:45:38.255] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//trimParameters35_C1.dat
[10:45:38.261] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//trimParameters35_C2.dat
[10:45:38.268] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//trimParameters35_C3.dat
[10:45:38.274] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//trimParameters35_C4.dat
[10:45:38.281] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//trimParameters35_C5.dat
[10:45:38.288] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//trimParameters35_C6.dat
[10:45:38.294] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//trimParameters35_C7.dat
[10:45:38.301] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//trimParameters35_C8.dat
[10:45:38.308] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//trimParameters35_C9.dat
[10:45:38.314] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//trimParameters35_C10.dat
[10:45:38.321] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//trimParameters35_C11.dat
[10:45:38.328] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//trimParameters35_C12.dat
[10:45:38.334] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//trimParameters35_C13.dat
[10:45:38.341] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//trimParameters35_C14.dat
[10:45:38.348] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//trimParameters35_C15.dat
[10:45:38.354] <TB3> INFO: PixTestTrim::trimTest() done
[10:45:38.354] <TB3> INFO: vtrim: 100 108 104 107 90 93 96 123 107 91 88 115 110 105 92 92
[10:45:38.354] <TB3> INFO: vthrcomp: 84 102 92 87 100 89 100 98 99 90 98 91 107 90 93 88
[10:45:38.354] <TB3> INFO: vcal mean: 34.97 35.06 34.98 34.98 35.01 35.00 34.97 34.98 34.91 35.03 34.97 34.93 35.01 34.97 34.98 35.03
[10:45:38.354] <TB3> INFO: vcal RMS: 0.81 0.84 0.82 0.79 1.02 0.74 0.85 1.25 1.01 0.75 0.86 0.79 0.82 0.79 0.78 0.81
[10:45:38.354] <TB3> INFO: bits mean: 9.96 9.76 9.56 9.86 8.63 9.21 9.73 9.52 9.36 9.16 9.27 9.41 8.76 9.45 9.29 9.09
[10:45:38.354] <TB3> INFO: bits RMS: 2.57 2.57 2.62 2.63 3.03 2.68 2.66 2.65 2.76 2.63 2.73 2.58 2.68 2.64 2.78 2.73
[10:45:38.361] <TB3> INFO: ----------------------------------------------------------------------
[10:45:38.361] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[10:45:38.361] <TB3> INFO: ----------------------------------------------------------------------
[10:45:38.363] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[10:45:38.371] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:45:38.371] <TB3> INFO: run 1 of 1
[10:45:38.662] <TB3> INFO: Expecting 4160000 events.
[10:46:20.537] <TB3> INFO: 916530 events read in total (41319ms).
[10:47:01.823] <TB3> INFO: 1827875 events read in total (82605ms).
[10:47:42.717] <TB3> INFO: 2732565 events read in total (123499ms).
[10:48:23.522] <TB3> INFO: 3633305 events read in total (164304ms).
[10:48:47.672] <TB3> INFO: 4160000 events read in total (188454ms).
[10:48:47.716] <TB3> INFO: Test took 189344ms.
[10:49:17.279] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 162 (-1/-1) hits flags = 528 (plus default)
[10:49:17.287] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:49:17.287] <TB3> INFO: run 1 of 1
[10:49:17.549] <TB3> INFO: Expecting 3390400 events.
[10:50:00.789] <TB3> INFO: 962215 events read in total (42684ms).
[10:50:42.765] <TB3> INFO: 1915550 events read in total (84660ms).
[10:51:24.756] <TB3> INFO: 2859300 events read in total (126652ms).
[10:51:48.167] <TB3> INFO: 3390400 events read in total (150062ms).
[10:51:48.199] <TB3> INFO: Test took 150913ms.
[10:52:13.911] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 152 (-1/-1) hits flags = 528 (plus default)
[10:52:13.919] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:52:13.919] <TB3> INFO: run 1 of 1
[10:52:14.182] <TB3> INFO: Expecting 3182400 events.
[10:52:57.799] <TB3> INFO: 990370 events read in total (43061ms).
[10:53:40.053] <TB3> INFO: 1969515 events read in total (85315ms).
[10:54:22.468] <TB3> INFO: 2938500 events read in total (127730ms).
[10:54:33.599] <TB3> INFO: 3182400 events read in total (138861ms).
[10:54:33.630] <TB3> INFO: Test took 139711ms.
[10:55:00.023] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 153 (-1/-1) hits flags = 528 (plus default)
[10:55:00.031] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:55:00.031] <TB3> INFO: run 1 of 1
[10:55:00.319] <TB3> INFO: Expecting 3203200 events.
[10:55:43.568] <TB3> INFO: 986475 events read in total (42693ms).
[10:56:25.656] <TB3> INFO: 1961980 events read in total (84782ms).
[10:57:07.976] <TB3> INFO: 2927770 events read in total (127102ms).
[10:57:20.244] <TB3> INFO: 3203200 events read in total (139369ms).
[10:57:20.277] <TB3> INFO: Test took 140246ms.
[10:57:44.651] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 151 (-1/-1) hits flags = 528 (plus default)
[10:57:44.659] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[10:57:44.660] <TB3> INFO: run 1 of 1
[10:57:44.922] <TB3> INFO: Expecting 3161600 events.
[10:58:27.914] <TB3> INFO: 992245 events read in total (42436ms).
[10:59:10.869] <TB3> INFO: 1972820 events read in total (85391ms).
[10:59:53.362] <TB3> INFO: 2943815 events read in total (127885ms).
[11:00:03.227] <TB3> INFO: 3161600 events read in total (137749ms).
[11:00:03.257] <TB3> INFO: Test took 138597ms.
[11:00:27.525] <TB3> INFO: PixTestTrim::trimBitTest() done
[11:00:27.526] <TB3> INFO: PixTestTrim::doTest() done, duration: 2499 seconds
[11:00:27.526] <TB3> INFO: Decoding statistics:
[11:00:27.526] <TB3> INFO: General information:
[11:00:27.526] <TB3> INFO: 16bit words read: 0
[11:00:27.526] <TB3> INFO: valid events total: 0
[11:00:27.526] <TB3> INFO: empty events: 0
[11:00:27.526] <TB3> INFO: valid events with pixels: 0
[11:00:27.526] <TB3> INFO: valid pixel hits: 0
[11:00:27.526] <TB3> INFO: Event errors: 0
[11:00:27.526] <TB3> INFO: start marker: 0
[11:00:27.526] <TB3> INFO: stop marker: 0
[11:00:27.526] <TB3> INFO: overflow: 0
[11:00:27.526] <TB3> INFO: invalid 5bit words: 0
[11:00:27.526] <TB3> INFO: invalid XOR eye diagram: 0
[11:00:27.526] <TB3> INFO: frame (failed synchr.): 0
[11:00:27.526] <TB3> INFO: idle data (no TBM trl): 0
[11:00:27.526] <TB3> INFO: no data (only TBM hdr): 0
[11:00:27.526] <TB3> INFO: TBM errors: 0
[11:00:27.526] <TB3> INFO: flawed TBM headers: 0
[11:00:27.526] <TB3> INFO: flawed TBM trailers: 0
[11:00:27.526] <TB3> INFO: event ID mismatches: 0
[11:00:27.526] <TB3> INFO: ROC errors: 0
[11:00:27.526] <TB3> INFO: missing ROC header(s): 0
[11:00:27.526] <TB3> INFO: misplaced readback start: 0
[11:00:27.526] <TB3> INFO: Pixel decoding errors: 0
[11:00:27.526] <TB3> INFO: pixel data incomplete: 0
[11:00:27.526] <TB3> INFO: pixel address: 0
[11:00:27.526] <TB3> INFO: pulse height fill bit: 0
[11:00:27.526] <TB3> INFO: buffer corruption: 0
[11:00:28.191] <TB3> INFO: ######################################################################
[11:00:28.191] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[11:00:28.191] <TB3> INFO: ######################################################################
[11:00:28.457] <TB3> INFO: Expecting 41600 events.
[11:00:32.200] <TB3> INFO: 41600 events read in total (3186ms).
[11:00:32.201] <TB3> INFO: Test took 4008ms.
[11:00:32.667] <TB3> INFO: Expecting 41600 events.
[11:00:36.467] <TB3> INFO: 41600 events read in total (3244ms).
[11:00:36.467] <TB3> INFO: Test took 4064ms.
[11:00:36.769] <TB3> INFO: Expecting 41600 events.
[11:00:40.693] <TB3> INFO: 41600 events read in total (3368ms).
[11:00:40.694] <TB3> INFO: Test took 4205ms.
[11:00:40.950] <TB3> INFO: Expecting 2560 events.
[11:00:41.803] <TB3> INFO: 2560 events read in total (297ms).
[11:00:41.803] <TB3> INFO: Test took 1095ms.
[11:00:42.108] <TB3> INFO: Expecting 2560 events.
[11:00:42.961] <TB3> INFO: 2560 events read in total (297ms).
[11:00:42.961] <TB3> INFO: Test took 1156ms.
[11:00:43.266] <TB3> INFO: Expecting 2560 events.
[11:00:44.119] <TB3> INFO: 2560 events read in total (297ms).
[11:00:44.119] <TB3> INFO: Test took 1157ms.
[11:00:44.424] <TB3> INFO: Expecting 2560 events.
[11:00:45.277] <TB3> INFO: 2560 events read in total (297ms).
[11:00:45.277] <TB3> INFO: Test took 1158ms.
[11:00:45.582] <TB3> INFO: Expecting 2560 events.
[11:00:46.436] <TB3> INFO: 2560 events read in total (298ms).
[11:00:46.437] <TB3> INFO: Test took 1159ms.
[11:00:46.741] <TB3> INFO: Expecting 2560 events.
[11:00:47.596] <TB3> INFO: 2560 events read in total (298ms).
[11:00:47.596] <TB3> INFO: Test took 1159ms.
[11:00:47.901] <TB3> INFO: Expecting 2560 events.
[11:00:48.754] <TB3> INFO: 2560 events read in total (297ms).
[11:00:48.754] <TB3> INFO: Test took 1157ms.
[11:00:49.059] <TB3> INFO: Expecting 2560 events.
[11:00:49.914] <TB3> INFO: 2560 events read in total (299ms).
[11:00:49.914] <TB3> INFO: Test took 1160ms.
[11:00:50.219] <TB3> INFO: Expecting 2560 events.
[11:00:51.075] <TB3> INFO: 2560 events read in total (300ms).
[11:00:51.076] <TB3> INFO: Test took 1162ms.
[11:00:51.382] <TB3> INFO: Expecting 2560 events.
[11:00:52.235] <TB3> INFO: 2560 events read in total (297ms).
[11:00:52.235] <TB3> INFO: Test took 1159ms.
[11:00:52.540] <TB3> INFO: Expecting 2560 events.
[11:00:53.395] <TB3> INFO: 2560 events read in total (299ms).
[11:00:53.395] <TB3> INFO: Test took 1159ms.
[11:00:53.700] <TB3> INFO: Expecting 2560 events.
[11:00:54.556] <TB3> INFO: 2560 events read in total (300ms).
[11:00:54.557] <TB3> INFO: Test took 1162ms.
[11:00:54.861] <TB3> INFO: Expecting 2560 events.
[11:00:55.715] <TB3> INFO: 2560 events read in total (297ms).
[11:00:55.715] <TB3> INFO: Test took 1158ms.
[11:00:56.020] <TB3> INFO: Expecting 2560 events.
[11:00:56.872] <TB3> INFO: 2560 events read in total (296ms).
[11:00:56.872] <TB3> INFO: Test took 1157ms.
[11:00:57.178] <TB3> INFO: Expecting 2560 events.
[11:00:58.030] <TB3> INFO: 2560 events read in total (296ms).
[11:00:58.030] <TB3> INFO: Test took 1157ms.
[11:00:58.335] <TB3> INFO: Expecting 2560 events.
[11:00:59.187] <TB3> INFO: 2560 events read in total (296ms).
[11:00:59.187] <TB3> INFO: Test took 1156ms.
[11:00:59.190] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:00:59.493] <TB3> INFO: Expecting 655360 events.
[11:01:16.077] <TB3> INFO: 655360 events read in total (16028ms).
[11:01:16.086] <TB3> INFO: Expecting 655360 events.
[11:01:32.659] <TB3> INFO: 655360 events read in total (16172ms).
[11:01:32.673] <TB3> INFO: Expecting 655360 events.
[11:01:49.294] <TB3> INFO: 655360 events read in total (16219ms).
[11:01:49.309] <TB3> INFO: Expecting 655360 events.
[11:02:05.882] <TB3> INFO: 655360 events read in total (16171ms).
[11:02:05.901] <TB3> INFO: Expecting 655360 events.
[11:02:22.391] <TB3> INFO: 655360 events read in total (16089ms).
[11:02:22.414] <TB3> INFO: Expecting 655360 events.
[11:02:39.134] <TB3> INFO: 655360 events read in total (16318ms).
[11:02:39.176] <TB3> INFO: Expecting 655360 events.
[11:02:55.734] <TB3> INFO: 655360 events read in total (16157ms).
[11:02:55.763] <TB3> INFO: Expecting 655360 events.
[11:03:12.492] <TB3> INFO: 655360 events read in total (16327ms).
[11:03:12.530] <TB3> INFO: Expecting 655360 events.
[11:03:29.291] <TB3> INFO: 655360 events read in total (16359ms).
[11:03:29.328] <TB3> INFO: Expecting 655360 events.
[11:03:45.940] <TB3> INFO: 655360 events read in total (16210ms).
[11:03:45.982] <TB3> INFO: Expecting 655360 events.
[11:04:02.764] <TB3> INFO: 655360 events read in total (16380ms).
[11:04:02.827] <TB3> INFO: Expecting 655360 events.
[11:04:19.760] <TB3> INFO: 655360 events read in total (16531ms).
[11:04:19.812] <TB3> INFO: Expecting 655360 events.
[11:04:36.455] <TB3> INFO: 655360 events read in total (16241ms).
[11:04:36.518] <TB3> INFO: Expecting 655360 events.
[11:04:53.162] <TB3> INFO: 655360 events read in total (16243ms).
[11:04:53.216] <TB3> INFO: Expecting 655360 events.
[11:05:09.971] <TB3> INFO: 655360 events read in total (16354ms).
[11:05:10.031] <TB3> INFO: Expecting 655360 events.
[11:05:26.679] <TB3> INFO: 655360 events read in total (16246ms).
[11:05:26.761] <TB3> INFO: Test took 267571ms.
[11:05:26.849] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:05:27.079] <TB3> INFO: Expecting 655360 events.
[11:05:44.048] <TB3> INFO: 655360 events read in total (16413ms).
[11:05:44.058] <TB3> INFO: Expecting 655360 events.
[11:06:00.614] <TB3> INFO: 655360 events read in total (16155ms).
[11:06:00.629] <TB3> INFO: Expecting 655360 events.
[11:06:17.332] <TB3> INFO: 655360 events read in total (16301ms).
[11:06:17.349] <TB3> INFO: Expecting 655360 events.
[11:06:33.927] <TB3> INFO: 655360 events read in total (16176ms).
[11:06:33.947] <TB3> INFO: Expecting 655360 events.
[11:06:50.604] <TB3> INFO: 655360 events read in total (16256ms).
[11:06:50.632] <TB3> INFO: Expecting 655360 events.
[11:07:07.334] <TB3> INFO: 655360 events read in total (16301ms).
[11:07:07.361] <TB3> INFO: Expecting 655360 events.
[11:07:24.212] <TB3> INFO: 655360 events read in total (16449ms).
[11:07:24.241] <TB3> INFO: Expecting 655360 events.
[11:07:40.890] <TB3> INFO: 655360 events read in total (16247ms).
[11:07:40.928] <TB3> INFO: Expecting 655360 events.
[11:07:57.577] <TB3> INFO: 655360 events read in total (16247ms).
[11:07:57.613] <TB3> INFO: Expecting 655360 events.
[11:08:14.346] <TB3> INFO: 655360 events read in total (16331ms).
[11:08:14.390] <TB3> INFO: Expecting 655360 events.
[11:08:31.122] <TB3> INFO: 655360 events read in total (16330ms).
[11:08:31.175] <TB3> INFO: Expecting 655360 events.
[11:08:47.968] <TB3> INFO: 655360 events read in total (16391ms).
[11:08:48.018] <TB3> INFO: Expecting 655360 events.
[11:09:04.699] <TB3> INFO: 655360 events read in total (16279ms).
[11:09:04.753] <TB3> INFO: Expecting 655360 events.
[11:09:21.389] <TB3> INFO: 655360 events read in total (16234ms).
[11:09:21.442] <TB3> INFO: Expecting 655360 events.
[11:09:38.161] <TB3> INFO: 655360 events read in total (16318ms).
[11:09:38.219] <TB3> INFO: Expecting 655360 events.
[11:09:54.875] <TB3> INFO: 655360 events read in total (16255ms).
[11:09:54.942] <TB3> INFO: Test took 268093ms.
[11:09:55.130] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:55.136] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:55.143] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:55.149] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:55.156] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:55.163] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:55.169] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:55.176] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:55.182] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[11:09:55.189] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:55.195] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:55.202] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:55.208] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:55.215] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:55.221] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:55.228] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:55.234] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[11:09:55.270] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C0.dat
[11:09:55.270] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C1.dat
[11:09:55.270] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C2.dat
[11:09:55.271] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C3.dat
[11:09:55.271] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C4.dat
[11:09:55.271] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C5.dat
[11:09:55.271] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C6.dat
[11:09:55.271] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C7.dat
[11:09:55.271] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C8.dat
[11:09:55.271] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C9.dat
[11:09:55.271] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C10.dat
[11:09:55.271] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C11.dat
[11:09:55.271] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C12.dat
[11:09:55.272] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C13.dat
[11:09:55.272] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C14.dat
[11:09:55.272] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//dacParameters35_C15.dat
[11:09:55.537] <TB3> INFO: Expecting 41600 events.
[11:09:59.066] <TB3> INFO: 41600 events read in total (2973ms).
[11:09:59.066] <TB3> INFO: Test took 3792ms.
[11:09:59.536] <TB3> INFO: Expecting 41600 events.
[11:10:03.003] <TB3> INFO: 41600 events read in total (2911ms).
[11:10:03.004] <TB3> INFO: Test took 3755ms.
[11:10:03.450] <TB3> INFO: Expecting 41600 events.
[11:10:06.928] <TB3> INFO: 41600 events read in total (2922ms).
[11:10:06.929] <TB3> INFO: Test took 3750ms.
[11:10:07.108] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:07.196] <TB3> INFO: Expecting 2560 events.
[11:10:08.050] <TB3> INFO: 2560 events read in total (298ms).
[11:10:08.050] <TB3> INFO: Test took 942ms.
[11:10:08.052] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:08.356] <TB3> INFO: Expecting 2560 events.
[11:10:09.211] <TB3> INFO: 2560 events read in total (299ms).
[11:10:09.212] <TB3> INFO: Test took 1160ms.
[11:10:09.213] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:09.517] <TB3> INFO: Expecting 2560 events.
[11:10:10.376] <TB3> INFO: 2560 events read in total (303ms).
[11:10:10.376] <TB3> INFO: Test took 1163ms.
[11:10:10.378] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:10.682] <TB3> INFO: Expecting 2560 events.
[11:10:11.535] <TB3> INFO: 2560 events read in total (297ms).
[11:10:11.535] <TB3> INFO: Test took 1157ms.
[11:10:11.537] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:11.841] <TB3> INFO: Expecting 2560 events.
[11:10:12.694] <TB3> INFO: 2560 events read in total (297ms).
[11:10:12.695] <TB3> INFO: Test took 1159ms.
[11:10:12.698] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:12.999] <TB3> INFO: Expecting 2560 events.
[11:10:13.859] <TB3> INFO: 2560 events read in total (303ms).
[11:10:13.859] <TB3> INFO: Test took 1161ms.
[11:10:13.860] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:14.164] <TB3> INFO: Expecting 2560 events.
[11:10:15.017] <TB3> INFO: 2560 events read in total (297ms).
[11:10:15.017] <TB3> INFO: Test took 1157ms.
[11:10:15.019] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:15.323] <TB3> INFO: Expecting 2560 events.
[11:10:16.176] <TB3> INFO: 2560 events read in total (297ms).
[11:10:16.176] <TB3> INFO: Test took 1157ms.
[11:10:16.179] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:16.482] <TB3> INFO: Expecting 2560 events.
[11:10:17.341] <TB3> INFO: 2560 events read in total (303ms).
[11:10:17.341] <TB3> INFO: Test took 1162ms.
[11:10:17.346] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:17.647] <TB3> INFO: Expecting 2560 events.
[11:10:18.505] <TB3> INFO: 2560 events read in total (302ms).
[11:10:18.505] <TB3> INFO: Test took 1159ms.
[11:10:18.507] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:18.811] <TB3> INFO: Expecting 2560 events.
[11:10:19.669] <TB3> INFO: 2560 events read in total (302ms).
[11:10:19.669] <TB3> INFO: Test took 1162ms.
[11:10:19.671] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:19.976] <TB3> INFO: Expecting 2560 events.
[11:10:20.835] <TB3> INFO: 2560 events read in total (303ms).
[11:10:20.835] <TB3> INFO: Test took 1164ms.
[11:10:20.837] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:21.141] <TB3> INFO: Expecting 2560 events.
[11:10:21.995] <TB3> INFO: 2560 events read in total (298ms).
[11:10:21.995] <TB3> INFO: Test took 1158ms.
[11:10:21.996] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:22.301] <TB3> INFO: Expecting 2560 events.
[11:10:23.154] <TB3> INFO: 2560 events read in total (297ms).
[11:10:23.154] <TB3> INFO: Test took 1158ms.
[11:10:23.156] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:23.460] <TB3> INFO: Expecting 2560 events.
[11:10:24.313] <TB3> INFO: 2560 events read in total (297ms).
[11:10:24.313] <TB3> INFO: Test took 1157ms.
[11:10:24.315] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:24.618] <TB3> INFO: Expecting 2560 events.
[11:10:25.472] <TB3> INFO: 2560 events read in total (297ms).
[11:10:25.472] <TB3> INFO: Test took 1157ms.
[11:10:25.475] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:25.778] <TB3> INFO: Expecting 2560 events.
[11:10:26.632] <TB3> INFO: 2560 events read in total (298ms).
[11:10:26.632] <TB3> INFO: Test took 1157ms.
[11:10:26.633] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:26.938] <TB3> INFO: Expecting 2560 events.
[11:10:27.792] <TB3> INFO: 2560 events read in total (298ms).
[11:10:27.792] <TB3> INFO: Test took 1159ms.
[11:10:27.794] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:28.098] <TB3> INFO: Expecting 2560 events.
[11:10:28.951] <TB3> INFO: 2560 events read in total (297ms).
[11:10:28.952] <TB3> INFO: Test took 1158ms.
[11:10:28.953] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:29.257] <TB3> INFO: Expecting 2560 events.
[11:10:30.112] <TB3> INFO: 2560 events read in total (299ms).
[11:10:30.112] <TB3> INFO: Test took 1159ms.
[11:10:30.114] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:30.417] <TB3> INFO: Expecting 2560 events.
[11:10:31.271] <TB3> INFO: 2560 events read in total (298ms).
[11:10:31.271] <TB3> INFO: Test took 1157ms.
[11:10:31.274] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:31.577] <TB3> INFO: Expecting 2560 events.
[11:10:32.431] <TB3> INFO: 2560 events read in total (298ms).
[11:10:32.431] <TB3> INFO: Test took 1157ms.
[11:10:32.433] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:32.739] <TB3> INFO: Expecting 2560 events.
[11:10:33.598] <TB3> INFO: 2560 events read in total (303ms).
[11:10:33.606] <TB3> INFO: Test took 1173ms.
[11:10:33.609] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:33.903] <TB3> INFO: Expecting 2560 events.
[11:10:34.756] <TB3> INFO: 2560 events read in total (296ms).
[11:10:34.756] <TB3> INFO: Test took 1147ms.
[11:10:34.758] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:35.062] <TB3> INFO: Expecting 2560 events.
[11:10:35.915] <TB3> INFO: 2560 events read in total (297ms).
[11:10:35.915] <TB3> INFO: Test took 1157ms.
[11:10:35.917] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:36.220] <TB3> INFO: Expecting 2560 events.
[11:10:37.075] <TB3> INFO: 2560 events read in total (298ms).
[11:10:37.075] <TB3> INFO: Test took 1158ms.
[11:10:37.077] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:37.380] <TB3> INFO: Expecting 2560 events.
[11:10:38.235] <TB3> INFO: 2560 events read in total (299ms).
[11:10:38.235] <TB3> INFO: Test took 1158ms.
[11:10:38.237] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:38.540] <TB3> INFO: Expecting 2560 events.
[11:10:39.394] <TB3> INFO: 2560 events read in total (297ms).
[11:10:39.394] <TB3> INFO: Test took 1157ms.
[11:10:39.396] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:39.700] <TB3> INFO: Expecting 2560 events.
[11:10:40.554] <TB3> INFO: 2560 events read in total (298ms).
[11:10:40.555] <TB3> INFO: Test took 1159ms.
[11:10:40.556] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:40.860] <TB3> INFO: Expecting 2560 events.
[11:10:41.713] <TB3> INFO: 2560 events read in total (297ms).
[11:10:41.713] <TB3> INFO: Test took 1157ms.
[11:10:41.715] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:42.018] <TB3> INFO: Expecting 2560 events.
[11:10:42.871] <TB3> INFO: 2560 events read in total (296ms).
[11:10:42.872] <TB3> INFO: Test took 1158ms.
[11:10:42.873] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[11:10:43.177] <TB3> INFO: Expecting 2560 events.
[11:10:44.031] <TB3> INFO: 2560 events read in total (298ms).
[11:10:44.031] <TB3> INFO: Test took 1158ms.
[11:10:44.483] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 616 seconds
[11:10:44.483] <TB3> INFO: PH scale (per ROC): 99 96 90 87 76 91 80 80 75 81 84 86 81 84 81 90
[11:10:44.483] <TB3> INFO: PH offset (per ROC): 134 142 161 140 164 148 155 160 176 137 160 146 161 146 147 140
[11:10:44.488] <TB3> INFO: Decoding statistics:
[11:10:44.488] <TB3> INFO: General information:
[11:10:44.488] <TB3> INFO: 16bit words read: 91932
[11:10:44.488] <TB3> INFO: valid events total: 10240
[11:10:44.488] <TB3> INFO: empty events: 7737
[11:10:44.488] <TB3> INFO: valid events with pixels: 2503
[11:10:44.488] <TB3> INFO: valid pixel hits: 2503
[11:10:44.488] <TB3> INFO: Event errors: 0
[11:10:44.488] <TB3> INFO: start marker: 0
[11:10:44.488] <TB3> INFO: stop marker: 0
[11:10:44.488] <TB3> INFO: overflow: 0
[11:10:44.488] <TB3> INFO: invalid 5bit words: 0
[11:10:44.488] <TB3> INFO: invalid XOR eye diagram: 0
[11:10:44.488] <TB3> INFO: frame (failed synchr.): 0
[11:10:44.488] <TB3> INFO: idle data (no TBM trl): 0
[11:10:44.488] <TB3> INFO: no data (only TBM hdr): 0
[11:10:44.488] <TB3> INFO: TBM errors: 0
[11:10:44.488] <TB3> INFO: flawed TBM headers: 0
[11:10:44.488] <TB3> INFO: flawed TBM trailers: 0
[11:10:44.488] <TB3> INFO: event ID mismatches: 0
[11:10:44.488] <TB3> INFO: ROC errors: 0
[11:10:44.488] <TB3> INFO: missing ROC header(s): 0
[11:10:44.488] <TB3> INFO: misplaced readback start: 0
[11:10:44.488] <TB3> INFO: Pixel decoding errors: 0
[11:10:44.488] <TB3> INFO: pixel data incomplete: 0
[11:10:44.488] <TB3> INFO: pixel address: 0
[11:10:44.488] <TB3> INFO: pulse height fill bit: 0
[11:10:44.488] <TB3> INFO: buffer corruption: 0
[11:10:44.673] <TB3> INFO: ######################################################################
[11:10:44.673] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[11:10:44.673] <TB3> INFO: ######################################################################
[11:10:44.683] <TB3> INFO: scanning low vcal = 10
[11:10:44.953] <TB3> INFO: Expecting 41600 events.
[11:10:48.479] <TB3> INFO: 41600 events read in total (2970ms).
[11:10:48.479] <TB3> INFO: Test took 3796ms.
[11:10:48.480] <TB3> INFO: scanning low vcal = 20
[11:10:48.784] <TB3> INFO: Expecting 41600 events.
[11:10:52.332] <TB3> INFO: 41600 events read in total (2992ms).
[11:10:52.332] <TB3> INFO: Test took 3851ms.
[11:10:52.333] <TB3> INFO: scanning low vcal = 30
[11:10:52.637] <TB3> INFO: Expecting 41600 events.
[11:10:56.172] <TB3> INFO: 41600 events read in total (2979ms).
[11:10:56.173] <TB3> INFO: Test took 3839ms.
[11:10:56.174] <TB3> INFO: scanning low vcal = 40
[11:10:56.473] <TB3> INFO: Expecting 41600 events.
[11:11:00.587] <TB3> INFO: 41600 events read in total (3557ms).
[11:11:00.588] <TB3> INFO: Test took 4414ms.
[11:11:00.591] <TB3> INFO: scanning low vcal = 50
[11:11:00.862] <TB3> INFO: Expecting 41600 events.
[11:11:05.037] <TB3> INFO: 41600 events read in total (3619ms).
[11:11:05.037] <TB3> INFO: Test took 4446ms.
[11:11:05.040] <TB3> INFO: scanning low vcal = 60
[11:11:05.305] <TB3> INFO: Expecting 41600 events.
[11:11:09.514] <TB3> INFO: 41600 events read in total (3653ms).
[11:11:09.515] <TB3> INFO: Test took 4475ms.
[11:11:09.517] <TB3> INFO: scanning low vcal = 70
[11:11:09.803] <TB3> INFO: Expecting 41600 events.
[11:11:13.971] <TB3> INFO: 41600 events read in total (3612ms).
[11:11:13.971] <TB3> INFO: Test took 4454ms.
[11:11:13.974] <TB3> INFO: scanning low vcal = 80
[11:11:14.236] <TB3> INFO: Expecting 41600 events.
[11:11:18.416] <TB3> INFO: 41600 events read in total (3624ms).
[11:11:18.417] <TB3> INFO: Test took 4443ms.
[11:11:18.419] <TB3> INFO: scanning low vcal = 90
[11:11:18.688] <TB3> INFO: Expecting 41600 events.
[11:11:22.874] <TB3> INFO: 41600 events read in total (3630ms).
[11:11:22.874] <TB3> INFO: Test took 4455ms.
[11:11:22.877] <TB3> INFO: scanning low vcal = 100
[11:11:23.135] <TB3> INFO: Expecting 41600 events.
[11:11:27.334] <TB3> INFO: 41600 events read in total (3637ms).
[11:11:27.334] <TB3> INFO: Test took 4457ms.
[11:11:27.337] <TB3> INFO: scanning low vcal = 110
[11:11:27.610] <TB3> INFO: Expecting 41600 events.
[11:11:31.804] <TB3> INFO: 41600 events read in total (3638ms).
[11:11:31.805] <TB3> INFO: Test took 4468ms.
[11:11:31.807] <TB3> INFO: scanning low vcal = 120
[11:11:32.089] <TB3> INFO: Expecting 41600 events.
[11:11:36.272] <TB3> INFO: 41600 events read in total (3627ms).
[11:11:36.273] <TB3> INFO: Test took 4466ms.
[11:11:36.276] <TB3> INFO: scanning low vcal = 130
[11:11:36.540] <TB3> INFO: Expecting 41600 events.
[11:11:40.740] <TB3> INFO: 41600 events read in total (3644ms).
[11:11:40.741] <TB3> INFO: Test took 4465ms.
[11:11:40.743] <TB3> INFO: scanning low vcal = 140
[11:11:41.007] <TB3> INFO: Expecting 41600 events.
[11:11:45.229] <TB3> INFO: 41600 events read in total (3666ms).
[11:11:45.229] <TB3> INFO: Test took 4486ms.
[11:11:45.233] <TB3> INFO: scanning low vcal = 150
[11:11:45.505] <TB3> INFO: Expecting 41600 events.
[11:11:49.712] <TB3> INFO: 41600 events read in total (3651ms).
[11:11:49.713] <TB3> INFO: Test took 4480ms.
[11:11:49.716] <TB3> INFO: scanning low vcal = 160
[11:11:49.995] <TB3> INFO: Expecting 41600 events.
[11:11:54.163] <TB3> INFO: 41600 events read in total (3612ms).
[11:11:54.164] <TB3> INFO: Test took 4448ms.
[11:11:54.166] <TB3> INFO: scanning low vcal = 170
[11:11:54.433] <TB3> INFO: Expecting 41600 events.
[11:11:58.613] <TB3> INFO: 41600 events read in total (3623ms).
[11:11:58.614] <TB3> INFO: Test took 4448ms.
[11:11:58.618] <TB3> INFO: scanning low vcal = 180
[11:11:58.898] <TB3> INFO: Expecting 41600 events.
[11:12:03.094] <TB3> INFO: 41600 events read in total (3640ms).
[11:12:03.095] <TB3> INFO: Test took 4477ms.
[11:12:03.098] <TB3> INFO: scanning low vcal = 190
[11:12:03.384] <TB3> INFO: Expecting 41600 events.
[11:12:07.629] <TB3> INFO: 41600 events read in total (3689ms).
[11:12:07.630] <TB3> INFO: Test took 4532ms.
[11:12:07.633] <TB3> INFO: scanning low vcal = 200
[11:12:07.896] <TB3> INFO: Expecting 41600 events.
[11:12:12.106] <TB3> INFO: 41600 events read in total (3654ms).
[11:12:12.106] <TB3> INFO: Test took 4473ms.
[11:12:12.109] <TB3> INFO: scanning low vcal = 210
[11:12:12.372] <TB3> INFO: Expecting 41600 events.
[11:12:16.569] <TB3> INFO: 41600 events read in total (3641ms).
[11:12:16.570] <TB3> INFO: Test took 4461ms.
[11:12:16.572] <TB3> INFO: scanning low vcal = 220
[11:12:16.846] <TB3> INFO: Expecting 41600 events.
[11:12:21.034] <TB3> INFO: 41600 events read in total (3632ms).
[11:12:21.035] <TB3> INFO: Test took 4463ms.
[11:12:21.038] <TB3> INFO: scanning low vcal = 230
[11:12:21.310] <TB3> INFO: Expecting 41600 events.
[11:12:25.476] <TB3> INFO: 41600 events read in total (3611ms).
[11:12:25.477] <TB3> INFO: Test took 4439ms.
[11:12:25.480] <TB3> INFO: scanning low vcal = 240
[11:12:25.750] <TB3> INFO: Expecting 41600 events.
[11:12:29.966] <TB3> INFO: 41600 events read in total (3660ms).
[11:12:29.966] <TB3> INFO: Test took 4486ms.
[11:12:29.969] <TB3> INFO: scanning low vcal = 250
[11:12:30.235] <TB3> INFO: Expecting 41600 events.
[11:12:34.423] <TB3> INFO: 41600 events read in total (3632ms).
[11:12:34.423] <TB3> INFO: Test took 4454ms.
[11:12:34.427] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[11:12:34.689] <TB3> INFO: Expecting 41600 events.
[11:12:38.916] <TB3> INFO: 41600 events read in total (3670ms).
[11:12:38.917] <TB3> INFO: Test took 4490ms.
[11:12:38.919] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[11:12:39.179] <TB3> INFO: Expecting 41600 events.
[11:12:43.445] <TB3> INFO: 41600 events read in total (3710ms).
[11:12:43.445] <TB3> INFO: Test took 4526ms.
[11:12:43.448] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[11:12:43.734] <TB3> INFO: Expecting 41600 events.
[11:12:47.953] <TB3> INFO: 41600 events read in total (3662ms).
[11:12:47.954] <TB3> INFO: Test took 4506ms.
[11:12:47.956] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[11:12:48.221] <TB3> INFO: Expecting 41600 events.
[11:12:52.426] <TB3> INFO: 41600 events read in total (3649ms).
[11:12:52.427] <TB3> INFO: Test took 4471ms.
[11:12:52.429] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[11:12:52.691] <TB3> INFO: Expecting 41600 events.
[11:12:56.895] <TB3> INFO: 41600 events read in total (3648ms).
[11:12:56.896] <TB3> INFO: Test took 4467ms.
[11:12:57.363] <TB3> INFO: PixTestGainPedestal::measure() done
[11:13:29.338] <TB3> INFO: PixTestGainPedestal::fit() done
[11:13:29.338] <TB3> INFO: non-linearity mean: 0.954 0.957 0.953 0.950 0.958 0.953 0.956 0.955 0.957 0.952 0.954 0.957 0.954 0.949 0.953 0.952
[11:13:29.338] <TB3> INFO: non-linearity RMS: 0.006 0.005 0.006 0.006 0.007 0.007 0.006 0.005 0.007 0.005 0.006 0.006 0.006 0.005 0.006 0.006
[11:13:29.338] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[11:13:29.358] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[11:13:29.379] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[11:13:29.402] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[11:13:29.422] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[11:13:29.442] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[11:13:29.467] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[11:13:29.487] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[11:13:29.507] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[11:13:29.529] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[11:13:29.549] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[11:13:29.569] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[11:13:29.595] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[11:13:29.615] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[11:13:29.635] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[11:13:29.655] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[11:13:29.675] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 165 seconds
[11:13:29.675] <TB3> INFO: Decoding statistics:
[11:13:29.675] <TB3> INFO: General information:
[11:13:29.675] <TB3> INFO: 16bit words read: 2662400
[11:13:29.675] <TB3> INFO: valid events total: 166400
[11:13:29.675] <TB3> INFO: empty events: 0
[11:13:29.675] <TB3> INFO: valid events with pixels: 166400
[11:13:29.675] <TB3> INFO: valid pixel hits: 665560
[11:13:29.675] <TB3> INFO: Event errors: 0
[11:13:29.675] <TB3> INFO: start marker: 0
[11:13:29.675] <TB3> INFO: stop marker: 0
[11:13:29.675] <TB3> INFO: overflow: 0
[11:13:29.675] <TB3> INFO: invalid 5bit words: 0
[11:13:29.675] <TB3> INFO: invalid XOR eye diagram: 0
[11:13:29.675] <TB3> INFO: frame (failed synchr.): 0
[11:13:29.675] <TB3> INFO: idle data (no TBM trl): 0
[11:13:29.675] <TB3> INFO: no data (only TBM hdr): 0
[11:13:29.675] <TB3> INFO: TBM errors: 0
[11:13:29.675] <TB3> INFO: flawed TBM headers: 0
[11:13:29.675] <TB3> INFO: flawed TBM trailers: 0
[11:13:29.675] <TB3> INFO: event ID mismatches: 0
[11:13:29.675] <TB3> INFO: ROC errors: 0
[11:13:29.675] <TB3> INFO: missing ROC header(s): 0
[11:13:29.675] <TB3> INFO: misplaced readback start: 0
[11:13:29.675] <TB3> INFO: Pixel decoding errors: 0
[11:13:29.675] <TB3> INFO: pixel data incomplete: 0
[11:13:29.675] <TB3> INFO: pixel address: 0
[11:13:29.675] <TB3> INFO: pulse height fill bit: 0
[11:13:29.675] <TB3> INFO: buffer corruption: 0
[11:13:29.683] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C15.dat
[11:13:29.684] <TB3> INFO: ######################################################################
[11:13:29.684] <TB3> INFO: PixTestReadback::doTest()
[11:13:29.684] <TB3> INFO: ######################################################################
[11:13:29.684] <TB3> INFO: ----------------------------------------------------------------------
[11:13:29.684] <TB3> INFO: PixTestReadback::CalibrateVd()
[11:13:29.684] <TB3> INFO: ----------------------------------------------------------------------
[11:13:39.163] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C0.dat
[11:13:39.163] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C1.dat
[11:13:39.163] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C2.dat
[11:13:39.163] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C3.dat
[11:13:39.163] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C4.dat
[11:13:39.163] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C5.dat
[11:13:39.163] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C6.dat
[11:13:39.163] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C7.dat
[11:13:39.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C8.dat
[11:13:39.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C9.dat
[11:13:39.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C10.dat
[11:13:39.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C11.dat
[11:13:39.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C12.dat
[11:13:39.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C13.dat
[11:13:39.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C14.dat
[11:13:39.164] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C15.dat
[11:13:39.193] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:13:39.193] <TB3> INFO: ----------------------------------------------------------------------
[11:13:39.193] <TB3> INFO: PixTestReadback::CalibrateVa()
[11:13:39.193] <TB3> INFO: ----------------------------------------------------------------------
[11:13:48.689] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C0.dat
[11:13:48.689] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C1.dat
[11:13:48.689] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C2.dat
[11:13:48.689] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C3.dat
[11:13:48.689] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C4.dat
[11:13:48.689] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C5.dat
[11:13:48.689] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C6.dat
[11:13:48.689] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C7.dat
[11:13:48.689] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C8.dat
[11:13:48.689] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C9.dat
[11:13:48.690] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C10.dat
[11:13:48.690] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C11.dat
[11:13:48.690] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C12.dat
[11:13:48.690] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C13.dat
[11:13:48.690] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C14.dat
[11:13:48.690] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C15.dat
[11:13:48.713] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:13:48.713] <TB3> INFO: ----------------------------------------------------------------------
[11:13:48.713] <TB3> INFO: PixTestReadback::readbackVbg()
[11:13:48.713] <TB3> INFO: ----------------------------------------------------------------------
[11:13:56.021] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:13:56.021] <TB3> INFO: ----------------------------------------------------------------------
[11:13:56.021] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[11:13:56.021] <TB3> INFO: ----------------------------------------------------------------------
[11:13:56.021] <TB3> INFO: Vbg will be calibrated using Vd calibration
[11:13:56.021] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 154.9calibrated Vbg = 1.19931 :::*/*/*/*/
[11:13:56.021] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 161.1calibrated Vbg = 1.19386 :::*/*/*/*/
[11:13:56.021] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 147.9calibrated Vbg = 1.20286 :::*/*/*/*/
[11:13:56.021] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 153.7calibrated Vbg = 1.21013 :::*/*/*/*/
[11:13:56.021] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 156.5calibrated Vbg = 1.215 :::*/*/*/*/
[11:13:56.021] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 147.3calibrated Vbg = 1.21234 :::*/*/*/*/
[11:13:56.021] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 145.3calibrated Vbg = 1.21696 :::*/*/*/*/
[11:13:56.021] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 163.4calibrated Vbg = 1.21546 :::*/*/*/*/
[11:13:56.021] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 153.6calibrated Vbg = 1.21087 :::*/*/*/*/
[11:13:56.021] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 146calibrated Vbg = 1.21502 :::*/*/*/*/
[11:13:56.021] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 153.4calibrated Vbg = 1.21421 :::*/*/*/*/
[11:13:56.021] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 152.6calibrated Vbg = 1.20697 :::*/*/*/*/
[11:13:56.021] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 150.4calibrated Vbg = 1.20137 :::*/*/*/*/
[11:13:56.021] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 146.9calibrated Vbg = 1.20043 :::*/*/*/*/
[11:13:56.021] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 145calibrated Vbg = 1.20217 :::*/*/*/*/
[11:13:56.021] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 143.2calibrated Vbg = 1.19228 :::*/*/*/*/
[11:13:56.023] <TB3> INFO: ----------------------------------------------------------------------
[11:13:56.023] <TB3> INFO: PixTestReadback::CalibrateIa()
[11:13:56.023] <TB3> INFO: ----------------------------------------------------------------------
[11:16:31.174] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C0.dat
[11:16:31.174] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C1.dat
[11:16:31.174] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C2.dat
[11:16:31.174] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C3.dat
[11:16:31.174] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C4.dat
[11:16:31.174] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C5.dat
[11:16:31.174] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C6.dat
[11:16:31.174] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C7.dat
[11:16:31.174] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C8.dat
[11:16:31.174] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C9.dat
[11:16:31.174] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C10.dat
[11:16:31.175] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C11.dat
[11:16:31.175] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C12.dat
[11:16:31.175] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C13.dat
[11:16:31.175] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C14.dat
[11:16:31.175] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2294_FullQualification_2016-08-19_07h55m_1471586112//002_FulltestPxar_m20//readbackCal_C15.dat
[11:16:31.197] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[11:16:31.198] <TB3> INFO: PixTestReadback::doTest() done
[11:16:31.198] <TB3> INFO: Decoding statistics:
[11:16:31.198] <TB3> INFO: General information:
[11:16:31.198] <TB3> INFO: 16bit words read: 1024
[11:16:31.198] <TB3> INFO: valid events total: 128
[11:16:31.198] <TB3> INFO: empty events: 128
[11:16:31.198] <TB3> INFO: valid events with pixels: 0
[11:16:31.198] <TB3> INFO: valid pixel hits: 0
[11:16:31.198] <TB3> INFO: Event errors: 0
[11:16:31.198] <TB3> INFO: start marker: 0
[11:16:31.198] <TB3> INFO: stop marker: 0
[11:16:31.198] <TB3> INFO: overflow: 0
[11:16:31.198] <TB3> INFO: invalid 5bit words: 0
[11:16:31.198] <TB3> INFO: invalid XOR eye diagram: 0
[11:16:31.198] <TB3> INFO: frame (failed synchr.): 0
[11:16:31.198] <TB3> INFO: idle data (no TBM trl): 0
[11:16:31.198] <TB3> INFO: no data (only TBM hdr): 0
[11:16:31.198] <TB3> INFO: TBM errors: 0
[11:16:31.198] <TB3> INFO: flawed TBM headers: 0
[11:16:31.198] <TB3> INFO: flawed TBM trailers: 0
[11:16:31.198] <TB3> INFO: event ID mismatches: 0
[11:16:31.198] <TB3> INFO: ROC errors: 0
[11:16:31.198] <TB3> INFO: missing ROC header(s): 0
[11:16:31.198] <TB3> INFO: misplaced readback start: 0
[11:16:31.198] <TB3> INFO: Pixel decoding errors: 0
[11:16:31.198] <TB3> INFO: pixel data incomplete: 0
[11:16:31.198] <TB3> INFO: pixel address: 0
[11:16:31.198] <TB3> INFO: pulse height fill bit: 0
[11:16:31.198] <TB3> INFO: buffer corruption: 0
[11:16:31.218] <TB3> INFO: Decoding statistics:
[11:16:31.218] <TB3> INFO: General information:
[11:16:31.218] <TB3> INFO: 16bit words read: 2755356
[11:16:31.218] <TB3> INFO: valid events total: 176768
[11:16:31.218] <TB3> INFO: empty events: 7865
[11:16:31.218] <TB3> INFO: valid events with pixels: 168903
[11:16:31.218] <TB3> INFO: valid pixel hits: 668063
[11:16:31.218] <TB3> INFO: Event errors: 0
[11:16:31.218] <TB3> INFO: start marker: 0
[11:16:31.218] <TB3> INFO: stop marker: 0
[11:16:31.218] <TB3> INFO: overflow: 0
[11:16:31.218] <TB3> INFO: invalid 5bit words: 0
[11:16:31.218] <TB3> INFO: invalid XOR eye diagram: 0
[11:16:31.218] <TB3> INFO: frame (failed synchr.): 0
[11:16:31.218] <TB3> INFO: idle data (no TBM trl): 0
[11:16:31.218] <TB3> INFO: no data (only TBM hdr): 0
[11:16:31.218] <TB3> INFO: TBM errors: 0
[11:16:31.218] <TB3> INFO: flawed TBM headers: 0
[11:16:31.218] <TB3> INFO: flawed TBM trailers: 0
[11:16:31.218] <TB3> INFO: event ID mismatches: 0
[11:16:31.218] <TB3> INFO: ROC errors: 0
[11:16:31.218] <TB3> INFO: missing ROC header(s): 0
[11:16:31.218] <TB3> INFO: misplaced readback start: 0
[11:16:31.218] <TB3> INFO: Pixel decoding errors: 0
[11:16:31.218] <TB3> INFO: pixel data incomplete: 0
[11:16:31.218] <TB3> INFO: pixel address: 0
[11:16:31.218] <TB3> INFO: pulse height fill bit: 0
[11:16:31.218] <TB3> INFO: buffer corruption: 0
[11:16:31.218] <TB3> INFO: enter test to run
[11:16:31.218] <TB3> INFO: test: exit no parameter change
[11:16:31.369] <TB3> QUIET: Connection to board 170 closed.
[11:16:31.448] <TB3> INFO: pXar: this is the end, my friend