[17:36:38.232] <TB1> INFO: *** Welcome to pxar ***
[17:36:38.232] <TB1> INFO: *** Today: 2016/09/20
[17:36:38.777] <TB1> INFO: *** Version: fe94
[17:36:38.777] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters_C15.dat
[17:36:38.778] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//tbmParameters_C0b.dat
[17:36:38.778] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//defaultMaskFile.dat
[17:36:38.778] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//trimParameters_C15.dat
[17:36:38.847] <TB1> INFO: clk: 4
[17:36:38.847] <TB1> INFO: ctr: 4
[17:36:38.847] <TB1> INFO: sda: 19
[17:36:38.847] <TB1> INFO: tin: 9
[17:36:38.847] <TB1> INFO: level: 15
[17:36:38.847] <TB1> INFO: triggerdelay: 0
[17:36:38.847] <TB1> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[17:36:38.847] <TB1> INFO: Log level: INFO
[17:36:38.854] <TB1> INFO: Found DTB DTB_WXBYFL
[17:36:38.862] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[17:36:38.864] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[17:36:38.867] <TB1> INFO: RPC call hashes of host and DTB match: 486171790
[17:36:40.372] <TB1> INFO: DUT info:
[17:36:40.372] <TB1> INFO: The DUT currently contains the following objects:
[17:36:40.372] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[17:36:40.372] <TB1> INFO: TBM Core alpha (0): 7 registers set
[17:36:40.372] <TB1> INFO: TBM Core beta (1): 7 registers set
[17:36:40.372] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:36:40.372] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:36:40.372] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:36:40.372] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:36:40.372] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:36:40.372] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:36:40.372] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:36:40.372] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:36:40.372] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:36:40.372] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:36:40.372] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:36:40.372] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:36:40.372] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:36:40.372] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:36:40.372] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:36:40.372] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:36:40.372] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:36:40.773] <TB1> INFO: enter 'restricted' command line mode
[17:36:40.773] <TB1> INFO: enter test to run
[17:36:40.773] <TB1> INFO: test: pretest no parameter change
[17:36:40.773] <TB1> INFO: running: pretest
[17:36:40.778] <TB1> INFO: ----------------------------------------------------------------------
[17:36:40.778] <TB1> INFO: PixTestPretest::programROC()
[17:36:40.778] <TB1> INFO: ----------------------------------------------------------------------
[17:36:58.793] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[17:36:58.793] <TB1> INFO: IA differences per ROC: 18.5 17.7 16.9 15.3 20.1 18.5 18.5 16.9 18.5 20.1 18.5 17.7 20.1 20.9 19.3 18.5
[17:36:58.883] <TB1> INFO: enter test to run
[17:36:58.883] <TB1> INFO: test: pretest no parameter change
[17:36:58.883] <TB1> INFO: running: pretest
[17:36:58.885] <TB1> INFO: ----------------------------------------------------------------------
[17:36:58.885] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[17:36:58.885] <TB1> INFO: ----------------------------------------------------------------------
[17:37:20.146] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 384.3 mA = 24.0187 mA/ROC
[17:37:20.146] <TB1> INFO: i(loss) [mA/ROC]: 19.3 20.1 19.3 19.3 19.3 19.3 18.5 18.5 18.5 18.5 19.3 19.3 18.5 19.3 17.7 19.3
[17:37:20.173] <TB1> INFO: enter test to run
[17:37:20.175] <TB1> INFO: test: pretest no parameter change
[17:37:20.175] <TB1> INFO: running: pretest
[17:37:20.175] <TB1> INFO: ----------------------------------------------------------------------
[17:37:20.175] <TB1> INFO: PixTestPretest::findTiming()
[17:37:20.175] <TB1> INFO: ----------------------------------------------------------------------
[17:37:20.175] <TB1> INFO: PixTestCmd::init()

[17:38:49.529] <TB1> INFO: TBM phases: 160MHz: 0, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[17:38:49.529] <TB1> INFO: (success/tries = 100/100), width = 5
[17:38:49.530] <TB1> INFO: enter test to run
[17:38:49.530] <TB1> INFO: test: pretest no parameter change
[17:38:49.530] <TB1> INFO: running: pretest
[17:38:49.531] <TB1> INFO: ----------------------------------------------------------------------
[17:38:49.531] <TB1> INFO: PixTestPretest::findWorkingPixel()
[17:38:49.531] <TB1> INFO: ----------------------------------------------------------------------
[17:38:49.625] <TB1> INFO: Expecting 231680 events.


[17:38:58.801] <TB1> INFO: 231680 events read in total (8619ms).
[17:38:58.807] <TB1> INFO: Test took 9270ms.
[17:38:59.010] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[17:38:59.081] <TB1> INFO: enter test to run
[17:38:59.081] <TB1> INFO: test: pretest no parameter change
[17:38:59.081] <TB1> INFO: running: pretest
[17:38:59.082] <TB1> INFO: ----------------------------------------------------------------------
[17:38:59.082] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[17:38:59.082] <TB1> INFO: ----------------------------------------------------------------------
[17:38:59.174] <TB1> INFO: Expecting 231680 events.
[17:39:08.360] <TB1> INFO: 231680 events read in total (8630ms).
[17:39:08.366] <TB1> INFO: Test took 9280ms.
[17:39:08.582] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[17:39:08.582] <TB1> INFO: CalDel: 115 136 122 117 129 127 158 137 135 142 146 124 143 144 153 139
[17:39:08.582] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[17:39:08.615] <TB1> INFO: enter test to run
[17:39:08.615] <TB1> INFO: test: pretest no parameter change
[17:39:08.615] <TB1> INFO: running: pretest
[17:39:08.615] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters_C0.dat
[17:39:08.616] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters_C1.dat
[17:39:08.616] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters_C2.dat
[17:39:08.616] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters_C3.dat
[17:39:08.616] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters_C4.dat
[17:39:08.616] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters_C5.dat
[17:39:08.616] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters_C6.dat
[17:39:08.616] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters_C7.dat
[17:39:08.616] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters_C8.dat
[17:39:08.617] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters_C9.dat
[17:39:08.617] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters_C10.dat
[17:39:08.617] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters_C11.dat
[17:39:08.617] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters_C12.dat
[17:39:08.617] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters_C13.dat
[17:39:08.617] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters_C14.dat
[17:39:08.617] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters_C15.dat
[17:39:08.617] <TB1> INFO: enter test to run
[17:39:08.617] <TB1> INFO: test: fulltest no parameter change
[17:39:08.617] <TB1> INFO: running: fulltest
[17:39:08.617] <TB1> INFO: ######################################################################
[17:39:08.617] <TB1> INFO: PixTestFullTest::doTest()
[17:39:08.617] <TB1> INFO: ######################################################################
[17:39:08.619] <TB1> INFO: ######################################################################
[17:39:08.619] <TB1> INFO: PixTestAlive::doTest()
[17:39:08.619] <TB1> INFO: ######################################################################
[17:39:08.620] <TB1> INFO: ----------------------------------------------------------------------
[17:39:08.620] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:39:08.620] <TB1> INFO: ----------------------------------------------------------------------
[17:39:08.883] <TB1> INFO: Expecting 41600 events.
[17:39:12.449] <TB1> INFO: 41600 events read in total (3009ms).
[17:39:12.450] <TB1> INFO: Test took 3829ms.
[17:39:12.669] <TB1> INFO: PixTestAlive::aliveTest() done
[17:39:12.669] <TB1> INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:39:12.670] <TB1> INFO: ----------------------------------------------------------------------
[17:39:12.670] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:39:12.670] <TB1> INFO: ----------------------------------------------------------------------
[17:39:12.953] <TB1> INFO: Expecting 41600 events.
[17:39:15.728] <TB1> INFO: 41600 events read in total (2219ms).
[17:39:15.728] <TB1> INFO: Test took 3056ms.
[17:39:15.728] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:39:15.964] <TB1> INFO: PixTestAlive::maskTest() done
[17:39:15.964] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:39:15.966] <TB1> INFO: ----------------------------------------------------------------------
[17:39:15.966] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:39:15.966] <TB1> INFO: ----------------------------------------------------------------------
[17:39:16.246] <TB1> INFO: Expecting 41600 events.
[17:39:19.675] <TB1> INFO: 41600 events read in total (2873ms).
[17:39:19.675] <TB1> INFO: Test took 3708ms.
[17:39:19.901] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[17:39:19.901] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:39:19.901] <TB1> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[17:39:19.901] <TB1> INFO: Decoding statistics:
[17:39:19.901] <TB1> INFO: General information:
[17:39:19.901] <TB1> INFO: 16bit words read: 0
[17:39:19.901] <TB1> INFO: valid events total: 0
[17:39:19.901] <TB1> INFO: empty events: 0
[17:39:19.901] <TB1> INFO: valid events with pixels: 0
[17:39:19.901] <TB1> INFO: valid pixel hits: 0
[17:39:19.901] <TB1> INFO: Event errors: 0
[17:39:19.901] <TB1> INFO: start marker: 0
[17:39:19.901] <TB1> INFO: stop marker: 0
[17:39:19.901] <TB1> INFO: overflow: 0
[17:39:19.901] <TB1> INFO: invalid 5bit words: 0
[17:39:19.901] <TB1> INFO: invalid XOR eye diagram: 0
[17:39:19.901] <TB1> INFO: frame (failed synchr.): 0
[17:39:19.901] <TB1> INFO: idle data (no TBM trl): 0
[17:39:19.901] <TB1> INFO: no data (only TBM hdr): 0
[17:39:19.901] <TB1> INFO: TBM errors: 0
[17:39:19.901] <TB1> INFO: flawed TBM headers: 0
[17:39:19.901] <TB1> INFO: flawed TBM trailers: 0
[17:39:19.901] <TB1> INFO: event ID mismatches: 0
[17:39:19.901] <TB1> INFO: ROC errors: 0
[17:39:19.901] <TB1> INFO: missing ROC header(s): 0
[17:39:19.901] <TB1> INFO: misplaced readback start: 0
[17:39:19.901] <TB1> INFO: Pixel decoding errors: 0
[17:39:19.901] <TB1> INFO: pixel data incomplete: 0
[17:39:19.901] <TB1> INFO: pixel address: 0
[17:39:19.901] <TB1> INFO: pulse height fill bit: 0
[17:39:19.901] <TB1> INFO: buffer corruption: 0
[17:39:19.909] <TB1> INFO: ######################################################################
[17:39:19.909] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[17:39:19.909] <TB1> INFO: ######################################################################
[17:39:19.911] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[17:39:19.922] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[17:39:19.922] <TB1> INFO: run 1 of 1
[17:39:20.219] <TB1> INFO: Expecting 3120000 events.
[17:39:57.369] <TB1> INFO: 863190 events read in total (36593ms).
[17:40:32.892] <TB1> INFO: 1706610 events read in total (72117ms).
[17:41:09.982] <TB1> INFO: 2558795 events read in total (109206ms).
[17:41:33.733] <TB1> INFO: 3120000 events read in total (132957ms).
[17:41:33.804] <TB1> INFO: Test took 133882ms.
[17:41:58.784] <TB1> INFO: PixTestBBMap::doTest() done, duration: 158 seconds
[17:41:58.784] <TB1> INFO: number of dead bumps (per ROC): 3 0 0 0 0 0 0 1 0 2 0 3 0 0 0 9
[17:41:58.784] <TB1> INFO: separation cut (per ROC): 95 90 113 83 88 84 86 79 80 85 87 86 92 98 88 88
[17:41:58.784] <TB1> INFO: Decoding statistics:
[17:41:58.784] <TB1> INFO: General information:
[17:41:58.784] <TB1> INFO: 16bit words read: 0
[17:41:58.784] <TB1> INFO: valid events total: 0
[17:41:58.784] <TB1> INFO: empty events: 0
[17:41:58.784] <TB1> INFO: valid events with pixels: 0
[17:41:58.784] <TB1> INFO: valid pixel hits: 0
[17:41:58.784] <TB1> INFO: Event errors: 0
[17:41:58.784] <TB1> INFO: start marker: 0
[17:41:58.784] <TB1> INFO: stop marker: 0
[17:41:58.784] <TB1> INFO: overflow: 0
[17:41:58.784] <TB1> INFO: invalid 5bit words: 0
[17:41:58.784] <TB1> INFO: invalid XOR eye diagram: 0
[17:41:58.784] <TB1> INFO: frame (failed synchr.): 0
[17:41:58.784] <TB1> INFO: idle data (no TBM trl): 0
[17:41:58.784] <TB1> INFO: no data (only TBM hdr): 0
[17:41:58.784] <TB1> INFO: TBM errors: 0
[17:41:58.784] <TB1> INFO: flawed TBM headers: 0
[17:41:58.784] <TB1> INFO: flawed TBM trailers: 0
[17:41:58.784] <TB1> INFO: event ID mismatches: 0
[17:41:58.784] <TB1> INFO: ROC errors: 0
[17:41:58.784] <TB1> INFO: missing ROC header(s): 0
[17:41:58.784] <TB1> INFO: misplaced readback start: 0
[17:41:58.784] <TB1> INFO: Pixel decoding errors: 0
[17:41:58.784] <TB1> INFO: pixel data incomplete: 0
[17:41:58.784] <TB1> INFO: pixel address: 0
[17:41:58.784] <TB1> INFO: pulse height fill bit: 0
[17:41:58.784] <TB1> INFO: buffer corruption: 0
[17:41:58.854] <TB1> INFO: ######################################################################
[17:41:58.854] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[17:41:58.854] <TB1> INFO: ######################################################################
[17:41:58.854] <TB1> INFO: ----------------------------------------------------------------------
[17:41:58.854] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[17:41:58.854] <TB1> INFO: ----------------------------------------------------------------------
[17:41:58.854] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[17:41:58.862] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[17:41:58.862] <TB1> INFO: run 1 of 1
[17:41:59.123] <TB1> INFO: Expecting 36608000 events.
[17:42:27.627] <TB1> INFO: 836000 events read in total (27948ms).
[17:42:55.431] <TB1> INFO: 1659500 events read in total (55752ms).
[17:43:23.367] <TB1> INFO: 2484750 events read in total (83688ms).
[17:43:51.078] <TB1> INFO: 3307450 events read in total (111399ms).
[17:44:19.124] <TB1> INFO: 4133100 events read in total (139445ms).
[17:44:47.254] <TB1> INFO: 4958250 events read in total (167575ms).
[17:45:15.001] <TB1> INFO: 5779250 events read in total (195323ms).
[17:45:42.892] <TB1> INFO: 6602550 events read in total (223213ms).
[17:46:11.142] <TB1> INFO: 7424400 events read in total (251463ms).
[17:46:39.113] <TB1> INFO: 8245800 events read in total (279434ms).
[17:47:07.163] <TB1> INFO: 9069350 events read in total (307484ms).
[17:47:35.003] <TB1> INFO: 9889300 events read in total (335324ms).
[17:48:02.943] <TB1> INFO: 10709350 events read in total (363264ms).
[17:48:30.865] <TB1> INFO: 11529900 events read in total (391186ms).
[17:48:58.774] <TB1> INFO: 12350150 events read in total (419095ms).
[17:49:26.475] <TB1> INFO: 13171650 events read in total (446796ms).
[17:49:54.402] <TB1> INFO: 13991700 events read in total (474723ms).
[17:50:22.434] <TB1> INFO: 14813900 events read in total (502755ms).
[17:50:50.166] <TB1> INFO: 15630850 events read in total (530487ms).
[17:51:18.219] <TB1> INFO: 16451400 events read in total (558540ms).
[17:51:46.150] <TB1> INFO: 17270200 events read in total (586471ms).
[17:52:13.823] <TB1> INFO: 18088150 events read in total (614144ms).
[17:52:41.310] <TB1> INFO: 18900200 events read in total (641631ms).
[17:53:09.300] <TB1> INFO: 19715300 events read in total (669621ms).
[17:53:36.947] <TB1> INFO: 20527200 events read in total (697268ms).
[17:54:04.453] <TB1> INFO: 21338450 events read in total (724774ms).
[17:54:32.084] <TB1> INFO: 22148950 events read in total (752405ms).
[17:55:00.157] <TB1> INFO: 22958350 events read in total (780478ms).
[17:55:27.459] <TB1> INFO: 23768300 events read in total (807780ms).
[17:55:55.397] <TB1> INFO: 24579150 events read in total (835718ms).
[17:56:22.883] <TB1> INFO: 25387450 events read in total (863204ms).
[17:56:50.375] <TB1> INFO: 26199550 events read in total (890696ms).
[17:57:17.984] <TB1> INFO: 27006750 events read in total (918305ms).
[17:57:45.580] <TB1> INFO: 27815100 events read in total (945901ms).
[17:58:13.156] <TB1> INFO: 28624300 events read in total (973477ms).
[17:58:40.848] <TB1> INFO: 29433500 events read in total (1001169ms).
[17:59:08.300] <TB1> INFO: 30243950 events read in total (1028621ms).
[17:59:35.752] <TB1> INFO: 31051950 events read in total (1056073ms).
[18:00:03.973] <TB1> INFO: 31861100 events read in total (1084294ms).
[18:00:32.185] <TB1> INFO: 32670500 events read in total (1112506ms).
[18:00:59.986] <TB1> INFO: 33480850 events read in total (1140307ms).
[18:01:27.876] <TB1> INFO: 34290300 events read in total (1168197ms).
[18:01:55.495] <TB1> INFO: 35099750 events read in total (1195816ms).
[18:02:22.988] <TB1> INFO: 35911800 events read in total (1223309ms).
[18:02:46.592] <TB1> INFO: 36608000 events read in total (1246913ms).
[18:02:46.646] <TB1> INFO: Test took 1247783ms.
[18:02:47.025] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:02:48.810] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:02:50.752] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:02:52.592] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:02:54.499] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:02:56.399] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:02:58.245] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:02:59.936] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:03:01.613] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:03:03.276] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:03:04.974] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:03:06.692] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:03:08.412] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:03:10.136] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:03:11.848] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:03:13.578] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[18:03:15.334] <TB1> INFO: PixTestScurves::scurves() done
[18:03:15.334] <TB1> INFO: Vcal mean: 104.10 93.26 93.24 91.92 89.47 86.72 93.39 89.09 98.04 93.62 93.69 88.48 91.97 99.52 95.57 90.35
[18:03:15.334] <TB1> INFO: Vcal RMS: 5.60 4.77 4.94 4.55 5.00 4.87 4.61 4.91 5.27 5.32 5.55 5.30 4.87 5.51 4.89 4.59
[18:03:15.334] <TB1> INFO: PixTestScurves::fullTest() done, duration: 1276 seconds
[18:03:15.334] <TB1> INFO: Decoding statistics:
[18:03:15.334] <TB1> INFO: General information:
[18:03:15.334] <TB1> INFO: 16bit words read: 0
[18:03:15.334] <TB1> INFO: valid events total: 0
[18:03:15.334] <TB1> INFO: empty events: 0
[18:03:15.334] <TB1> INFO: valid events with pixels: 0
[18:03:15.334] <TB1> INFO: valid pixel hits: 0
[18:03:15.334] <TB1> INFO: Event errors: 0
[18:03:15.335] <TB1> INFO: start marker: 0
[18:03:15.335] <TB1> INFO: stop marker: 0
[18:03:15.335] <TB1> INFO: overflow: 0
[18:03:15.335] <TB1> INFO: invalid 5bit words: 0
[18:03:15.335] <TB1> INFO: invalid XOR eye diagram: 0
[18:03:15.335] <TB1> INFO: frame (failed synchr.): 0
[18:03:15.335] <TB1> INFO: idle data (no TBM trl): 0
[18:03:15.335] <TB1> INFO: no data (only TBM hdr): 0
[18:03:15.335] <TB1> INFO: TBM errors: 0
[18:03:15.335] <TB1> INFO: flawed TBM headers: 0
[18:03:15.335] <TB1> INFO: flawed TBM trailers: 0
[18:03:15.335] <TB1> INFO: event ID mismatches: 0
[18:03:15.335] <TB1> INFO: ROC errors: 0
[18:03:15.335] <TB1> INFO: missing ROC header(s): 0
[18:03:15.335] <TB1> INFO: misplaced readback start: 0
[18:03:15.335] <TB1> INFO: Pixel decoding errors: 0
[18:03:15.335] <TB1> INFO: pixel data incomplete: 0
[18:03:15.335] <TB1> INFO: pixel address: 0
[18:03:15.335] <TB1> INFO: pulse height fill bit: 0
[18:03:15.335] <TB1> INFO: buffer corruption: 0
[18:03:15.405] <TB1> INFO: ######################################################################
[18:03:15.405] <TB1> INFO: PixTestTrim::doTest()
[18:03:15.405] <TB1> INFO: ######################################################################
[18:03:15.406] <TB1> INFO: ----------------------------------------------------------------------
[18:03:15.406] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[18:03:15.406] <TB1> INFO: ----------------------------------------------------------------------
[18:03:15.485] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[18:03:15.485] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[18:03:15.493] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[18:03:15.493] <TB1> INFO: run 1 of 1
[18:03:15.769] <TB1> INFO: Expecting 6281600 events.
[18:03:52.228] <TB1> INFO: 1116960 events read in total (35901ms).
[18:04:27.087] <TB1> INFO: 2225980 events read in total (70760ms).
[18:05:02.620] <TB1> INFO: 3329680 events read in total (106293ms).
[18:05:38.395] <TB1> INFO: 4438990 events read in total (142069ms).
[18:06:14.204] <TB1> INFO: 5552180 events read in total (177878ms).
[18:06:38.724] <TB1> INFO: 6281600 events read in total (202397ms).
[18:06:38.779] <TB1> INFO: Test took 203287ms.
[18:06:58.841] <TB1> INFO: ROC 0 VthrComp = 106
[18:06:58.841] <TB1> INFO: ROC 1 VthrComp = 98
[18:06:58.841] <TB1> INFO: ROC 2 VthrComp = 100
[18:06:58.841] <TB1> INFO: ROC 3 VthrComp = 95
[18:06:58.841] <TB1> INFO: ROC 4 VthrComp = 93
[18:06:58.841] <TB1> INFO: ROC 5 VthrComp = 90
[18:06:58.841] <TB1> INFO: ROC 6 VthrComp = 94
[18:06:58.841] <TB1> INFO: ROC 7 VthrComp = 93
[18:06:58.841] <TB1> INFO: ROC 8 VthrComp = 94
[18:06:58.842] <TB1> INFO: ROC 9 VthrComp = 91
[18:06:58.843] <TB1> INFO: ROC 10 VthrComp = 93
[18:06:58.844] <TB1> INFO: ROC 11 VthrComp = 88
[18:06:58.844] <TB1> INFO: ROC 12 VthrComp = 95
[18:06:58.844] <TB1> INFO: ROC 13 VthrComp = 101
[18:06:58.844] <TB1> INFO: ROC 14 VthrComp = 97
[18:06:58.844] <TB1> INFO: ROC 15 VthrComp = 93
[18:06:58.844] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[18:06:58.844] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[18:06:58.853] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[18:06:58.853] <TB1> INFO: run 1 of 1
[18:06:59.122] <TB1> INFO: Expecting 6281600 events.
[18:07:33.477] <TB1> INFO: 758910 events read in total (33799ms).
[18:08:03.723] <TB1> INFO: 1515940 events read in total (64045ms).
[18:08:33.792] <TB1> INFO: 2273290 events read in total (94114ms).
[18:09:04.617] <TB1> INFO: 3031200 events read in total (124939ms).
[18:09:34.710] <TB1> INFO: 3783440 events read in total (155032ms).
[18:10:05.116] <TB1> INFO: 4532570 events read in total (185438ms).
[18:10:35.239] <TB1> INFO: 5280830 events read in total (215561ms).
[18:11:05.792] <TB1> INFO: 6028380 events read in total (246114ms).
[18:11:16.540] <TB1> INFO: 6281600 events read in total (256863ms).
[18:11:16.597] <TB1> INFO: Test took 257744ms.
[18:11:39.844] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 60.4589 for pixel 18/24 mean/min/max = 47.5107/34.468/60.5534
[18:11:39.844] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 56.1008 for pixel 11/27 mean/min/max = 43.8802/31.4907/56.2697
[18:11:39.844] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 57.1155 for pixel 18/0 mean/min/max = 44.7943/32.4447/57.1438
[18:11:39.844] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 55.2148 for pixel 12/0 mean/min/max = 44.2851/33.2371/55.3332
[18:11:39.845] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 57.3074 for pixel 10/70 mean/min/max = 45.4259/33.3696/57.4822
[18:11:39.845] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 57.0133 for pixel 9/2 mean/min/max = 45.6882/34.2739/57.1026
[18:11:39.845] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 56.4112 for pixel 20/0 mean/min/max = 45.1759/33.8475/56.5043
[18:11:39.845] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 57.4949 for pixel 13/9 mean/min/max = 45.4775/33.3935/57.5614
[18:11:39.846] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 60.6181 for pixel 0/1 mean/min/max = 46.4349/32.197/60.6729
[18:11:39.846] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 62.666 for pixel 5/11 mean/min/max = 48.0718/33.2721/62.8714
[18:11:39.846] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 61.336 for pixel 5/50 mean/min/max = 46.8886/32.303/61.4743
[18:11:39.846] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 59.2876 for pixel 0/21 mean/min/max = 46.5337/33.7782/59.2892
[18:11:39.846] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 57.0726 for pixel 23/79 mean/min/max = 44.9489/32.825/57.0728
[18:11:39.847] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 59.1211 for pixel 1/43 mean/min/max = 45.5765/31.9762/59.1769
[18:11:39.847] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 55.9366 for pixel 41/7 mean/min/max = 44.193/32.2651/56.1209
[18:11:39.847] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 56.3352 for pixel 5/0 mean/min/max = 44.9132/33.3918/56.4346
[18:11:39.847] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:11:39.937] <TB1> INFO: Expecting 514560 events.
[18:11:50.759] <TB1> INFO: 514560 events read in total (10264ms).
[18:11:50.766] <TB1> INFO: Expecting 514560 events.
[18:12:01.554] <TB1> INFO: 514560 events read in total (10386ms).
[18:12:01.565] <TB1> INFO: Expecting 514560 events.
[18:12:12.328] <TB1> INFO: 514560 events read in total (10361ms).
[18:12:12.340] <TB1> INFO: Expecting 514560 events.
[18:12:23.063] <TB1> INFO: 514560 events read in total (10321ms).
[18:12:23.082] <TB1> INFO: Expecting 514560 events.
[18:12:33.848] <TB1> INFO: 514560 events read in total (10364ms).
[18:12:33.864] <TB1> INFO: Expecting 514560 events.
[18:12:44.504] <TB1> INFO: 514560 events read in total (10238ms).
[18:12:44.524] <TB1> INFO: Expecting 514560 events.
[18:12:55.255] <TB1> INFO: 514560 events read in total (10329ms).
[18:12:55.279] <TB1> INFO: Expecting 514560 events.
[18:13:06.092] <TB1> INFO: 514560 events read in total (10411ms).
[18:13:06.120] <TB1> INFO: Expecting 514560 events.
[18:13:16.821] <TB1> INFO: 514560 events read in total (10299ms).
[18:13:16.848] <TB1> INFO: Expecting 514560 events.
[18:13:27.525] <TB1> INFO: 514560 events read in total (10275ms).
[18:13:27.557] <TB1> INFO: Expecting 514560 events.
[18:13:38.294] <TB1> INFO: 514560 events read in total (10335ms).
[18:13:38.327] <TB1> INFO: Expecting 514560 events.
[18:13:48.999] <TB1> INFO: 514560 events read in total (10271ms).
[18:13:49.040] <TB1> INFO: Expecting 514560 events.
[18:13:59.778] <TB1> INFO: 514560 events read in total (10336ms).
[18:13:59.827] <TB1> INFO: Expecting 514560 events.
[18:14:10.603] <TB1> INFO: 514560 events read in total (10374ms).
[18:14:10.646] <TB1> INFO: Expecting 514560 events.
[18:14:21.337] <TB1> INFO: 514560 events read in total (10290ms).
[18:14:21.388] <TB1> INFO: Expecting 514560 events.
[18:14:32.230] <TB1> INFO: 514560 events read in total (10440ms).
[18:14:32.291] <TB1> INFO: Test took 172444ms.
[18:14:33.293] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[18:14:33.302] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[18:14:33.302] <TB1> INFO: run 1 of 1
[18:14:33.570] <TB1> INFO: Expecting 6281600 events.
[18:15:05.255] <TB1> INFO: 747060 events read in total (31129ms).
[18:15:35.703] <TB1> INFO: 1492850 events read in total (61577ms).
[18:16:05.844] <TB1> INFO: 2238610 events read in total (91718ms).
[18:16:36.256] <TB1> INFO: 2984950 events read in total (122130ms).
[18:17:06.514] <TB1> INFO: 3725870 events read in total (152388ms).
[18:17:36.644] <TB1> INFO: 4463170 events read in total (182518ms).
[18:18:06.551] <TB1> INFO: 5198430 events read in total (212425ms).
[18:18:36.805] <TB1> INFO: 5932410 events read in total (242679ms).
[18:18:51.437] <TB1> INFO: 6281600 events read in total (257311ms).
[18:18:51.494] <TB1> INFO: Test took 258193ms.
[18:19:15.416] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 7.862711 .. 51.969520
[18:19:15.676] <TB1> INFO: Expecting 208000 events.
[18:19:25.285] <TB1> INFO: 208000 events read in total (9053ms).
[18:19:25.286] <TB1> INFO: Test took 9868ms.
[18:19:25.372] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 7 .. 61 (-1/-1) hits flags = 528 (plus default)
[18:19:25.381] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[18:19:25.381] <TB1> INFO: run 1 of 1
[18:19:25.644] <TB1> INFO: Expecting 2288000 events.
[18:19:59.368] <TB1> INFO: 911760 events read in total (33168ms).
[18:20:32.086] <TB1> INFO: 1817220 events read in total (65887ms).
[18:20:49.815] <TB1> INFO: 2288000 events read in total (83615ms).
[18:20:49.838] <TB1> INFO: Test took 84457ms.
[18:21:03.582] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 22.944147 .. 45.849138
[18:21:03.844] <TB1> INFO: Expecting 208000 events.
[18:21:13.526] <TB1> INFO: 208000 events read in total (9126ms).
[18:21:13.527] <TB1> INFO: Test took 9943ms.
[18:21:13.626] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 12 .. 55 (-1/-1) hits flags = 528 (plus default)
[18:21:13.635] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[18:21:13.635] <TB1> INFO: run 1 of 1
[18:21:13.900] <TB1> INFO: Expecting 1830400 events.
[18:21:47.609] <TB1> INFO: 915490 events read in total (33153ms).
[18:22:20.633] <TB1> INFO: 1828530 events read in total (66177ms).
[18:22:21.092] <TB1> INFO: 1830400 events read in total (66636ms).
[18:22:21.112] <TB1> INFO: Test took 67477ms.
[18:22:34.640] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 23.522678 .. 43.098146
[18:22:34.941] <TB1> INFO: Expecting 208000 events.
[18:22:44.718] <TB1> INFO: 208000 events read in total (9220ms).
[18:22:44.719] <TB1> INFO: Test took 10077ms.
[18:22:44.806] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 13 .. 53 (-1/-1) hits flags = 528 (plus default)
[18:22:44.814] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[18:22:44.815] <TB1> INFO: run 1 of 1
[18:22:45.078] <TB1> INFO: Expecting 1705600 events.
[18:23:19.346] <TB1> INFO: 925250 events read in total (33711ms).
[18:23:47.861] <TB1> INFO: 1705600 events read in total (62226ms).
[18:23:47.878] <TB1> INFO: Test took 63064ms.
[18:24:01.352] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 25.449786 .. 42.532498
[18:24:01.619] <TB1> INFO: Expecting 208000 events.
[18:24:11.422] <TB1> INFO: 208000 events read in total (9247ms).
[18:24:11.423] <TB1> INFO: Test took 10070ms.
[18:24:11.510] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 15 .. 52 (-1/-1) hits flags = 528 (plus default)
[18:24:11.519] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[18:24:11.519] <TB1> INFO: run 1 of 1
[18:24:11.792] <TB1> INFO: Expecting 1580800 events.
[18:24:45.760] <TB1> INFO: 921390 events read in total (33412ms).
[18:25:09.984] <TB1> INFO: 1580800 events read in total (57636ms).
[18:25:10.002] <TB1> INFO: Test took 58483ms.
[18:25:22.624] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[18:25:22.624] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[18:25:22.632] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[18:25:22.632] <TB1> INFO: run 1 of 1
[18:25:22.891] <TB1> INFO: Expecting 1705600 events.
[18:25:56.578] <TB1> INFO: 894830 events read in total (33130ms).
[18:26:26.239] <TB1> INFO: 1705600 events read in total (62791ms).
[18:26:26.256] <TB1> INFO: Test took 63624ms.
[18:26:40.267] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C0.dat
[18:26:40.267] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C1.dat
[18:26:40.267] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C2.dat
[18:26:40.267] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C3.dat
[18:26:40.267] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C4.dat
[18:26:40.268] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C5.dat
[18:26:40.268] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C6.dat
[18:26:40.268] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C7.dat
[18:26:40.268] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C8.dat
[18:26:40.268] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C9.dat
[18:26:40.268] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C10.dat
[18:26:40.269] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C11.dat
[18:26:40.269] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C12.dat
[18:26:40.269] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C13.dat
[18:26:40.269] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C14.dat
[18:26:40.269] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C15.dat
[18:26:40.269] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//trimParameters35_C0.dat
[18:26:40.279] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//trimParameters35_C1.dat
[18:26:40.287] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//trimParameters35_C2.dat
[18:26:40.296] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//trimParameters35_C3.dat
[18:26:40.306] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//trimParameters35_C4.dat
[18:26:40.315] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//trimParameters35_C5.dat
[18:26:40.321] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//trimParameters35_C6.dat
[18:26:40.328] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//trimParameters35_C7.dat
[18:26:40.334] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//trimParameters35_C8.dat
[18:26:40.340] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//trimParameters35_C9.dat
[18:26:40.346] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//trimParameters35_C10.dat
[18:26:40.352] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//trimParameters35_C11.dat
[18:26:40.358] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//trimParameters35_C12.dat
[18:26:40.364] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//trimParameters35_C13.dat
[18:26:40.371] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//trimParameters35_C14.dat
[18:26:40.377] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//trimParameters35_C15.dat
[18:26:40.383] <TB1> INFO: PixTestTrim::trimTest() done
[18:26:40.383] <TB1> INFO: vtrim: 111 100 114 92 98 99 98 97 103 120 120 100 101 111 97 98
[18:26:40.383] <TB1> INFO: vthrcomp: 106 98 100 95 93 90 94 93 94 91 93 88 95 101 97 93
[18:26:40.383] <TB1> INFO: vcal mean: 34.95 34.92 34.95 34.96 34.93 34.99 34.97 35.03 34.99 34.95 34.96 35.00 34.98 34.99 35.01 35.00
[18:26:40.383] <TB1> INFO: vcal RMS: 1.16 0.89 0.84 0.83 0.81 0.80 0.82 0.84 0.87 0.88 0.88 0.82 0.79 0.87 0.88 0.79
[18:26:40.383] <TB1> INFO: bits mean: 8.82 10.08 9.63 9.69 9.42 9.47 9.45 9.59 9.32 9.01 9.50 8.70 9.27 9.57 10.05 9.58
[18:26:40.383] <TB1> INFO: bits RMS: 2.50 2.59 2.65 2.52 2.56 2.43 2.49 2.50 2.68 2.62 2.59 2.73 2.75 2.69 2.51 2.50
[18:26:40.390] <TB1> INFO: ----------------------------------------------------------------------
[18:26:40.390] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[18:26:40.390] <TB1> INFO: ----------------------------------------------------------------------
[18:26:40.393] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[18:26:40.401] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[18:26:40.401] <TB1> INFO: run 1 of 1
[18:26:40.661] <TB1> INFO: Expecting 4160000 events.
[18:27:18.849] <TB1> INFO: 948125 events read in total (37632ms).
[18:27:56.502] <TB1> INFO: 1888845 events read in total (75285ms).
[18:28:34.380] <TB1> INFO: 2820265 events read in total (113163ms).
[18:29:11.470] <TB1> INFO: 3748845 events read in total (150253ms).
[18:29:27.975] <TB1> INFO: 4160000 events read in total (166759ms).
[18:29:28.026] <TB1> INFO: Test took 167625ms.
[18:29:56.159] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 167 (-1/-1) hits flags = 528 (plus default)
[18:29:56.168] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[18:29:56.168] <TB1> INFO: run 1 of 1
[18:29:56.428] <TB1> INFO: Expecting 3494400 events.
[18:30:35.303] <TB1> INFO: 987545 events read in total (38319ms).
[18:31:13.229] <TB1> INFO: 1964755 events read in total (76245ms).
[18:31:50.572] <TB1> INFO: 2930110 events read in total (113588ms).
[18:32:12.567] <TB1> INFO: 3494400 events read in total (135583ms).
[18:32:12.614] <TB1> INFO: Test took 136446ms.
[18:32:37.319] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 158 (-1/-1) hits flags = 528 (plus default)
[18:32:37.328] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[18:32:37.328] <TB1> INFO: run 1 of 1
[18:32:37.620] <TB1> INFO: Expecting 3307200 events.
[18:33:16.687] <TB1> INFO: 1015070 events read in total (38511ms).
[18:33:54.779] <TB1> INFO: 2016310 events read in total (76603ms).
[18:34:32.916] <TB1> INFO: 3006935 events read in total (114740ms).
[18:34:44.978] <TB1> INFO: 3307200 events read in total (126802ms).
[18:34:45.020] <TB1> INFO: Test took 127693ms.
[18:35:09.562] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 155 (-1/-1) hits flags = 528 (plus default)
[18:35:09.571] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[18:35:09.571] <TB1> INFO: run 1 of 1
[18:35:09.830] <TB1> INFO: Expecting 3244800 events.
[18:35:48.994] <TB1> INFO: 1024035 events read in total (38607ms).
[18:36:28.273] <TB1> INFO: 2033280 events read in total (77886ms).
[18:37:06.794] <TB1> INFO: 3032445 events read in total (116407ms).
[18:37:15.214] <TB1> INFO: 3244800 events read in total (124827ms).
[18:37:15.255] <TB1> INFO: Test took 125684ms.
[18:37:40.191] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 155 (-1/-1) hits flags = 528 (plus default)
[18:37:40.199] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[18:37:40.200] <TB1> INFO: run 1 of 1
[18:37:40.477] <TB1> INFO: Expecting 3244800 events.
[18:38:21.446] <TB1> INFO: 1023095 events read in total (40413ms).
[18:39:00.356] <TB1> INFO: 2031270 events read in total (79323ms).
[18:39:39.036] <TB1> INFO: 3029400 events read in total (118003ms).
[18:39:47.667] <TB1> INFO: 3244800 events read in total (126634ms).
[18:39:47.707] <TB1> INFO: Test took 127508ms.
[18:40:11.841] <TB1> INFO: PixTestTrim::trimBitTest() done
[18:40:11.842] <TB1> INFO: PixTestTrim::doTest() done, duration: 2216 seconds
[18:40:11.842] <TB1> INFO: Decoding statistics:
[18:40:11.842] <TB1> INFO: General information:
[18:40:11.842] <TB1> INFO: 16bit words read: 0
[18:40:11.842] <TB1> INFO: valid events total: 0
[18:40:11.842] <TB1> INFO: empty events: 0
[18:40:11.842] <TB1> INFO: valid events with pixels: 0
[18:40:11.842] <TB1> INFO: valid pixel hits: 0
[18:40:11.842] <TB1> INFO: Event errors: 0
[18:40:11.842] <TB1> INFO: start marker: 0
[18:40:11.842] <TB1> INFO: stop marker: 0
[18:40:11.842] <TB1> INFO: overflow: 0
[18:40:11.842] <TB1> INFO: invalid 5bit words: 0
[18:40:11.842] <TB1> INFO: invalid XOR eye diagram: 0
[18:40:11.842] <TB1> INFO: frame (failed synchr.): 0
[18:40:11.842] <TB1> INFO: idle data (no TBM trl): 0
[18:40:11.842] <TB1> INFO: no data (only TBM hdr): 0
[18:40:11.842] <TB1> INFO: TBM errors: 0
[18:40:11.842] <TB1> INFO: flawed TBM headers: 0
[18:40:11.842] <TB1> INFO: flawed TBM trailers: 0
[18:40:11.842] <TB1> INFO: event ID mismatches: 0
[18:40:11.842] <TB1> INFO: ROC errors: 0
[18:40:11.842] <TB1> INFO: missing ROC header(s): 0
[18:40:11.842] <TB1> INFO: misplaced readback start: 0
[18:40:11.842] <TB1> INFO: Pixel decoding errors: 0
[18:40:11.842] <TB1> INFO: pixel data incomplete: 0
[18:40:11.842] <TB1> INFO: pixel address: 0
[18:40:11.842] <TB1> INFO: pulse height fill bit: 0
[18:40:11.842] <TB1> INFO: buffer corruption: 0
[18:40:12.592] <TB1> INFO: ######################################################################
[18:40:12.592] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[18:40:12.592] <TB1> INFO: ######################################################################
[18:40:12.881] <TB1> INFO: Expecting 41600 events.
[18:40:16.322] <TB1> INFO: 41600 events read in total (2884ms).
[18:40:16.322] <TB1> INFO: Test took 3728ms.
[18:40:16.815] <TB1> INFO: Expecting 41600 events.
[18:40:20.220] <TB1> INFO: 41600 events read in total (2848ms).
[18:40:20.221] <TB1> INFO: Test took 3701ms.
[18:40:20.503] <TB1> INFO: Expecting 41600 events.
[18:40:23.979] <TB1> INFO: 41600 events read in total (2920ms).
[18:40:23.979] <TB1> INFO: Test took 3736ms.
[18:40:24.235] <TB1> INFO: Expecting 2560 events.
[18:40:25.087] <TB1> INFO: 2560 events read in total (296ms).
[18:40:25.087] <TB1> INFO: Test took 1095ms.
[18:40:25.392] <TB1> INFO: Expecting 2560 events.
[18:40:26.246] <TB1> INFO: 2560 events read in total (298ms).
[18:40:26.246] <TB1> INFO: Test took 1159ms.
[18:40:26.552] <TB1> INFO: Expecting 2560 events.
[18:40:27.407] <TB1> INFO: 2560 events read in total (299ms).
[18:40:27.407] <TB1> INFO: Test took 1160ms.
[18:40:27.712] <TB1> INFO: Expecting 2560 events.
[18:40:28.563] <TB1> INFO: 2560 events read in total (295ms).
[18:40:28.563] <TB1> INFO: Test took 1156ms.
[18:40:28.868] <TB1> INFO: Expecting 2560 events.
[18:40:29.722] <TB1> INFO: 2560 events read in total (298ms).
[18:40:29.722] <TB1> INFO: Test took 1153ms.
[18:40:30.027] <TB1> INFO: Expecting 2560 events.
[18:40:30.879] <TB1> INFO: 2560 events read in total (296ms).
[18:40:30.879] <TB1> INFO: Test took 1157ms.
[18:40:31.185] <TB1> INFO: Expecting 2560 events.
[18:40:32.037] <TB1> INFO: 2560 events read in total (296ms).
[18:40:32.037] <TB1> INFO: Test took 1158ms.
[18:40:32.342] <TB1> INFO: Expecting 2560 events.
[18:40:33.195] <TB1> INFO: 2560 events read in total (297ms).
[18:40:33.196] <TB1> INFO: Test took 1159ms.
[18:40:33.501] <TB1> INFO: Expecting 2560 events.
[18:40:34.353] <TB1> INFO: 2560 events read in total (296ms).
[18:40:34.353] <TB1> INFO: Test took 1157ms.
[18:40:34.658] <TB1> INFO: Expecting 2560 events.
[18:40:35.512] <TB1> INFO: 2560 events read in total (298ms).
[18:40:35.512] <TB1> INFO: Test took 1159ms.
[18:40:35.817] <TB1> INFO: Expecting 2560 events.
[18:40:36.669] <TB1> INFO: 2560 events read in total (296ms).
[18:40:36.669] <TB1> INFO: Test took 1156ms.
[18:40:36.975] <TB1> INFO: Expecting 2560 events.
[18:40:37.830] <TB1> INFO: 2560 events read in total (299ms).
[18:40:37.831] <TB1> INFO: Test took 1161ms.
[18:40:38.135] <TB1> INFO: Expecting 2560 events.
[18:40:38.994] <TB1> INFO: 2560 events read in total (302ms).
[18:40:38.994] <TB1> INFO: Test took 1163ms.
[18:40:39.298] <TB1> INFO: Expecting 2560 events.
[18:40:40.152] <TB1> INFO: 2560 events read in total (297ms).
[18:40:40.152] <TB1> INFO: Test took 1157ms.
[18:40:40.457] <TB1> INFO: Expecting 2560 events.
[18:40:41.313] <TB1> INFO: 2560 events read in total (300ms).
[18:40:41.313] <TB1> INFO: Test took 1161ms.
[18:40:41.618] <TB1> INFO: Expecting 2560 events.
[18:40:42.471] <TB1> INFO: 2560 events read in total (297ms).
[18:40:42.471] <TB1> INFO: Test took 1157ms.
[18:40:42.474] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:40:42.776] <TB1> INFO: Expecting 655360 events.
[18:40:56.715] <TB1> INFO: 655360 events read in total (13382ms).
[18:40:56.725] <TB1> INFO: Expecting 655360 events.
[18:41:10.427] <TB1> INFO: 655360 events read in total (13300ms).
[18:41:10.445] <TB1> INFO: Expecting 655360 events.
[18:41:24.236] <TB1> INFO: 655360 events read in total (13389ms).
[18:41:24.254] <TB1> INFO: Expecting 655360 events.
[18:41:37.889] <TB1> INFO: 655360 events read in total (13233ms).
[18:41:37.914] <TB1> INFO: Expecting 655360 events.
[18:41:51.717] <TB1> INFO: 655360 events read in total (13401ms).
[18:41:51.744] <TB1> INFO: Expecting 655360 events.
[18:42:05.589] <TB1> INFO: 655360 events read in total (13443ms).
[18:42:05.621] <TB1> INFO: Expecting 655360 events.
[18:42:19.532] <TB1> INFO: 655360 events read in total (13509ms).
[18:42:19.569] <TB1> INFO: Expecting 655360 events.
[18:42:33.359] <TB1> INFO: 655360 events read in total (13388ms).
[18:42:33.400] <TB1> INFO: Expecting 655360 events.
[18:42:47.176] <TB1> INFO: 655360 events read in total (13374ms).
[18:42:47.221] <TB1> INFO: Expecting 655360 events.
[18:43:01.172] <TB1> INFO: 655360 events read in total (13549ms).
[18:43:01.227] <TB1> INFO: Expecting 655360 events.
[18:43:15.020] <TB1> INFO: 655360 events read in total (13391ms).
[18:43:15.080] <TB1> INFO: Expecting 655360 events.
[18:43:28.947] <TB1> INFO: 655360 events read in total (13466ms).
[18:43:29.019] <TB1> INFO: Expecting 655360 events.
[18:43:42.860] <TB1> INFO: 655360 events read in total (13439ms).
[18:43:42.922] <TB1> INFO: Expecting 655360 events.
[18:43:56.746] <TB1> INFO: 655360 events read in total (13422ms).
[18:43:56.810] <TB1> INFO: Expecting 655360 events.
[18:44:10.720] <TB1> INFO: 655360 events read in total (13508ms).
[18:44:10.798] <TB1> INFO: Expecting 655360 events.
[18:44:24.672] <TB1> INFO: 655360 events read in total (13472ms).
[18:44:24.745] <TB1> INFO: Test took 222271ms.
[18:44:24.825] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:44:25.055] <TB1> INFO: Expecting 655360 events.
[18:44:39.033] <TB1> INFO: 655360 events read in total (13422ms).
[18:44:39.046] <TB1> INFO: Expecting 655360 events.
[18:44:52.838] <TB1> INFO: 655360 events read in total (13390ms).
[18:44:52.853] <TB1> INFO: Expecting 655360 events.
[18:45:06.678] <TB1> INFO: 655360 events read in total (13423ms).
[18:45:06.698] <TB1> INFO: Expecting 655360 events.
[18:45:20.514] <TB1> INFO: 655360 events read in total (13415ms).
[18:45:20.538] <TB1> INFO: Expecting 655360 events.
[18:45:34.332] <TB1> INFO: 655360 events read in total (13392ms).
[18:45:34.358] <TB1> INFO: Expecting 655360 events.
[18:45:48.141] <TB1> INFO: 655360 events read in total (13381ms).
[18:45:48.178] <TB1> INFO: Expecting 655360 events.
[18:46:01.784] <TB1> INFO: 655360 events read in total (13205ms).
[18:46:01.832] <TB1> INFO: Expecting 655360 events.
[18:46:15.569] <TB1> INFO: 655360 events read in total (13335ms).
[18:46:15.620] <TB1> INFO: Expecting 655360 events.
[18:46:29.441] <TB1> INFO: 655360 events read in total (13419ms).
[18:46:29.486] <TB1> INFO: Expecting 655360 events.
[18:46:43.177] <TB1> INFO: 655360 events read in total (13289ms).
[18:46:43.226] <TB1> INFO: Expecting 655360 events.
[18:46:57.162] <TB1> INFO: 655360 events read in total (13535ms).
[18:46:57.219] <TB1> INFO: Expecting 655360 events.
[18:47:11.025] <TB1> INFO: 655360 events read in total (13404ms).
[18:47:11.083] <TB1> INFO: Expecting 655360 events.
[18:47:24.835] <TB1> INFO: 655360 events read in total (13350ms).
[18:47:24.897] <TB1> INFO: Expecting 655360 events.
[18:47:38.706] <TB1> INFO: 655360 events read in total (13407ms).
[18:47:38.770] <TB1> INFO: Expecting 655360 events.
[18:47:52.643] <TB1> INFO: 655360 events read in total (13471ms).
[18:47:52.717] <TB1> INFO: Expecting 655360 events.
[18:48:06.335] <TB1> INFO: 655360 events read in total (13216ms).
[18:48:06.409] <TB1> INFO: Test took 221584ms.
[18:48:06.596] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:48:06.603] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:48:06.611] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:48:06.618] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:48:06.626] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:48:06.635] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:48:06.642] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:48:06.649] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:48:06.658] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:48:06.665] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:48:06.674] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:48:06.681] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:48:06.689] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:48:06.698] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:48:06.705] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:48:06.712] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[18:48:06.753] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C0.dat
[18:48:06.753] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C1.dat
[18:48:06.753] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C2.dat
[18:48:06.753] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C3.dat
[18:48:06.754] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C4.dat
[18:48:06.754] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C5.dat
[18:48:06.754] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C6.dat
[18:48:06.754] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C7.dat
[18:48:06.754] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C8.dat
[18:48:06.754] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C9.dat
[18:48:06.754] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C10.dat
[18:48:06.754] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C11.dat
[18:48:06.754] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C12.dat
[18:48:06.754] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C13.dat
[18:48:06.754] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C14.dat
[18:48:06.755] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//dacParameters35_C15.dat
[18:48:07.017] <TB1> INFO: Expecting 41600 events.
[18:48:10.124] <TB1> INFO: 41600 events read in total (2552ms).
[18:48:10.125] <TB1> INFO: Test took 3368ms.
[18:48:10.564] <TB1> INFO: Expecting 41600 events.
[18:48:13.601] <TB1> INFO: 41600 events read in total (2481ms).
[18:48:13.602] <TB1> INFO: Test took 3298ms.
[18:48:14.040] <TB1> INFO: Expecting 41600 events.
[18:48:17.132] <TB1> INFO: 41600 events read in total (2536ms).
[18:48:17.133] <TB1> INFO: Test took 3353ms.
[18:48:17.317] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:17.406] <TB1> INFO: Expecting 2560 events.
[18:48:18.257] <TB1> INFO: 2560 events read in total (295ms).
[18:48:18.257] <TB1> INFO: Test took 940ms.
[18:48:18.259] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:18.565] <TB1> INFO: Expecting 2560 events.
[18:48:19.420] <TB1> INFO: 2560 events read in total (299ms).
[18:48:19.421] <TB1> INFO: Test took 1162ms.
[18:48:19.423] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:19.725] <TB1> INFO: Expecting 2560 events.
[18:48:20.577] <TB1> INFO: 2560 events read in total (296ms).
[18:48:20.577] <TB1> INFO: Test took 1154ms.
[18:48:20.579] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:20.882] <TB1> INFO: Expecting 2560 events.
[18:48:21.738] <TB1> INFO: 2560 events read in total (299ms).
[18:48:21.738] <TB1> INFO: Test took 1159ms.
[18:48:21.741] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:22.043] <TB1> INFO: Expecting 2560 events.
[18:48:22.895] <TB1> INFO: 2560 events read in total (296ms).
[18:48:22.895] <TB1> INFO: Test took 1154ms.
[18:48:22.897] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:23.200] <TB1> INFO: Expecting 2560 events.
[18:48:24.052] <TB1> INFO: 2560 events read in total (295ms).
[18:48:24.052] <TB1> INFO: Test took 1155ms.
[18:48:24.054] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:24.357] <TB1> INFO: Expecting 2560 events.
[18:48:25.212] <TB1> INFO: 2560 events read in total (298ms).
[18:48:25.212] <TB1> INFO: Test took 1158ms.
[18:48:25.214] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:25.518] <TB1> INFO: Expecting 2560 events.
[18:48:26.370] <TB1> INFO: 2560 events read in total (296ms).
[18:48:26.370] <TB1> INFO: Test took 1156ms.
[18:48:26.372] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:26.676] <TB1> INFO: Expecting 2560 events.
[18:48:27.530] <TB1> INFO: 2560 events read in total (298ms).
[18:48:27.530] <TB1> INFO: Test took 1158ms.
[18:48:27.532] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:27.836] <TB1> INFO: Expecting 2560 events.
[18:48:28.692] <TB1> INFO: 2560 events read in total (300ms).
[18:48:28.693] <TB1> INFO: Test took 1161ms.
[18:48:28.695] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:28.999] <TB1> INFO: Expecting 2560 events.
[18:48:29.854] <TB1> INFO: 2560 events read in total (298ms).
[18:48:29.854] <TB1> INFO: Test took 1159ms.
[18:48:29.861] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:30.160] <TB1> INFO: Expecting 2560 events.
[18:48:31.011] <TB1> INFO: 2560 events read in total (296ms).
[18:48:31.012] <TB1> INFO: Test took 1151ms.
[18:48:31.014] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:31.318] <TB1> INFO: Expecting 2560 events.
[18:48:32.170] <TB1> INFO: 2560 events read in total (296ms).
[18:48:32.170] <TB1> INFO: Test took 1157ms.
[18:48:32.173] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:32.477] <TB1> INFO: Expecting 2560 events.
[18:48:33.331] <TB1> INFO: 2560 events read in total (297ms).
[18:48:33.331] <TB1> INFO: Test took 1158ms.
[18:48:33.335] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:33.637] <TB1> INFO: Expecting 2560 events.
[18:48:34.490] <TB1> INFO: 2560 events read in total (297ms).
[18:48:34.490] <TB1> INFO: Test took 1155ms.
[18:48:34.492] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:34.795] <TB1> INFO: Expecting 2560 events.
[18:48:35.649] <TB1> INFO: 2560 events read in total (298ms).
[18:48:35.650] <TB1> INFO: Test took 1158ms.
[18:48:35.653] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:35.954] <TB1> INFO: Expecting 2560 events.
[18:48:36.812] <TB1> INFO: 2560 events read in total (302ms).
[18:48:36.812] <TB1> INFO: Test took 1160ms.
[18:48:36.814] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:37.117] <TB1> INFO: Expecting 2560 events.
[18:48:37.970] <TB1> INFO: 2560 events read in total (296ms).
[18:48:37.970] <TB1> INFO: Test took 1157ms.
[18:48:37.972] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:38.275] <TB1> INFO: Expecting 2560 events.
[18:48:39.128] <TB1> INFO: 2560 events read in total (296ms).
[18:48:39.128] <TB1> INFO: Test took 1157ms.
[18:48:39.131] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:39.434] <TB1> INFO: Expecting 2560 events.
[18:48:40.293] <TB1> INFO: 2560 events read in total (303ms).
[18:48:40.293] <TB1> INFO: Test took 1162ms.
[18:48:40.295] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:40.598] <TB1> INFO: Expecting 2560 events.
[18:48:41.453] <TB1> INFO: 2560 events read in total (298ms).
[18:48:41.454] <TB1> INFO: Test took 1159ms.
[18:48:41.456] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:41.759] <TB1> INFO: Expecting 2560 events.
[18:48:42.611] <TB1> INFO: 2560 events read in total (295ms).
[18:48:42.611] <TB1> INFO: Test took 1156ms.
[18:48:42.614] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:42.916] <TB1> INFO: Expecting 2560 events.
[18:48:43.768] <TB1> INFO: 2560 events read in total (296ms).
[18:48:43.768] <TB1> INFO: Test took 1154ms.
[18:48:43.770] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:44.074] <TB1> INFO: Expecting 2560 events.
[18:48:44.926] <TB1> INFO: 2560 events read in total (296ms).
[18:48:44.926] <TB1> INFO: Test took 1156ms.
[18:48:44.928] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:45.232] <TB1> INFO: Expecting 2560 events.
[18:48:46.083] <TB1> INFO: 2560 events read in total (295ms).
[18:48:46.084] <TB1> INFO: Test took 1156ms.
[18:48:46.085] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:46.389] <TB1> INFO: Expecting 2560 events.
[18:48:47.243] <TB1> INFO: 2560 events read in total (298ms).
[18:48:47.243] <TB1> INFO: Test took 1158ms.
[18:48:47.245] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:47.549] <TB1> INFO: Expecting 2560 events.
[18:48:48.403] <TB1> INFO: 2560 events read in total (298ms).
[18:48:48.404] <TB1> INFO: Test took 1159ms.
[18:48:48.406] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:48.709] <TB1> INFO: Expecting 2560 events.
[18:48:49.564] <TB1> INFO: 2560 events read in total (299ms).
[18:48:49.564] <TB1> INFO: Test took 1159ms.
[18:48:49.566] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:49.869] <TB1> INFO: Expecting 2560 events.
[18:48:50.725] <TB1> INFO: 2560 events read in total (300ms).
[18:48:50.725] <TB1> INFO: Test took 1159ms.
[18:48:50.729] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:51.030] <TB1> INFO: Expecting 2560 events.
[18:48:51.884] <TB1> INFO: 2560 events read in total (298ms).
[18:48:51.884] <TB1> INFO: Test took 1155ms.
[18:48:51.886] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:52.189] <TB1> INFO: Expecting 2560 events.
[18:48:53.041] <TB1> INFO: 2560 events read in total (295ms).
[18:48:53.042] <TB1> INFO: Test took 1156ms.
[18:48:53.043] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:48:53.347] <TB1> INFO: Expecting 2560 events.
[18:48:54.198] <TB1> INFO: 2560 events read in total (295ms).
[18:48:54.199] <TB1> INFO: Test took 1156ms.
[18:48:54.679] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 522 seconds
[18:48:54.679] <TB1> INFO: PH scale (per ROC): 77 75 86 76 79 82 74 70 75 67 78 77 75 65 79 74
[18:48:54.679] <TB1> INFO: PH offset (per ROC): 174 167 166 170 164 182 156 189 171 162 171 161 177 184 166 172
[18:48:54.683] <TB1> INFO: Decoding statistics:
[18:48:54.683] <TB1> INFO: General information:
[18:48:54.683] <TB1> INFO: 16bit words read: 86930
[18:48:54.683] <TB1> INFO: valid events total: 10240
[18:48:54.683] <TB1> INFO: empty events: 7735
[18:48:54.683] <TB1> INFO: valid events with pixels: 2505
[18:48:54.683] <TB1> INFO: valid pixel hits: 2505
[18:48:54.683] <TB1> INFO: Event errors: 0
[18:48:54.683] <TB1> INFO: start marker: 0
[18:48:54.683] <TB1> INFO: stop marker: 0
[18:48:54.683] <TB1> INFO: overflow: 0
[18:48:54.683] <TB1> INFO: invalid 5bit words: 0
[18:48:54.683] <TB1> INFO: invalid XOR eye diagram: 0
[18:48:54.683] <TB1> INFO: frame (failed synchr.): 0
[18:48:54.683] <TB1> INFO: idle data (no TBM trl): 0
[18:48:54.683] <TB1> INFO: no data (only TBM hdr): 0
[18:48:54.683] <TB1> INFO: TBM errors: 0
[18:48:54.683] <TB1> INFO: flawed TBM headers: 0
[18:48:54.683] <TB1> INFO: flawed TBM trailers: 0
[18:48:54.683] <TB1> INFO: event ID mismatches: 0
[18:48:54.683] <TB1> INFO: ROC errors: 0
[18:48:54.683] <TB1> INFO: missing ROC header(s): 0
[18:48:54.683] <TB1> INFO: misplaced readback start: 0
[18:48:54.683] <TB1> INFO: Pixel decoding errors: 0
[18:48:54.683] <TB1> INFO: pixel data incomplete: 0
[18:48:54.683] <TB1> INFO: pixel address: 0
[18:48:54.683] <TB1> INFO: pulse height fill bit: 0
[18:48:54.683] <TB1> INFO: buffer corruption: 0
[18:48:54.862] <TB1> INFO: ######################################################################
[18:48:54.862] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[18:48:54.862] <TB1> INFO: ######################################################################
[18:48:54.872] <TB1> INFO: scanning low vcal = 10
[18:48:55.134] <TB1> INFO: Expecting 41600 events.
[18:48:58.504] <TB1> INFO: 41600 events read in total (2814ms).
[18:48:58.504] <TB1> INFO: Test took 3632ms.
[18:48:58.506] <TB1> INFO: scanning low vcal = 20
[18:48:58.803] <TB1> INFO: Expecting 41600 events.
[18:49:02.192] <TB1> INFO: 41600 events read in total (2833ms).
[18:49:02.192] <TB1> INFO: Test took 3686ms.
[18:49:02.194] <TB1> INFO: scanning low vcal = 30
[18:49:02.491] <TB1> INFO: Expecting 41600 events.
[18:49:05.912] <TB1> INFO: 41600 events read in total (2864ms).
[18:49:05.913] <TB1> INFO: Test took 3719ms.
[18:49:05.915] <TB1> INFO: scanning low vcal = 40
[18:49:06.273] <TB1> INFO: Expecting 41600 events.
[18:49:10.136] <TB1> INFO: 41600 events read in total (3307ms).
[18:49:10.137] <TB1> INFO: Test took 4222ms.
[18:49:10.140] <TB1> INFO: scanning low vcal = 50
[18:49:10.400] <TB1> INFO: Expecting 41600 events.
[18:49:14.414] <TB1> INFO: 41600 events read in total (3459ms).
[18:49:14.415] <TB1> INFO: Test took 4275ms.
[18:49:14.418] <TB1> INFO: scanning low vcal = 60
[18:49:14.716] <TB1> INFO: Expecting 41600 events.
[18:49:18.668] <TB1> INFO: 41600 events read in total (3396ms).
[18:49:18.668] <TB1> INFO: Test took 4250ms.
[18:49:18.671] <TB1> INFO: scanning low vcal = 70
[18:49:18.960] <TB1> INFO: Expecting 41600 events.
[18:49:22.887] <TB1> INFO: 41600 events read in total (3371ms).
[18:49:22.887] <TB1> INFO: Test took 4216ms.
[18:49:22.892] <TB1> INFO: scanning low vcal = 80
[18:49:23.182] <TB1> INFO: Expecting 41600 events.
[18:49:27.183] <TB1> INFO: 41600 events read in total (3444ms).
[18:49:27.184] <TB1> INFO: Test took 4292ms.
[18:49:27.187] <TB1> INFO: scanning low vcal = 90
[18:49:27.472] <TB1> INFO: Expecting 41600 events.
[18:49:31.458] <TB1> INFO: 41600 events read in total (3430ms).
[18:49:31.458] <TB1> INFO: Test took 4271ms.
[18:49:31.461] <TB1> INFO: scanning low vcal = 100
[18:49:31.741] <TB1> INFO: Expecting 41600 events.
[18:49:35.677] <TB1> INFO: 41600 events read in total (3380ms).
[18:49:35.678] <TB1> INFO: Test took 4216ms.
[18:49:35.681] <TB1> INFO: scanning low vcal = 110
[18:49:35.960] <TB1> INFO: Expecting 41600 events.
[18:49:39.898] <TB1> INFO: 41600 events read in total (3382ms).
[18:49:39.898] <TB1> INFO: Test took 4217ms.
[18:49:39.901] <TB1> INFO: scanning low vcal = 120
[18:49:40.160] <TB1> INFO: Expecting 41600 events.
[18:49:44.120] <TB1> INFO: 41600 events read in total (3403ms).
[18:49:44.121] <TB1> INFO: Test took 4219ms.
[18:49:44.123] <TB1> INFO: scanning low vcal = 130
[18:49:44.386] <TB1> INFO: Expecting 41600 events.
[18:49:48.337] <TB1> INFO: 41600 events read in total (3395ms).
[18:49:48.337] <TB1> INFO: Test took 4214ms.
[18:49:48.340] <TB1> INFO: scanning low vcal = 140
[18:49:48.606] <TB1> INFO: Expecting 41600 events.
[18:49:52.579] <TB1> INFO: 41600 events read in total (3417ms).
[18:49:52.579] <TB1> INFO: Test took 4239ms.
[18:49:52.582] <TB1> INFO: scanning low vcal = 150
[18:49:52.862] <TB1> INFO: Expecting 41600 events.
[18:49:56.855] <TB1> INFO: 41600 events read in total (3437ms).
[18:49:56.855] <TB1> INFO: Test took 4272ms.
[18:49:56.858] <TB1> INFO: scanning low vcal = 160
[18:49:57.149] <TB1> INFO: Expecting 41600 events.
[18:50:01.112] <TB1> INFO: 41600 events read in total (3406ms).
[18:50:01.112] <TB1> INFO: Test took 4254ms.
[18:50:01.115] <TB1> INFO: scanning low vcal = 170
[18:50:01.382] <TB1> INFO: Expecting 41600 events.
[18:50:05.375] <TB1> INFO: 41600 events read in total (3437ms).
[18:50:05.376] <TB1> INFO: Test took 4261ms.
[18:50:05.380] <TB1> INFO: scanning low vcal = 180
[18:50:05.660] <TB1> INFO: Expecting 41600 events.
[18:50:09.634] <TB1> INFO: 41600 events read in total (3418ms).
[18:50:09.635] <TB1> INFO: Test took 4255ms.
[18:50:09.637] <TB1> INFO: scanning low vcal = 190
[18:50:09.912] <TB1> INFO: Expecting 41600 events.
[18:50:13.877] <TB1> INFO: 41600 events read in total (3407ms).
[18:50:13.925] <TB1> INFO: Test took 4287ms.
[18:50:13.927] <TB1> INFO: scanning low vcal = 200
[18:50:14.199] <TB1> INFO: Expecting 41600 events.
[18:50:18.190] <TB1> INFO: 41600 events read in total (3435ms).
[18:50:18.191] <TB1> INFO: Test took 4263ms.
[18:50:18.194] <TB1> INFO: scanning low vcal = 210
[18:50:18.483] <TB1> INFO: Expecting 41600 events.
[18:50:22.443] <TB1> INFO: 41600 events read in total (3403ms).
[18:50:22.444] <TB1> INFO: Test took 4250ms.
[18:50:22.447] <TB1> INFO: scanning low vcal = 220
[18:50:22.726] <TB1> INFO: Expecting 41600 events.
[18:50:26.709] <TB1> INFO: 41600 events read in total (3427ms).
[18:50:26.710] <TB1> INFO: Test took 4263ms.
[18:50:26.713] <TB1> INFO: scanning low vcal = 230
[18:50:26.972] <TB1> INFO: Expecting 41600 events.
[18:50:30.972] <TB1> INFO: 41600 events read in total (3444ms).
[18:50:30.973] <TB1> INFO: Test took 4260ms.
[18:50:30.981] <TB1> INFO: scanning low vcal = 240
[18:50:31.268] <TB1> INFO: Expecting 41600 events.
[18:50:35.213] <TB1> INFO: 41600 events read in total (3389ms).
[18:50:35.213] <TB1> INFO: Test took 4231ms.
[18:50:35.217] <TB1> INFO: scanning low vcal = 250
[18:50:35.478] <TB1> INFO: Expecting 41600 events.
[18:50:39.419] <TB1> INFO: 41600 events read in total (3384ms).
[18:50:39.419] <TB1> INFO: Test took 4202ms.
[18:50:39.423] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[18:50:39.683] <TB1> INFO: Expecting 41600 events.
[18:50:43.667] <TB1> INFO: 41600 events read in total (3428ms).
[18:50:43.668] <TB1> INFO: Test took 4245ms.
[18:50:43.671] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[18:50:43.961] <TB1> INFO: Expecting 41600 events.
[18:50:47.881] <TB1> INFO: 41600 events read in total (3363ms).
[18:50:47.881] <TB1> INFO: Test took 4210ms.
[18:50:47.887] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[18:50:48.148] <TB1> INFO: Expecting 41600 events.
[18:50:52.104] <TB1> INFO: 41600 events read in total (3400ms).
[18:50:52.105] <TB1> INFO: Test took 4217ms.
[18:50:52.108] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[18:50:52.405] <TB1> INFO: Expecting 41600 events.
[18:50:56.366] <TB1> INFO: 41600 events read in total (3405ms).
[18:50:56.367] <TB1> INFO: Test took 4259ms.
[18:50:56.369] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[18:50:56.637] <TB1> INFO: Expecting 41600 events.
[18:51:00.598] <TB1> INFO: 41600 events read in total (3404ms).
[18:51:00.598] <TB1> INFO: Test took 4229ms.
[18:51:01.138] <TB1> INFO: PixTestGainPedestal::measure() done
[18:51:35.509] <TB1> INFO: PixTestGainPedestal::fit() done
[18:51:35.509] <TB1> INFO: non-linearity mean: 0.950 0.955 0.954 0.957 0.948 0.958 0.950 0.945 0.957 0.952 0.956 0.950 0.946 0.949 0.954 0.956
[18:51:35.509] <TB1> INFO: non-linearity RMS: 0.008 0.007 0.006 0.005 0.008 0.007 0.007 0.007 0.007 0.008 0.007 0.009 0.007 0.007 0.007 0.006
[18:51:35.509] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[18:51:35.528] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[18:51:35.551] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[18:51:35.578] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[18:51:35.610] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[18:51:35.642] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[18:51:35.665] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[18:51:35.683] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[18:51:35.707] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[18:51:35.732] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[18:51:35.764] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[18:51:35.791] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[18:51:35.823] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[18:51:35.855] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[18:51:35.886] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[18:51:35.918] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[18:51:35.949] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 161 seconds
[18:51:35.949] <TB1> INFO: Decoding statistics:
[18:51:35.949] <TB1> INFO: General information:
[18:51:35.949] <TB1> INFO: 16bit words read: 2662358
[18:51:35.949] <TB1> INFO: valid events total: 166400
[18:51:35.949] <TB1> INFO: empty events: 0
[18:51:35.949] <TB1> INFO: valid events with pixels: 166400
[18:51:35.949] <TB1> INFO: valid pixel hits: 665579
[18:51:35.949] <TB1> INFO: Event errors: 0
[18:51:35.949] <TB1> INFO: start marker: 0
[18:51:35.949] <TB1> INFO: stop marker: 0
[18:51:35.949] <TB1> INFO: overflow: 0
[18:51:35.949] <TB1> INFO: invalid 5bit words: 0
[18:51:35.949] <TB1> INFO: invalid XOR eye diagram: 0
[18:51:35.949] <TB1> INFO: frame (failed synchr.): 0
[18:51:35.949] <TB1> INFO: idle data (no TBM trl): 0
[18:51:35.949] <TB1> INFO: no data (only TBM hdr): 0
[18:51:35.949] <TB1> INFO: TBM errors: 0
[18:51:35.949] <TB1> INFO: flawed TBM headers: 0
[18:51:35.949] <TB1> INFO: flawed TBM trailers: 0
[18:51:35.949] <TB1> INFO: event ID mismatches: 0
[18:51:35.949] <TB1> INFO: ROC errors: 0
[18:51:35.949] <TB1> INFO: missing ROC header(s): 0
[18:51:35.949] <TB1> INFO: misplaced readback start: 0
[18:51:35.949] <TB1> INFO: Pixel decoding errors: 0
[18:51:35.949] <TB1> INFO: pixel data incomplete: 0
[18:51:35.949] <TB1> INFO: pixel address: 0
[18:51:35.949] <TB1> INFO: pulse height fill bit: 0
[18:51:35.949] <TB1> INFO: buffer corruption: 0
[18:51:35.958] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C15.dat
[18:51:35.959] <TB1> INFO: ######################################################################
[18:51:35.959] <TB1> INFO: PixTestReadback::doTest()
[18:51:35.959] <TB1> INFO: ######################################################################
[18:51:35.959] <TB1> INFO: ----------------------------------------------------------------------
[18:51:35.959] <TB1> INFO: PixTestReadback::CalibrateVd()
[18:51:35.959] <TB1> INFO: ----------------------------------------------------------------------
[18:51:45.447] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C0.dat
[18:51:45.447] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C1.dat
[18:51:45.447] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C2.dat
[18:51:45.447] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C3.dat
[18:51:45.447] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C4.dat
[18:51:45.447] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C5.dat
[18:51:45.447] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C6.dat
[18:51:45.447] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C7.dat
[18:51:45.447] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C8.dat
[18:51:45.447] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C9.dat
[18:51:45.447] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C10.dat
[18:51:45.448] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C11.dat
[18:51:45.448] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C12.dat
[18:51:45.448] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C13.dat
[18:51:45.448] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C14.dat
[18:51:45.448] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C15.dat
[18:51:45.478] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[18:51:45.478] <TB1> INFO: ----------------------------------------------------------------------
[18:51:45.478] <TB1> INFO: PixTestReadback::CalibrateVa()
[18:51:45.478] <TB1> INFO: ----------------------------------------------------------------------
[18:51:54.972] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C0.dat
[18:51:54.972] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C1.dat
[18:51:54.972] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C2.dat
[18:51:54.972] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C3.dat
[18:51:54.972] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C4.dat
[18:51:54.972] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C5.dat
[18:51:54.972] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C6.dat
[18:51:54.973] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C7.dat
[18:51:54.973] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C8.dat
[18:51:54.973] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C9.dat
[18:51:54.973] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C10.dat
[18:51:54.973] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C11.dat
[18:51:54.973] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C12.dat
[18:51:54.973] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C13.dat
[18:51:54.973] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C14.dat
[18:51:54.973] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C15.dat
[18:51:55.004] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[18:51:55.004] <TB1> INFO: ----------------------------------------------------------------------
[18:51:55.004] <TB1> INFO: PixTestReadback::readbackVbg()
[18:51:55.004] <TB1> INFO: ----------------------------------------------------------------------
[18:52:02.314] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[18:52:02.314] <TB1> INFO: ----------------------------------------------------------------------
[18:52:02.314] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[18:52:02.314] <TB1> INFO: ----------------------------------------------------------------------
[18:52:02.314] <TB1> INFO: Vbg will be calibrated using Vd calibration
[18:52:02.314] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 151.8calibrated Vbg = 1.20086 :::*/*/*/*/
[18:52:02.314] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 146.4calibrated Vbg = 1.20356 :::*/*/*/*/
[18:52:02.314] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 147.5calibrated Vbg = 1.19832 :::*/*/*/*/
[18:52:02.314] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 147.7calibrated Vbg = 1.20896 :::*/*/*/*/
[18:52:02.314] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 152.4calibrated Vbg = 1.21631 :::*/*/*/*/
[18:52:02.314] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 152.8calibrated Vbg = 1.21945 :::*/*/*/*/
[18:52:02.314] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 147.8calibrated Vbg = 1.21546 :::*/*/*/*/
[18:52:02.314] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 148calibrated Vbg = 1.2181 :::*/*/*/*/
[18:52:02.314] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 155.4calibrated Vbg = 1.21576 :::*/*/*/*/
[18:52:02.314] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 142calibrated Vbg = 1.21887 :::*/*/*/*/
[18:52:02.314] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 153calibrated Vbg = 1.21256 :::*/*/*/*/
[18:52:02.314] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 154.5calibrated Vbg = 1.20456 :::*/*/*/*/
[18:52:02.314] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 155.8calibrated Vbg = 1.20367 :::*/*/*/*/
[18:52:02.314] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 154calibrated Vbg = 1.20468 :::*/*/*/*/
[18:52:02.314] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 154.5calibrated Vbg = 1.20196 :::*/*/*/*/
[18:52:02.314] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 147.4calibrated Vbg = 1.20319 :::*/*/*/*/
[18:52:02.316] <TB1> INFO: ----------------------------------------------------------------------
[18:52:02.316] <TB1> INFO: PixTestReadback::CalibrateIa()
[18:52:02.316] <TB1> INFO: ----------------------------------------------------------------------
[18:54:37.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C0.dat
[18:54:37.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C1.dat
[18:54:37.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C2.dat
[18:54:37.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C3.dat
[18:54:37.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C4.dat
[18:54:37.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C5.dat
[18:54:37.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C6.dat
[18:54:37.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C7.dat
[18:54:37.588] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C8.dat
[18:54:37.588] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C9.dat
[18:54:37.588] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C10.dat
[18:54:37.588] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C11.dat
[18:54:37.588] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C12.dat
[18:54:37.588] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C13.dat
[18:54:37.588] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C14.dat
[18:54:37.588] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2341_FullQualification_2016-09-20_11h10m_1474362632//004_FulltestPxar_p17//readbackCal_C15.dat
[18:54:37.619] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[18:54:37.619] <TB1> INFO: PixTestReadback::doTest() done
[18:54:37.619] <TB1> INFO: Decoding statistics:
[18:54:37.619] <TB1> INFO: General information:
[18:54:37.619] <TB1> INFO: 16bit words read: 1024
[18:54:37.619] <TB1> INFO: valid events total: 128
[18:54:37.619] <TB1> INFO: empty events: 128
[18:54:37.619] <TB1> INFO: valid events with pixels: 0
[18:54:37.619] <TB1> INFO: valid pixel hits: 0
[18:54:37.619] <TB1> INFO: Event errors: 0
[18:54:37.619] <TB1> INFO: start marker: 0
[18:54:37.619] <TB1> INFO: stop marker: 0
[18:54:37.620] <TB1> INFO: overflow: 0
[18:54:37.620] <TB1> INFO: invalid 5bit words: 0
[18:54:37.620] <TB1> INFO: invalid XOR eye diagram: 0
[18:54:37.620] <TB1> INFO: frame (failed synchr.): 0
[18:54:37.620] <TB1> INFO: idle data (no TBM trl): 0
[18:54:37.620] <TB1> INFO: no data (only TBM hdr): 0
[18:54:37.620] <TB1> INFO: TBM errors: 0
[18:54:37.620] <TB1> INFO: flawed TBM headers: 0
[18:54:37.620] <TB1> INFO: flawed TBM trailers: 0
[18:54:37.620] <TB1> INFO: event ID mismatches: 0
[18:54:37.620] <TB1> INFO: ROC errors: 0
[18:54:37.620] <TB1> INFO: missing ROC header(s): 0
[18:54:37.620] <TB1> INFO: misplaced readback start: 0
[18:54:37.620] <TB1> INFO: Pixel decoding errors: 0
[18:54:37.620] <TB1> INFO: pixel data incomplete: 0
[18:54:37.620] <TB1> INFO: pixel address: 0
[18:54:37.620] <TB1> INFO: pulse height fill bit: 0
[18:54:37.620] <TB1> INFO: buffer corruption: 0
[18:54:37.639] <TB1> INFO: Decoding statistics:
[18:54:37.639] <TB1> INFO: General information:
[18:54:37.639] <TB1> INFO: 16bit words read: 2750312
[18:54:37.639] <TB1> INFO: valid events total: 176768
[18:54:37.639] <TB1> INFO: empty events: 7863
[18:54:37.639] <TB1> INFO: valid events with pixels: 168905
[18:54:37.639] <TB1> INFO: valid pixel hits: 668084
[18:54:37.639] <TB1> INFO: Event errors: 0
[18:54:37.639] <TB1> INFO: start marker: 0
[18:54:37.639] <TB1> INFO: stop marker: 0
[18:54:37.639] <TB1> INFO: overflow: 0
[18:54:37.639] <TB1> INFO: invalid 5bit words: 0
[18:54:37.639] <TB1> INFO: invalid XOR eye diagram: 0
[18:54:37.639] <TB1> INFO: frame (failed synchr.): 0
[18:54:37.639] <TB1> INFO: idle data (no TBM trl): 0
[18:54:37.639] <TB1> INFO: no data (only TBM hdr): 0
[18:54:37.639] <TB1> INFO: TBM errors: 0
[18:54:37.639] <TB1> INFO: flawed TBM headers: 0
[18:54:37.639] <TB1> INFO: flawed TBM trailers: 0
[18:54:37.639] <TB1> INFO: event ID mismatches: 0
[18:54:37.639] <TB1> INFO: ROC errors: 0
[18:54:37.639] <TB1> INFO: missing ROC header(s): 0
[18:54:37.639] <TB1> INFO: misplaced readback start: 0
[18:54:37.639] <TB1> INFO: Pixel decoding errors: 0
[18:54:37.639] <TB1> INFO: pixel data incomplete: 0
[18:54:37.639] <TB1> INFO: pixel address: 0
[18:54:37.639] <TB1> INFO: pulse height fill bit: 0
[18:54:37.639] <TB1> INFO: buffer corruption: 0
[18:54:37.639] <TB1> INFO: enter test to run
[18:54:37.639] <TB1> INFO: test: exit no parameter change
[18:54:37.787] <TB1> QUIET: Connection to board 153 closed.
[18:54:37.866] <TB1> INFO: pXar: this is the end, my friend