[07:34:40.554] <TB3> INFO: *** Welcome to pxar ***
[07:34:40.554] <TB3> INFO: *** Today: 2016/10/07
[07:34:40.685] <TB3> INFO: *** Version: 0b00-dirty
[07:34:40.685] <TB3> INFO: readRocDacs: /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C15.dat
[07:34:40.687] <TB3> INFO: readTbmDacs: /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//tbmParameters_C0b.dat
[07:34:40.687] <TB3> INFO: readMaskFile: /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//defaultMaskFile.dat
[07:34:40.687] <TB3> INFO: readTrimFile: /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters_C15.dat
[07:34:40.779] <TB3> INFO: clk: 4
[07:34:40.779] <TB3> INFO: ctr: 4
[07:34:40.779] <TB3> INFO: sda: 19
[07:34:40.779] <TB3> INFO: tin: 9
[07:34:40.779] <TB3> INFO: level: 15
[07:34:40.779] <TB3> INFO: triggerdelay: 0
[07:34:40.779] <TB3> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[07:34:40.779] <TB3> INFO: Log level: INFO
[07:34:40.788] <TB3> INFO: Found DTB DTB_WZ4I6J
[07:34:40.801] <TB3> QUIET: Connection to board DTB_WZ4I6J opened.
[07:34:40.803] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 170
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WZ4I6J
MAC address: 40D8551180AA
Hostname: pixelDTB170
Comment:
------------------------------------------------------
[07:34:40.805] <TB3> INFO: RPC call hashes of host and DTB match: 486171790
[07:34:42.318] <TB3> INFO: DUT info:
[07:34:42.318] <TB3> INFO: The DUT currently contains the following objects:
[07:34:42.318] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[07:34:42.318] <TB3> INFO: TBM Core alpha (0): 7 registers set
[07:34:42.318] <TB3> INFO: TBM Core beta (1): 7 registers set
[07:34:42.318] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:34:42.318] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:42.318] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:42.318] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:42.318] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:42.318] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:42.318] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:42.318] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:42.318] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:42.318] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:42.318] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:42.318] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:42.318] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:42.318] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:42.318] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:42.318] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:42.318] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:34:42.719] <TB3> INFO: enter 'restricted' command line mode
[07:34:42.719] <TB3> INFO: enter test to run
[07:34:42.719] <TB3> INFO: test: pretest no parameter change
[07:34:42.719] <TB3> INFO: running: pretest
[07:34:42.723] <TB3> INFO: ----------------------------------------------------------------------
[07:34:42.723] <TB3> INFO: PixTestPretest::programROC()
[07:34:42.723] <TB3> INFO: ----------------------------------------------------------------------
[07:35:00.741] <TB3> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[07:35:00.741] <TB3> INFO: IA differences per ROC: 18.5 18.5 18.5 20.1 19.3 18.5 19.3 17.7 19.3 21.7 20.1 17.7 18.5 20.1 19.3 20.1
[07:35:00.853] <TB3> INFO: enter test to run
[07:35:00.854] <TB3> INFO: test: pretest no parameter change
[07:35:00.854] <TB3> INFO: running: pretest
[07:35:00.855] <TB3> INFO: ----------------------------------------------------------------------
[07:35:00.855] <TB3> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[07:35:00.855] <TB3> INFO: ----------------------------------------------------------------------
[07:35:22.176] <TB3> INFO: PixTestPretest::setVana() done, Module Ia 380.2 mA = 23.7625 mA/ROC
[07:35:22.176] <TB3> INFO: i(loss) [mA/ROC]: 19.3 18.4 18.4 19.3 19.3 19.3 19.3 19.3 18.4 18.4 18.4 18.4 18.4 18.4 18.4 18.4
[07:35:22.222] <TB3> INFO: enter test to run
[07:35:22.222] <TB3> INFO: test: pretest no parameter change
[07:35:22.222] <TB3> INFO: running: pretest
[07:35:22.222] <TB3> INFO: ----------------------------------------------------------------------
[07:35:22.223] <TB3> INFO: PixTestPretest::findTiming()
[07:35:22.223] <TB3> INFO: ----------------------------------------------------------------------
[07:35:22.223] <TB3> INFO: PixTestCmd::init()

[07:36:54.302] <TB3> INFO: TBM phases: 160MHz: 0, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[07:36:54.302] <TB3> INFO: (success/tries = 100/100), width = 5
[07:36:54.303] <TB3> INFO: enter test to run
[07:36:54.303] <TB3> INFO: test: pretest no parameter change
[07:36:54.303] <TB3> INFO: running: pretest
[07:36:54.305] <TB3> INFO: ----------------------------------------------------------------------
[07:36:54.305] <TB3> INFO: PixTestPretest::findWorkingPixel()
[07:36:54.305] <TB3> INFO: ----------------------------------------------------------------------
[07:36:54.400] <TB3> INFO: Expecting 231680 events.


[07:37:03.581] <TB3> INFO: 231680 events read in total (8624ms).
[07:37:03.587] <TB3> INFO: Test took 9277ms.
[07:37:03.793] <TB3> INFO: Found working pixel in all ROCs: col/row = 12/22
[07:37:03.873] <TB3> INFO: enter test to run
[07:37:03.873] <TB3> INFO: test: pretest no parameter change
[07:37:03.873] <TB3> INFO: running: pretest
[07:37:03.874] <TB3> INFO: ----------------------------------------------------------------------
[07:37:03.874] <TB3> INFO: PixTestPretest::setVthrCompCalDel()
[07:37:03.874] <TB3> INFO: ----------------------------------------------------------------------
[07:37:03.976] <TB3> INFO: Expecting 231680 events.
[07:37:13.222] <TB3> INFO: 231680 events read in total (8689ms).
[07:37:13.229] <TB3> INFO: Test took 9349ms.
[07:37:13.476] <TB3> INFO: PixTestPretest::setVthrCompCalDel() done
[07:37:13.476] <TB3> INFO: CalDel: 143 123 144 138 149 144 139 134 144 201 142 139 126 150 143 154
[07:37:13.476] <TB3> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[07:37:13.520] <TB3> INFO: enter test to run
[07:37:13.520] <TB3> INFO: test: pretest no parameter change
[07:37:13.521] <TB3> INFO: running: pretest
[07:37:13.521] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C0.dat
[07:37:13.521] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C1.dat
[07:37:13.521] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C2.dat
[07:37:13.521] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C3.dat
[07:37:13.521] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C4.dat
[07:37:13.521] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C5.dat
[07:37:13.521] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C6.dat
[07:37:13.521] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C7.dat
[07:37:13.522] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C8.dat
[07:37:13.522] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C9.dat
[07:37:13.522] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C10.dat
[07:37:13.522] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C11.dat
[07:37:13.522] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C12.dat
[07:37:13.522] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C13.dat
[07:37:13.522] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C14.dat
[07:37:13.522] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters_C15.dat
[07:37:13.522] <TB3> INFO: enter test to run
[07:37:13.522] <TB3> INFO: test: fulltest no parameter change
[07:37:13.522] <TB3> INFO: running: fulltest
[07:37:13.523] <TB3> INFO: ######################################################################
[07:37:13.523] <TB3> INFO: PixTestFullTest::doTest()
[07:37:13.523] <TB3> INFO: ######################################################################
[07:37:13.524] <TB3> INFO: ######################################################################
[07:37:13.524] <TB3> INFO: PixTestAlive::doTest()
[07:37:13.524] <TB3> INFO: ######################################################################
[07:37:13.525] <TB3> INFO: ----------------------------------------------------------------------
[07:37:13.525] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:37:13.525] <TB3> INFO: ----------------------------------------------------------------------
[07:37:13.800] <TB3> INFO: Expecting 41600 events.
[07:37:17.220] <TB3> INFO: 41600 events read in total (2864ms).
[07:37:17.221] <TB3> INFO: Test took 3694ms.
[07:37:17.457] <TB3> INFO: PixTestAlive::aliveTest() done
[07:37:17.457] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 3 0 0
[07:37:17.459] <TB3> INFO: ----------------------------------------------------------------------
[07:37:17.459] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:37:17.459] <TB3> INFO: ----------------------------------------------------------------------
[07:37:17.725] <TB3> INFO: Expecting 41600 events.
[07:37:20.485] <TB3> INFO: 41600 events read in total (2203ms).
[07:37:20.485] <TB3> INFO: Test took 3024ms.
[07:37:20.486] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[07:37:20.738] <TB3> INFO: PixTestAlive::maskTest() done
[07:37:20.738] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:37:20.740] <TB3> INFO: ----------------------------------------------------------------------
[07:37:20.740] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[07:37:20.740] <TB3> INFO: ----------------------------------------------------------------------
[07:37:21.013] <TB3> INFO: Expecting 41600 events.
[07:37:24.418] <TB3> INFO: 41600 events read in total (2849ms).
[07:37:24.419] <TB3> INFO: Test took 3677ms.
[07:37:24.658] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[07:37:24.658] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[07:37:24.659] <TB3> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[07:37:24.659] <TB3> INFO: Decoding statistics:
[07:37:24.659] <TB3> INFO: General information:
[07:37:24.659] <TB3> INFO: 16bit words read: 0
[07:37:24.659] <TB3> INFO: valid events total: 0
[07:37:24.659] <TB3> INFO: empty events: 0
[07:37:24.659] <TB3> INFO: valid events with pixels: 0
[07:37:24.659] <TB3> INFO: valid pixel hits: 0
[07:37:24.659] <TB3> INFO: Event errors: 0
[07:37:24.659] <TB3> INFO: start marker: 0
[07:37:24.659] <TB3> INFO: stop marker: 0
[07:37:24.659] <TB3> INFO: overflow: 0
[07:37:24.659] <TB3> INFO: invalid 5bit words: 0
[07:37:24.659] <TB3> INFO: invalid XOR eye diagram: 0
[07:37:24.659] <TB3> INFO: frame (failed synchr.): 0
[07:37:24.659] <TB3> INFO: idle data (no TBM trl): 0
[07:37:24.659] <TB3> INFO: no data (only TBM hdr): 0
[07:37:24.659] <TB3> INFO: TBM errors: 0
[07:37:24.659] <TB3> INFO: flawed TBM headers: 0
[07:37:24.659] <TB3> INFO: flawed TBM trailers: 0
[07:37:24.659] <TB3> INFO: event ID mismatches: 0
[07:37:24.659] <TB3> INFO: ROC errors: 0
[07:37:24.659] <TB3> INFO: missing ROC header(s): 0
[07:37:24.659] <TB3> INFO: misplaced readback start: 0
[07:37:24.659] <TB3> INFO: Pixel decoding errors: 0
[07:37:24.659] <TB3> INFO: pixel data incomplete: 0
[07:37:24.659] <TB3> INFO: pixel address: 0
[07:37:24.659] <TB3> INFO: pulse height fill bit: 0
[07:37:24.659] <TB3> INFO: buffer corruption: 0
[07:37:24.667] <TB3> INFO: ######################################################################
[07:37:24.667] <TB3> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[07:37:24.667] <TB3> INFO: ######################################################################
[07:37:24.671] <TB3> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[07:37:24.681] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[07:37:24.681] <TB3> INFO: run 1 of 1
[07:37:24.944] <TB3> INFO: Expecting 3120000 events.
[07:38:02.575] <TB3> INFO: 871750 events read in total (37074ms).
[07:38:39.570] <TB3> INFO: 1726845 events read in total (74070ms).
[07:39:19.276] <TB3> INFO: 2593160 events read in total (113775ms).
[07:39:43.008] <TB3> INFO: 3120000 events read in total (137507ms).
[07:39:43.093] <TB3> INFO: Test took 138411ms.
[07:40:07.785] <TB3> INFO: PixTestBBMap::doTest() done, duration: 163 seconds
[07:40:07.785] <TB3> INFO: number of dead bumps (per ROC): 0 0 0 1 1 0 1 0 0 1 0 0 0 2 0 0
[07:40:07.785] <TB3> INFO: separation cut (per ROC): 75 84 85 96 87 84 86 89 67 82 92 84 69 88 89 82
[07:40:07.785] <TB3> INFO: Decoding statistics:
[07:40:07.785] <TB3> INFO: General information:
[07:40:07.785] <TB3> INFO: 16bit words read: 0
[07:40:07.785] <TB3> INFO: valid events total: 0
[07:40:07.785] <TB3> INFO: empty events: 0
[07:40:07.786] <TB3> INFO: valid events with pixels: 0
[07:40:07.786] <TB3> INFO: valid pixel hits: 0
[07:40:07.786] <TB3> INFO: Event errors: 0
[07:40:07.786] <TB3> INFO: start marker: 0
[07:40:07.786] <TB3> INFO: stop marker: 0
[07:40:07.786] <TB3> INFO: overflow: 0
[07:40:07.786] <TB3> INFO: invalid 5bit words: 0
[07:40:07.786] <TB3> INFO: invalid XOR eye diagram: 0
[07:40:07.786] <TB3> INFO: frame (failed synchr.): 0
[07:40:07.786] <TB3> INFO: idle data (no TBM trl): 0
[07:40:07.786] <TB3> INFO: no data (only TBM hdr): 0
[07:40:07.786] <TB3> INFO: TBM errors: 0
[07:40:07.786] <TB3> INFO: flawed TBM headers: 0
[07:40:07.786] <TB3> INFO: flawed TBM trailers: 0
[07:40:07.786] <TB3> INFO: event ID mismatches: 0
[07:40:07.786] <TB3> INFO: ROC errors: 0
[07:40:07.786] <TB3> INFO: missing ROC header(s): 0
[07:40:07.786] <TB3> INFO: misplaced readback start: 0
[07:40:07.786] <TB3> INFO: Pixel decoding errors: 0
[07:40:07.786] <TB3> INFO: pixel data incomplete: 0
[07:40:07.786] <TB3> INFO: pixel address: 0
[07:40:07.786] <TB3> INFO: pulse height fill bit: 0
[07:40:07.786] <TB3> INFO: buffer corruption: 0
[07:40:07.858] <TB3> INFO: ######################################################################
[07:40:07.858] <TB3> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[07:40:07.858] <TB3> INFO: ######################################################################
[07:40:07.858] <TB3> INFO: ----------------------------------------------------------------------
[07:40:07.858] <TB3> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[07:40:07.858] <TB3> INFO: ----------------------------------------------------------------------
[07:40:07.858] <TB3> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[07:40:07.867] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[07:40:07.867] <TB3> INFO: run 1 of 1
[07:40:08.131] <TB3> INFO: Expecting 36608000 events.
[07:40:36.778] <TB3> INFO: 813450 events read in total (28091ms).
[07:41:05.201] <TB3> INFO: 1613100 events read in total (56514ms).
[07:41:33.446] <TB3> INFO: 2412300 events read in total (84759ms).
[07:42:01.421] <TB3> INFO: 3210050 events read in total (112734ms).
[07:42:29.623] <TB3> INFO: 4010450 events read in total (140936ms).
[07:42:57.597] <TB3> INFO: 4810400 events read in total (168910ms).
[07:43:25.614] <TB3> INFO: 5608400 events read in total (196927ms).
[07:43:53.709] <TB3> INFO: 6405750 events read in total (225022ms).
[07:44:22.034] <TB3> INFO: 7202550 events read in total (253347ms).
[07:44:49.784] <TB3> INFO: 8000800 events read in total (281097ms).
[07:45:17.979] <TB3> INFO: 8798400 events read in total (309292ms).
[07:45:46.671] <TB3> INFO: 9596100 events read in total (337984ms).
[07:46:14.779] <TB3> INFO: 10392000 events read in total (366092ms).
[07:46:42.359] <TB3> INFO: 11189800 events read in total (393672ms).
[07:47:10.377] <TB3> INFO: 11988900 events read in total (421690ms).
[07:47:38.752] <TB3> INFO: 12785450 events read in total (450065ms).
[07:48:06.809] <TB3> INFO: 13583350 events read in total (478122ms).
[07:48:35.044] <TB3> INFO: 14379500 events read in total (506357ms).
[07:49:03.304] <TB3> INFO: 15176350 events read in total (534617ms).
[07:49:31.674] <TB3> INFO: 15971850 events read in total (562987ms).
[07:49:59.927] <TB3> INFO: 16769950 events read in total (591240ms).
[07:50:28.214] <TB3> INFO: 17568450 events read in total (619527ms).
[07:50:56.398] <TB3> INFO: 18363000 events read in total (647711ms).
[07:51:24.716] <TB3> INFO: 19154600 events read in total (676029ms).
[07:51:52.851] <TB3> INFO: 19945650 events read in total (704164ms).
[07:52:20.883] <TB3> INFO: 20736650 events read in total (732196ms).
[07:52:48.832] <TB3> INFO: 21528700 events read in total (760145ms).
[07:53:16.971] <TB3> INFO: 22318000 events read in total (788284ms).
[07:53:45.236] <TB3> INFO: 23107550 events read in total (816549ms).
[07:54:13.229] <TB3> INFO: 23897300 events read in total (844542ms).
[07:54:41.579] <TB3> INFO: 24688300 events read in total (872892ms).
[07:55:09.782] <TB3> INFO: 25477300 events read in total (901095ms).
[07:55:38.157] <TB3> INFO: 26267200 events read in total (929470ms).
[07:56:06.435] <TB3> INFO: 27055800 events read in total (957748ms).
[07:56:34.697] <TB3> INFO: 27843550 events read in total (986010ms).
[07:57:03.258] <TB3> INFO: 28632800 events read in total (1014571ms).
[07:57:31.644] <TB3> INFO: 29421850 events read in total (1042957ms).
[07:58:00.151] <TB3> INFO: 30211750 events read in total (1071464ms).
[07:58:28.100] <TB3> INFO: 30999400 events read in total (1099413ms).
[07:58:56.374] <TB3> INFO: 31788900 events read in total (1127687ms).
[07:59:24.983] <TB3> INFO: 32577300 events read in total (1156296ms).
[07:59:53.275] <TB3> INFO: 33367850 events read in total (1184588ms).
[08:00:21.631] <TB3> INFO: 34158300 events read in total (1212944ms).
[08:00:50.219] <TB3> INFO: 34947700 events read in total (1241532ms).
[08:01:18.177] <TB3> INFO: 35736200 events read in total (1269490ms).
[08:01:46.940] <TB3> INFO: 36537750 events read in total (1298253ms).
[08:01:49.725] <TB3> INFO: 36608000 events read in total (1301038ms).
[08:01:49.794] <TB3> INFO: Test took 1301927ms.
[08:01:50.471] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:01:52.343] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:01:54.210] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:01:55.992] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:01:57.862] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:01:59.607] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:02:01.467] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:02:03.247] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:02:05.050] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:02:06.911] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:02:08.678] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:02:10.470] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:02:12.243] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:02:14.018] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:02:15.697] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:02:17.378] <TB3> INFO: dumping ASCII scurve output file: SCurveData
[08:02:19.097] <TB3> INFO: PixTestScurves::scurves() done
[08:02:19.097] <TB3> INFO: Vcal mean: 79.67 82.55 83.80 92.58 81.34 77.74 86.76 88.09 75.47 84.83 87.58 79.00 74.05 87.39 87.20 84.76
[08:02:19.097] <TB3> INFO: Vcal RMS: 4.47 4.28 4.80 4.88 4.00 3.74 4.74 5.38 4.50 4.50 5.37 4.08 4.31 5.36 4.73 4.98
[08:02:19.097] <TB3> INFO: PixTestScurves::fullTest() done, duration: 1331 seconds
[08:02:19.097] <TB3> INFO: Decoding statistics:
[08:02:19.097] <TB3> INFO: General information:
[08:02:19.097] <TB3> INFO: 16bit words read: 0
[08:02:19.097] <TB3> INFO: valid events total: 0
[08:02:19.097] <TB3> INFO: empty events: 0
[08:02:19.097] <TB3> INFO: valid events with pixels: 0
[08:02:19.097] <TB3> INFO: valid pixel hits: 0
[08:02:19.097] <TB3> INFO: Event errors: 0
[08:02:19.097] <TB3> INFO: start marker: 0
[08:02:19.097] <TB3> INFO: stop marker: 0
[08:02:19.097] <TB3> INFO: overflow: 0
[08:02:19.097] <TB3> INFO: invalid 5bit words: 0
[08:02:19.097] <TB3> INFO: invalid XOR eye diagram: 0
[08:02:19.097] <TB3> INFO: frame (failed synchr.): 0
[08:02:19.097] <TB3> INFO: idle data (no TBM trl): 0
[08:02:19.097] <TB3> INFO: no data (only TBM hdr): 0
[08:02:19.098] <TB3> INFO: TBM errors: 0
[08:02:19.098] <TB3> INFO: flawed TBM headers: 0
[08:02:19.098] <TB3> INFO: flawed TBM trailers: 0
[08:02:19.098] <TB3> INFO: event ID mismatches: 0
[08:02:19.098] <TB3> INFO: ROC errors: 0
[08:02:19.098] <TB3> INFO: missing ROC header(s): 0
[08:02:19.098] <TB3> INFO: misplaced readback start: 0
[08:02:19.098] <TB3> INFO: Pixel decoding errors: 0
[08:02:19.098] <TB3> INFO: pixel data incomplete: 0
[08:02:19.098] <TB3> INFO: pixel address: 0
[08:02:19.098] <TB3> INFO: pulse height fill bit: 0
[08:02:19.098] <TB3> INFO: buffer corruption: 0
[08:02:19.169] <TB3> INFO: ######################################################################
[08:02:19.169] <TB3> INFO: PixTestTrim::doTest()
[08:02:19.169] <TB3> INFO: ######################################################################
[08:02:19.170] <TB3> INFO: ----------------------------------------------------------------------
[08:02:19.170] <TB3> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[08:02:19.170] <TB3> INFO: ----------------------------------------------------------------------
[08:02:19.250] <TB3> INFO: ---> VthrComp thr map (minimal VthrComp)
[08:02:19.250] <TB3> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[08:02:19.258] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[08:02:19.258] <TB3> INFO: run 1 of 1
[08:02:19.520] <TB3> INFO: Expecting 6281600 events.
[08:02:55.907] <TB3> INFO: 1097140 events read in total (35830ms).
[08:03:31.389] <TB3> INFO: 2188560 events read in total (71312ms).
[08:04:06.824] <TB3> INFO: 3273440 events read in total (106747ms).
[08:04:42.434] <TB3> INFO: 4365700 events read in total (142357ms).
[08:05:18.184] <TB3> INFO: 5462590 events read in total (178108ms).
[08:05:44.804] <TB3> INFO: 6281600 events read in total (204727ms).
[08:05:44.852] <TB3> INFO: Test took 205593ms.
[08:06:04.718] <TB3> INFO: ROC 0 VthrComp = 85
[08:06:04.719] <TB3> INFO: ROC 1 VthrComp = 90
[08:06:04.719] <TB3> INFO: ROC 2 VthrComp = 90
[08:06:04.719] <TB3> INFO: ROC 3 VthrComp = 100
[08:06:04.719] <TB3> INFO: ROC 4 VthrComp = 94
[08:06:04.719] <TB3> INFO: ROC 5 VthrComp = 87
[08:06:04.719] <TB3> INFO: ROC 6 VthrComp = 91
[08:06:04.719] <TB3> INFO: ROC 7 VthrComp = 93
[08:06:04.719] <TB3> INFO: ROC 8 VthrComp = 81
[08:06:04.719] <TB3> INFO: ROC 9 VthrComp = 88
[08:06:04.720] <TB3> INFO: ROC 10 VthrComp = 92
[08:06:04.720] <TB3> INFO: ROC 11 VthrComp = 85
[08:06:04.720] <TB3> INFO: ROC 12 VthrComp = 78
[08:06:04.720] <TB3> INFO: ROC 13 VthrComp = 95
[08:06:04.720] <TB3> INFO: ROC 14 VthrComp = 92
[08:06:04.720] <TB3> INFO: ROC 15 VthrComp = 91
[08:06:04.720] <TB3> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[08:06:04.721] <TB3> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[08:06:04.729] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[08:06:04.729] <TB3> INFO: run 1 of 1
[08:06:04.993] <TB3> INFO: Expecting 6281600 events.
[08:06:36.276] <TB3> INFO: 755790 events read in total (30726ms).
[08:07:07.028] <TB3> INFO: 1510770 events read in total (61478ms).
[08:07:37.474] <TB3> INFO: 2266020 events read in total (91924ms).
[08:08:08.169] <TB3> INFO: 3021920 events read in total (122619ms).
[08:08:39.018] <TB3> INFO: 3773270 events read in total (153468ms).
[08:09:09.733] <TB3> INFO: 4521740 events read in total (184183ms).
[08:09:41.158] <TB3> INFO: 5268840 events read in total (215608ms).
[08:10:12.929] <TB3> INFO: 6015820 events read in total (247379ms).
[08:10:24.647] <TB3> INFO: 6281600 events read in total (259097ms).
[08:10:24.701] <TB3> INFO: Test took 259972ms.
[08:10:49.132] <TB3> INFO: roc 0 with ID = 0 has maximal Vcal 57.8931 for pixel 18/20 mean/min/max = 45.2385/32.4882/57.9888
[08:10:49.133] <TB3> INFO: roc 1 with ID = 1 has maximal Vcal 55.8216 for pixel 14/77 mean/min/max = 44.1811/32.4527/55.9094
[08:10:49.133] <TB3> INFO: roc 2 with ID = 2 has maximal Vcal 57.7448 for pixel 21/73 mean/min/max = 45.2796/32.6018/57.9573
[08:10:49.133] <TB3> INFO: roc 3 with ID = 3 has maximal Vcal 57.1955 for pixel 37/6 mean/min/max = 44.725/32.2205/57.2294
[08:10:49.134] <TB3> INFO: roc 4 with ID = 4 has maximal Vcal 54.75 for pixel 1/15 mean/min/max = 43.8192/32.3499/55.2885
[08:10:49.134] <TB3> INFO: roc 5 with ID = 5 has maximal Vcal 53.9509 for pixel 22/39 mean/min/max = 43.284/32.312/54.256
[08:10:49.135] <TB3> INFO: roc 6 with ID = 6 has maximal Vcal 57.288 for pixel 0/5 mean/min/max = 45.1825/33.0695/57.2954
[08:10:49.135] <TB3> INFO: roc 7 with ID = 7 has maximal Vcal 56.9655 for pixel 25/66 mean/min/max = 44.8449/32.5399/57.15
[08:10:49.135] <TB3> INFO: roc 8 with ID = 8 has maximal Vcal 58.1422 for pixel 0/3 mean/min/max = 45.2862/32.3807/58.1917
[08:10:49.136] <TB3> INFO: roc 9 with ID = 9 has maximal Vcal 56.8725 for pixel 11/12 mean/min/max = 44.9596/33.0079/56.9114
[08:10:49.136] <TB3> INFO: roc 10 with ID = 10 has maximal Vcal 60.3943 for pixel 4/43 mean/min/max = 46.2536/32.0747/60.4325
[08:10:49.136] <TB3> INFO: roc 11 with ID = 11 has maximal Vcal 55.5378 for pixel 21/79 mean/min/max = 43.8885/31.915/55.8621
[08:10:49.137] <TB3> INFO: roc 12 with ID = 12 has maximal Vcal 57.8634 for pixel 23/63 mean/min/max = 46.3768/34.688/58.0657
[08:10:49.137] <TB3> INFO: roc 13 with ID = 13 has maximal Vcal 56.4085 for pixel 18/65 mean/min/max = 43.8642/31.2917/56.4366
[08:10:49.137] <TB3> INFO: roc 14 with ID = 14 has maximal Vcal 57.2772 for pixel 51/2 mean/min/max = 44.774/32.2076/57.3405
[08:10:49.138] <TB3> INFO: roc 15 with ID = 15 has maximal Vcal 58.4116 for pixel 5/45 mean/min/max = 45.1835/31.8034/58.5636
[08:10:49.138] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:10:49.228] <TB3> INFO: Expecting 514560 events.
[08:11:00.129] <TB3> INFO: 514560 events read in total (10345ms).
[08:11:00.136] <TB3> INFO: Expecting 514560 events.
[08:11:11.017] <TB3> INFO: 514560 events read in total (10479ms).
[08:11:11.027] <TB3> INFO: Expecting 514560 events.
[08:11:21.843] <TB3> INFO: 514560 events read in total (10414ms).
[08:11:21.855] <TB3> INFO: Expecting 514560 events.
[08:11:32.611] <TB3> INFO: 514560 events read in total (10353ms).
[08:11:32.627] <TB3> INFO: Expecting 514560 events.
[08:11:43.370] <TB3> INFO: 514560 events read in total (10340ms).
[08:11:43.388] <TB3> INFO: Expecting 514560 events.
[08:11:54.193] <TB3> INFO: 514560 events read in total (10403ms).
[08:11:54.215] <TB3> INFO: Expecting 514560 events.
[08:12:04.966] <TB3> INFO: 514560 events read in total (10349ms).
[08:12:04.989] <TB3> INFO: Expecting 514560 events.
[08:12:15.794] <TB3> INFO: 514560 events read in total (10403ms).
[08:12:15.821] <TB3> INFO: Expecting 514560 events.
[08:12:26.618] <TB3> INFO: 514560 events read in total (10395ms).
[08:12:26.648] <TB3> INFO: Expecting 514560 events.
[08:12:37.500] <TB3> INFO: 514560 events read in total (10449ms).
[08:12:37.531] <TB3> INFO: Expecting 514560 events.
[08:12:48.392] <TB3> INFO: 514560 events read in total (10458ms).
[08:12:48.427] <TB3> INFO: Expecting 514560 events.
[08:12:59.395] <TB3> INFO: 514560 events read in total (10565ms).
[08:12:59.436] <TB3> INFO: Expecting 514560 events.
[08:13:10.256] <TB3> INFO: 514560 events read in total (10417ms).
[08:13:10.294] <TB3> INFO: Expecting 514560 events.
[08:13:21.181] <TB3> INFO: 514560 events read in total (10485ms).
[08:13:21.228] <TB3> INFO: Expecting 514560 events.
[08:13:32.012] <TB3> INFO: 514560 events read in total (10381ms).
[08:13:32.054] <TB3> INFO: Expecting 514560 events.
[08:13:42.697] <TB3> INFO: 514560 events read in total (10240ms).
[08:13:42.743] <TB3> INFO: Test took 173605ms.
[08:13:43.844] <TB3> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[08:13:43.853] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[08:13:43.855] <TB3> INFO: run 1 of 1
[08:13:44.121] <TB3> INFO: Expecting 6281600 events.
[08:14:15.267] <TB3> INFO: 746670 events read in total (30589ms).
[08:14:45.809] <TB3> INFO: 1491840 events read in total (61131ms).
[08:15:16.008] <TB3> INFO: 2238130 events read in total (91330ms).
[08:15:46.239] <TB3> INFO: 2984650 events read in total (121561ms).
[08:16:16.545] <TB3> INFO: 3726340 events read in total (151867ms).
[08:16:46.967] <TB3> INFO: 4464120 events read in total (182289ms).
[08:17:17.101] <TB3> INFO: 5199770 events read in total (212423ms).
[08:17:47.719] <TB3> INFO: 5935210 events read in total (243041ms).
[08:18:02.172] <TB3> INFO: 6281600 events read in total (257494ms).
[08:18:02.229] <TB3> INFO: Test took 258373ms.
[08:18:26.806] <TB3> INFO: ---> TrimStepCorr4 extremal thresholds: 6.539208 .. 48.958711
[08:18:27.075] <TB3> INFO: Expecting 208000 events.
[08:18:36.724] <TB3> INFO: 208000 events read in total (9092ms).
[08:18:36.725] <TB3> INFO: Test took 9915ms.
[08:18:36.818] <TB3> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 6 .. 58 (-1/-1) hits flags = 528 (plus default)
[08:18:36.827] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[08:18:36.827] <TB3> INFO: run 1 of 1
[08:18:37.092] <TB3> INFO: Expecting 2204800 events.
[08:19:11.322] <TB3> INFO: 935980 events read in total (33673ms).
[08:19:45.207] <TB3> INFO: 1863240 events read in total (67558ms).
[08:19:57.992] <TB3> INFO: 2204800 events read in total (80343ms).
[08:19:58.014] <TB3> INFO: Test took 81188ms.
[08:20:13.470] <TB3> INFO: ---> TrimStepCorr2 extremal thresholds: 22.279144 .. 44.511672
[08:20:13.737] <TB3> INFO: Expecting 208000 events.
[08:20:23.703] <TB3> INFO: 208000 events read in total (9409ms).
[08:20:23.704] <TB3> INFO: Test took 10232ms.
[08:20:23.804] <TB3> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 12 .. 54 (-1/-1) hits flags = 528 (plus default)
[08:20:23.813] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[08:20:23.813] <TB3> INFO: run 1 of 1
[08:20:24.079] <TB3> INFO: Expecting 1788800 events.
[08:20:58.474] <TB3> INFO: 922300 events read in total (33838ms).
[08:21:30.529] <TB3> INFO: 1788800 events read in total (65893ms).
[08:21:30.550] <TB3> INFO: Test took 66737ms.
[08:21:44.745] <TB3> INFO: ---> TrimStepCorr1a extremal thresholds: 19.736364 .. 42.172387
[08:21:45.018] <TB3> INFO: Expecting 208000 events.
[08:21:55.257] <TB3> INFO: 208000 events read in total (9683ms).
[08:21:55.258] <TB3> INFO: Test took 10511ms.
[08:21:55.361] <TB3> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 9 .. 52 (-1/-1) hits flags = 528 (plus default)
[08:21:55.370] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[08:21:55.370] <TB3> INFO: run 1 of 1
[08:21:55.661] <TB3> INFO: Expecting 1830400 events.
[08:22:31.132] <TB3> INFO: 960220 events read in total (34914ms).
[08:23:02.185] <TB3> INFO: 1830400 events read in total (65967ms).
[08:23:02.205] <TB3> INFO: Test took 66835ms.
[08:23:15.540] <TB3> INFO: ---> TrimStepCorr1b extremal thresholds: 20.645669 .. 41.295954
[08:23:15.806] <TB3> INFO: Expecting 208000 events.
[08:23:25.602] <TB3> INFO: 208000 events read in total (9239ms).
[08:23:25.603] <TB3> INFO: Test took 10061ms.
[08:23:25.692] <TB3> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 10 .. 51 (-1/-1) hits flags = 528 (plus default)
[08:23:25.701] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[08:23:25.701] <TB3> INFO: run 1 of 1
[08:23:25.966] <TB3> INFO: Expecting 1747200 events.
[08:24:01.016] <TB3> INFO: 965100 events read in total (34493ms).
[08:24:29.596] <TB3> INFO: 1747200 events read in total (63073ms).
[08:24:29.615] <TB3> INFO: Test took 63914ms.
[08:24:43.493] <TB3> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[08:24:43.493] <TB3> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[08:24:43.502] <TB3> INFO: dacScan split into 1 runs with ntrig = 10
[08:24:43.502] <TB3> INFO: run 1 of 1
[08:24:43.773] <TB3> INFO: Expecting 1705600 events.
[08:25:18.253] <TB3> INFO: 893630 events read in total (33924ms).
[08:25:48.744] <TB3> INFO: 1705600 events read in total (64415ms).
[08:25:48.763] <TB3> INFO: Test took 65261ms.
[08:26:02.625] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:26:02.626] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:26:02.626] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:26:02.626] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:26:02.626] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:26:02.626] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:26:02.626] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:26:02.626] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:26:02.626] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:26:02.626] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:26:02.626] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:26:02.627] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:26:02.627] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:26:02.627] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:26:02.627] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:26:02.627] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:26:02.627] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C0.dat
[08:26:02.633] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C1.dat
[08:26:02.639] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C2.dat
[08:26:02.646] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C3.dat
[08:26:02.652] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C4.dat
[08:26:02.658] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C5.dat
[08:26:02.664] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C6.dat
[08:26:02.670] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C7.dat
[08:26:02.676] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C8.dat
[08:26:02.682] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C9.dat
[08:26:02.688] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C10.dat
[08:26:02.696] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C11.dat
[08:26:02.704] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C12.dat
[08:26:02.712] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C13.dat
[08:26:02.719] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C14.dat
[08:26:02.725] <TB3> INFO: write trim parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//trimParameters35_C15.dat
[08:26:02.732] <TB3> INFO: PixTestTrim::trimTest() done
[08:26:02.732] <TB3> INFO: vtrim: 104 111 98 99 95 104 87 105 100 109 109 104 99 107 89 98
[08:26:02.732] <TB3> INFO: vthrcomp: 85 90 90 100 94 87 91 93 81 88 92 85 78 95 92 91
[08:26:02.732] <TB3> INFO: vcal mean: 34.98 34.95 34.97 34.96 34.93 34.96 34.93 34.98 34.94 34.98 34.96 34.91 34.98 34.88 34.99 34.95
[08:26:02.732] <TB3> INFO: vcal RMS: 0.83 0.81 0.80 0.84 0.79 0.78 0.79 0.84 0.80 0.81 0.85 0.83 0.78 1.26 0.80 0.91
[08:26:02.732] <TB3> INFO: bits mean: 9.52 10.00 9.31 9.78 10.03 10.34 8.91 9.30 9.09 9.64 9.48 10.00 8.74 10.20 9.09 9.83
[08:26:02.732] <TB3> INFO: bits RMS: 2.63 2.45 2.66 2.54 2.50 2.36 2.80 2.72 2.81 2.51 2.63 2.54 2.54 2.51 2.90 2.56
[08:26:02.740] <TB3> INFO: ----------------------------------------------------------------------
[08:26:02.741] <TB3> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[08:26:02.741] <TB3> INFO: ----------------------------------------------------------------------
[08:26:02.745] <TB3> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[08:26:02.753] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[08:26:02.753] <TB3> INFO: run 1 of 1
[08:26:03.022] <TB3> INFO: Expecting 4160000 events.
[08:26:40.991] <TB3> INFO: 915660 events read in total (37412ms).
[08:27:18.575] <TB3> INFO: 1826385 events read in total (74996ms).
[08:27:55.911] <TB3> INFO: 2730815 events read in total (112332ms).
[08:28:32.903] <TB3> INFO: 3632710 events read in total (149324ms).
[08:28:55.367] <TB3> INFO: 4160000 events read in total (171788ms).
[08:28:55.420] <TB3> INFO: Test took 172667ms.
[08:29:23.728] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 154 (-1/-1) hits flags = 528 (plus default)
[08:29:23.736] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[08:29:23.736] <TB3> INFO: run 1 of 1
[08:29:24.000] <TB3> INFO: Expecting 3224000 events.
[08:30:03.254] <TB3> INFO: 983680 events read in total (38697ms).
[08:30:42.696] <TB3> INFO: 1957515 events read in total (78139ms).
[08:31:21.543] <TB3> INFO: 2922525 events read in total (116986ms).
[08:31:33.686] <TB3> INFO: 3224000 events read in total (129129ms).
[08:31:33.730] <TB3> INFO: Test took 129995ms.
[08:31:57.824] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 139 (-1/-1) hits flags = 528 (plus default)
[08:31:57.833] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[08:31:57.833] <TB3> INFO: run 1 of 1
[08:31:58.103] <TB3> INFO: Expecting 2912000 events.
[08:32:38.578] <TB3> INFO: 1037155 events read in total (39919ms).
[08:33:18.115] <TB3> INFO: 2058020 events read in total (79456ms).
[08:33:52.657] <TB3> INFO: 2912000 events read in total (113998ms).
[08:33:52.699] <TB3> INFO: Test took 114866ms.
[08:34:16.859] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 139 (-1/-1) hits flags = 528 (plus default)
[08:34:16.867] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[08:34:16.872] <TB3> INFO: run 1 of 1
[08:34:17.157] <TB3> INFO: Expecting 2912000 events.
[08:34:57.006] <TB3> INFO: 1035815 events read in total (39292ms).
[08:35:36.469] <TB3> INFO: 2056275 events read in total (78755ms).
[08:36:11.394] <TB3> INFO: 2912000 events read in total (113680ms).
[08:36:11.436] <TB3> INFO: Test took 114564ms.
[08:36:34.280] <TB3> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 140 (-1/-1) hits flags = 528 (plus default)
[08:36:34.289] <TB3> INFO: dacScan split into 1 runs with ntrig = 5
[08:36:34.289] <TB3> INFO: run 1 of 1
[08:36:34.559] <TB3> INFO: Expecting 2932800 events.
[08:37:14.611] <TB3> INFO: 1031495 events read in total (39495ms).
[08:37:53.593] <TB3> INFO: 2047335 events read in total (78477ms).
[08:38:30.420] <TB3> INFO: 2932800 events read in total (115304ms).
[08:38:30.467] <TB3> INFO: Test took 116179ms.
[08:38:56.622] <TB3> INFO: PixTestTrim::trimBitTest() done
[08:38:56.623] <TB3> INFO: PixTestTrim::doTest() done, duration: 2197 seconds
[08:38:56.623] <TB3> INFO: Decoding statistics:
[08:38:56.623] <TB3> INFO: General information:
[08:38:56.623] <TB3> INFO: 16bit words read: 0
[08:38:56.623] <TB3> INFO: valid events total: 0
[08:38:56.623] <TB3> INFO: empty events: 0
[08:38:56.623] <TB3> INFO: valid events with pixels: 0
[08:38:56.623] <TB3> INFO: valid pixel hits: 0
[08:38:56.624] <TB3> INFO: Event errors: 0
[08:38:56.624] <TB3> INFO: start marker: 0
[08:38:56.624] <TB3> INFO: stop marker: 0
[08:38:56.624] <TB3> INFO: overflow: 0
[08:38:56.624] <TB3> INFO: invalid 5bit words: 0
[08:38:56.624] <TB3> INFO: invalid XOR eye diagram: 0
[08:38:56.624] <TB3> INFO: frame (failed synchr.): 0
[08:38:56.624] <TB3> INFO: idle data (no TBM trl): 0
[08:38:56.624] <TB3> INFO: no data (only TBM hdr): 0
[08:38:56.624] <TB3> INFO: TBM errors: 0
[08:38:56.624] <TB3> INFO: flawed TBM headers: 0
[08:38:56.624] <TB3> INFO: flawed TBM trailers: 0
[08:38:56.624] <TB3> INFO: event ID mismatches: 0
[08:38:56.624] <TB3> INFO: ROC errors: 0
[08:38:56.624] <TB3> INFO: missing ROC header(s): 0
[08:38:56.624] <TB3> INFO: misplaced readback start: 0
[08:38:56.624] <TB3> INFO: Pixel decoding errors: 0
[08:38:56.624] <TB3> INFO: pixel data incomplete: 0
[08:38:56.624] <TB3> INFO: pixel address: 0
[08:38:56.624] <TB3> INFO: pulse height fill bit: 0
[08:38:56.624] <TB3> INFO: buffer corruption: 0
[08:38:57.323] <TB3> INFO: ######################################################################
[08:38:57.323] <TB3> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[08:38:57.323] <TB3> INFO: ######################################################################
[08:38:57.636] <TB3> INFO: Expecting 41600 events.
[08:39:01.096] <TB3> INFO: 41600 events read in total (2903ms).
[08:39:01.097] <TB3> INFO: Test took 3772ms.
[08:39:01.583] <TB3> INFO: Expecting 41600 events.
[08:39:05.050] <TB3> INFO: 41600 events read in total (2910ms).
[08:39:05.051] <TB3> INFO: Test took 3771ms.
[08:39:05.339] <TB3> INFO: Expecting 41600 events.
[08:39:08.984] <TB3> INFO: 41600 events read in total (3089ms).
[08:39:08.984] <TB3> INFO: Test took 3911ms.
[08:39:09.241] <TB3> INFO: Expecting 2560 events.
[08:39:10.100] <TB3> INFO: 2560 events read in total (302ms).
[08:39:10.100] <TB3> INFO: Test took 1102ms.
[08:39:10.406] <TB3> INFO: Expecting 2560 events.
[08:39:11.261] <TB3> INFO: 2560 events read in total (298ms).
[08:39:11.261] <TB3> INFO: Test took 1159ms.
[08:39:11.568] <TB3> INFO: Expecting 2560 events.
[08:39:12.421] <TB3> INFO: 2560 events read in total (296ms).
[08:39:12.421] <TB3> INFO: Test took 1158ms.
[08:39:12.727] <TB3> INFO: Expecting 2560 events.
[08:39:13.586] <TB3> INFO: 2560 events read in total (302ms).
[08:39:13.586] <TB3> INFO: Test took 1163ms.
[08:39:13.892] <TB3> INFO: Expecting 2560 events.
[08:39:14.747] <TB3> INFO: 2560 events read in total (298ms).
[08:39:14.747] <TB3> INFO: Test took 1161ms.
[08:39:15.053] <TB3> INFO: Expecting 2560 events.
[08:39:15.907] <TB3> INFO: 2560 events read in total (297ms).
[08:39:15.907] <TB3> INFO: Test took 1159ms.
[08:39:16.214] <TB3> INFO: Expecting 2560 events.
[08:39:17.070] <TB3> INFO: 2560 events read in total (300ms).
[08:39:17.070] <TB3> INFO: Test took 1162ms.
[08:39:17.376] <TB3> INFO: Expecting 2560 events.
[08:39:18.235] <TB3> INFO: 2560 events read in total (302ms).
[08:39:18.235] <TB3> INFO: Test took 1164ms.
[08:39:18.542] <TB3> INFO: Expecting 2560 events.
[08:39:19.407] <TB3> INFO: 2560 events read in total (297ms).
[08:39:19.407] <TB3> INFO: Test took 1171ms.
[08:39:19.713] <TB3> INFO: Expecting 2560 events.
[08:39:20.570] <TB3> INFO: 2560 events read in total (301ms).
[08:39:20.570] <TB3> INFO: Test took 1163ms.
[08:39:20.879] <TB3> INFO: Expecting 2560 events.
[08:39:21.733] <TB3> INFO: 2560 events read in total (297ms).
[08:39:21.733] <TB3> INFO: Test took 1163ms.
[08:39:22.039] <TB3> INFO: Expecting 2560 events.
[08:39:22.897] <TB3> INFO: 2560 events read in total (301ms).
[08:39:22.897] <TB3> INFO: Test took 1163ms.
[08:39:23.204] <TB3> INFO: Expecting 2560 events.
[08:39:24.059] <TB3> INFO: 2560 events read in total (299ms).
[08:39:24.060] <TB3> INFO: Test took 1162ms.
[08:39:24.366] <TB3> INFO: Expecting 2560 events.
[08:39:25.224] <TB3> INFO: 2560 events read in total (301ms).
[08:39:25.224] <TB3> INFO: Test took 1164ms.
[08:39:25.530] <TB3> INFO: Expecting 2560 events.
[08:39:26.383] <TB3> INFO: 2560 events read in total (296ms).
[08:39:26.384] <TB3> INFO: Test took 1160ms.
[08:39:26.690] <TB3> INFO: Expecting 2560 events.
[08:39:27.545] <TB3> INFO: 2560 events read in total (298ms).
[08:39:27.545] <TB3> INFO: Test took 1161ms.
[08:39:27.551] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:39:27.852] <TB3> INFO: Expecting 655360 events.
[08:39:41.942] <TB3> INFO: 655360 events read in total (13533ms).
[08:39:41.953] <TB3> INFO: Expecting 655360 events.
[08:39:55.793] <TB3> INFO: 655360 events read in total (13438ms).
[08:39:55.809] <TB3> INFO: Expecting 655360 events.
[08:40:09.712] <TB3> INFO: 655360 events read in total (13501ms).
[08:40:09.736] <TB3> INFO: Expecting 655360 events.
[08:40:23.612] <TB3> INFO: 655360 events read in total (13474ms).
[08:40:23.635] <TB3> INFO: Expecting 655360 events.
[08:40:37.419] <TB3> INFO: 655360 events read in total (13381ms).
[08:40:37.449] <TB3> INFO: Expecting 655360 events.
[08:40:51.416] <TB3> INFO: 655360 events read in total (13565ms).
[08:40:51.447] <TB3> INFO: Expecting 655360 events.
[08:41:05.307] <TB3> INFO: 655360 events read in total (13458ms).
[08:41:05.344] <TB3> INFO: Expecting 655360 events.
[08:41:19.232] <TB3> INFO: 655360 events read in total (13485ms).
[08:41:19.276] <TB3> INFO: Expecting 655360 events.
[08:41:33.087] <TB3> INFO: 655360 events read in total (13408ms).
[08:41:33.132] <TB3> INFO: Expecting 655360 events.
[08:41:46.928] <TB3> INFO: 655360 events read in total (13394ms).
[08:41:46.978] <TB3> INFO: Expecting 655360 events.
[08:42:00.673] <TB3> INFO: 655360 events read in total (13293ms).
[08:42:00.726] <TB3> INFO: Expecting 655360 events.
[08:42:14.505] <TB3> INFO: 655360 events read in total (13377ms).
[08:42:14.563] <TB3> INFO: Expecting 655360 events.
[08:42:28.348] <TB3> INFO: 655360 events read in total (13382ms).
[08:42:28.411] <TB3> INFO: Expecting 655360 events.
[08:42:42.316] <TB3> INFO: 655360 events read in total (13503ms).
[08:42:42.419] <TB3> INFO: Expecting 655360 events.
[08:42:56.300] <TB3> INFO: 655360 events read in total (13479ms).
[08:42:56.376] <TB3> INFO: Expecting 655360 events.
[08:43:10.222] <TB3> INFO: 655360 events read in total (13444ms).
[08:43:10.322] <TB3> INFO: Test took 222771ms.
[08:43:10.409] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:43:10.636] <TB3> INFO: Expecting 655360 events.
[08:43:24.593] <TB3> INFO: 655360 events read in total (13401ms).
[08:43:24.604] <TB3> INFO: Expecting 655360 events.
[08:43:38.386] <TB3> INFO: 655360 events read in total (13376ms).
[08:43:38.402] <TB3> INFO: Expecting 655360 events.
[08:43:52.246] <TB3> INFO: 655360 events read in total (13442ms).
[08:43:52.267] <TB3> INFO: Expecting 655360 events.
[08:44:06.147] <TB3> INFO: 655360 events read in total (13478ms).
[08:44:06.171] <TB3> INFO: Expecting 655360 events.
[08:44:20.046] <TB3> INFO: 655360 events read in total (13472ms).
[08:44:20.073] <TB3> INFO: Expecting 655360 events.
[08:44:33.949] <TB3> INFO: 655360 events read in total (13473ms).
[08:44:33.981] <TB3> INFO: Expecting 655360 events.
[08:44:47.793] <TB3> INFO: 655360 events read in total (13409ms).
[08:44:47.835] <TB3> INFO: Expecting 655360 events.
[08:45:01.683] <TB3> INFO: 655360 events read in total (13445ms).
[08:45:01.726] <TB3> INFO: Expecting 655360 events.
[08:45:15.608] <TB3> INFO: 655360 events read in total (13480ms).
[08:45:15.655] <TB3> INFO: Expecting 655360 events.
[08:45:29.480] <TB3> INFO: 655360 events read in total (13423ms).
[08:45:29.534] <TB3> INFO: Expecting 655360 events.
[08:45:43.473] <TB3> INFO: 655360 events read in total (13536ms).
[08:45:43.530] <TB3> INFO: Expecting 655360 events.
[08:45:57.455] <TB3> INFO: 655360 events read in total (13523ms).
[08:45:57.513] <TB3> INFO: Expecting 655360 events.
[08:46:11.334] <TB3> INFO: 655360 events read in total (13418ms).
[08:46:11.397] <TB3> INFO: Expecting 655360 events.
[08:46:25.280] <TB3> INFO: 655360 events read in total (13480ms).
[08:46:25.384] <TB3> INFO: Expecting 655360 events.
[08:46:39.232] <TB3> INFO: 655360 events read in total (13446ms).
[08:46:39.319] <TB3> INFO: Expecting 655360 events.
[08:46:53.120] <TB3> INFO: 655360 events read in total (13398ms).
[08:46:53.195] <TB3> INFO: Test took 222786ms.
[08:46:53.408] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:53.417] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:53.425] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:53.433] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:53.442] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:53.450] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:53.461] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:53.469] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:53.478] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:53.486] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:53.493] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:53.500] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:53.507] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:53.515] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:53.523] <TB3> INFO: safety margin for low PH: adding 1, margin is now 21
[08:46:53.531] <TB3> INFO: safety margin for low PH: adding 2, margin is now 22
[08:46:53.538] <TB3> INFO: safety margin for low PH: adding 3, margin is now 23
[08:46:53.545] <TB3> INFO: safety margin for low PH: adding 4, margin is now 24
[08:46:53.552] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:53.559] <TB3> INFO: safety margin for low PH: adding 0, margin is now 20
[08:46:53.616] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C0.dat
[08:46:53.616] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C1.dat
[08:46:53.616] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C2.dat
[08:46:53.616] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C3.dat
[08:46:53.617] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C4.dat
[08:46:53.617] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C5.dat
[08:46:53.617] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C6.dat
[08:46:53.617] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C7.dat
[08:46:53.618] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C8.dat
[08:46:53.618] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C9.dat
[08:46:53.618] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C10.dat
[08:46:53.618] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C11.dat
[08:46:53.619] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C12.dat
[08:46:53.619] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C13.dat
[08:46:53.619] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C14.dat
[08:46:53.619] <TB3> INFO: write dac parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//dacParameters35_C15.dat
[08:46:53.900] <TB3> INFO: Expecting 41600 events.
[08:46:57.054] <TB3> INFO: 41600 events read in total (2597ms).
[08:46:57.054] <TB3> INFO: Test took 3430ms.
[08:46:57.512] <TB3> INFO: Expecting 41600 events.
[08:47:00.575] <TB3> INFO: 41600 events read in total (2506ms).
[08:47:00.576] <TB3> INFO: Test took 3334ms.
[08:47:01.044] <TB3> INFO: Expecting 41600 events.
[08:47:04.182] <TB3> INFO: 41600 events read in total (2581ms).
[08:47:04.183] <TB3> INFO: Test took 3408ms.
[08:47:04.375] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:04.465] <TB3> INFO: Expecting 2560 events.
[08:47:05.323] <TB3> INFO: 2560 events read in total (301ms).
[08:47:05.325] <TB3> INFO: Test took 950ms.
[08:47:05.330] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:05.630] <TB3> INFO: Expecting 2560 events.
[08:47:06.484] <TB3> INFO: 2560 events read in total (297ms).
[08:47:06.485] <TB3> INFO: Test took 1155ms.
[08:47:06.488] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:06.790] <TB3> INFO: Expecting 2560 events.
[08:47:07.647] <TB3> INFO: 2560 events read in total (298ms).
[08:47:07.647] <TB3> INFO: Test took 1159ms.
[08:47:07.651] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:07.954] <TB3> INFO: Expecting 2560 events.
[08:47:08.814] <TB3> INFO: 2560 events read in total (303ms).
[08:47:08.815] <TB3> INFO: Test took 1164ms.
[08:47:08.818] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:09.121] <TB3> INFO: Expecting 2560 events.
[08:47:09.984] <TB3> INFO: 2560 events read in total (306ms).
[08:47:09.984] <TB3> INFO: Test took 1166ms.
[08:47:09.988] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:10.291] <TB3> INFO: Expecting 2560 events.
[08:47:11.147] <TB3> INFO: 2560 events read in total (299ms).
[08:47:11.148] <TB3> INFO: Test took 1161ms.
[08:47:11.150] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:11.455] <TB3> INFO: Expecting 2560 events.
[08:47:12.315] <TB3> INFO: 2560 events read in total (303ms).
[08:47:12.316] <TB3> INFO: Test took 1166ms.
[08:47:12.320] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:12.620] <TB3> INFO: Expecting 2560 events.
[08:47:13.475] <TB3> INFO: 2560 events read in total (298ms).
[08:47:13.475] <TB3> INFO: Test took 1155ms.
[08:47:13.479] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:13.782] <TB3> INFO: Expecting 2560 events.
[08:47:14.635] <TB3> INFO: 2560 events read in total (297ms).
[08:47:14.635] <TB3> INFO: Test took 1157ms.
[08:47:14.638] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:14.941] <TB3> INFO: Expecting 2560 events.
[08:47:15.797] <TB3> INFO: 2560 events read in total (299ms).
[08:47:15.798] <TB3> INFO: Test took 1160ms.
[08:47:15.801] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:16.103] <TB3> INFO: Expecting 2560 events.
[08:47:16.963] <TB3> INFO: 2560 events read in total (303ms).
[08:47:16.963] <TB3> INFO: Test took 1162ms.
[08:47:16.965] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:17.270] <TB3> INFO: Expecting 2560 events.
[08:47:18.125] <TB3> INFO: 2560 events read in total (299ms).
[08:47:18.125] <TB3> INFO: Test took 1160ms.
[08:47:18.130] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:18.431] <TB3> INFO: Expecting 2560 events.
[08:47:19.285] <TB3> INFO: 2560 events read in total (298ms).
[08:47:19.285] <TB3> INFO: Test took 1155ms.
[08:47:19.288] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:19.592] <TB3> INFO: Expecting 2560 events.
[08:47:20.449] <TB3> INFO: 2560 events read in total (301ms).
[08:47:20.449] <TB3> INFO: Test took 1161ms.
[08:47:20.452] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:20.755] <TB3> INFO: Expecting 2560 events.
[08:47:21.609] <TB3> INFO: 2560 events read in total (297ms).
[08:47:21.609] <TB3> INFO: Test took 1157ms.
[08:47:21.612] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:21.917] <TB3> INFO: Expecting 2560 events.
[08:47:22.772] <TB3> INFO: 2560 events read in total (298ms).
[08:47:22.772] <TB3> INFO: Test took 1160ms.
[08:47:22.776] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:23.078] <TB3> INFO: Expecting 2560 events.
[08:47:23.937] <TB3> INFO: 2560 events read in total (302ms).
[08:47:23.937] <TB3> INFO: Test took 1161ms.
[08:47:23.941] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:24.243] <TB3> INFO: Expecting 2560 events.
[08:47:25.103] <TB3> INFO: 2560 events read in total (303ms).
[08:47:25.104] <TB3> INFO: Test took 1163ms.
[08:47:25.106] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:25.411] <TB3> INFO: Expecting 2560 events.
[08:47:26.273] <TB3> INFO: 2560 events read in total (306ms).
[08:47:26.274] <TB3> INFO: Test took 1168ms.
[08:47:26.279] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:26.579] <TB3> INFO: Expecting 2560 events.
[08:47:27.434] <TB3> INFO: 2560 events read in total (298ms).
[08:47:27.435] <TB3> INFO: Test took 1156ms.
[08:47:27.440] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:27.740] <TB3> INFO: Expecting 2560 events.
[08:47:28.597] <TB3> INFO: 2560 events read in total (300ms).
[08:47:28.597] <TB3> INFO: Test took 1157ms.
[08:47:28.600] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:28.904] <TB3> INFO: Expecting 2560 events.
[08:47:29.763] <TB3> INFO: 2560 events read in total (302ms).
[08:47:29.763] <TB3> INFO: Test took 1163ms.
[08:47:29.767] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:30.070] <TB3> INFO: Expecting 2560 events.
[08:47:30.927] <TB3> INFO: 2560 events read in total (300ms).
[08:47:30.927] <TB3> INFO: Test took 1160ms.
[08:47:30.931] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:31.233] <TB3> INFO: Expecting 2560 events.
[08:47:32.086] <TB3> INFO: 2560 events read in total (296ms).
[08:47:32.087] <TB3> INFO: Test took 1156ms.
[08:47:32.092] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:32.393] <TB3> INFO: Expecting 2560 events.
[08:47:33.247] <TB3> INFO: 2560 events read in total (297ms).
[08:47:33.247] <TB3> INFO: Test took 1156ms.
[08:47:33.250] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:33.554] <TB3> INFO: Expecting 2560 events.
[08:47:34.409] <TB3> INFO: 2560 events read in total (298ms).
[08:47:34.409] <TB3> INFO: Test took 1159ms.
[08:47:34.412] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:34.715] <TB3> INFO: Expecting 2560 events.
[08:47:35.578] <TB3> INFO: 2560 events read in total (306ms).
[08:47:35.578] <TB3> INFO: Test took 1166ms.
[08:47:35.582] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:35.884] <TB3> INFO: Expecting 2560 events.
[08:47:36.746] <TB3> INFO: 2560 events read in total (305ms).
[08:47:36.747] <TB3> INFO: Test took 1165ms.
[08:47:36.749] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:37.053] <TB3> INFO: Expecting 2560 events.
[08:47:37.914] <TB3> INFO: 2560 events read in total (304ms).
[08:47:37.915] <TB3> INFO: Test took 1166ms.
[08:47:37.918] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:38.221] <TB3> INFO: Expecting 2560 events.
[08:47:39.079] <TB3> INFO: 2560 events read in total (301ms).
[08:47:39.079] <TB3> INFO: Test took 1161ms.
[08:47:39.083] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:39.384] <TB3> INFO: Expecting 2560 events.
[08:47:40.238] <TB3> INFO: 2560 events read in total (297ms).
[08:47:40.238] <TB3> INFO: Test took 1156ms.
[08:47:40.240] <TB3> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:47:40.544] <TB3> INFO: Expecting 2560 events.
[08:47:41.401] <TB3> INFO: 2560 events read in total (300ms).
[08:47:41.402] <TB3> INFO: Test took 1162ms.
[08:47:41.901] <TB3> INFO: PixTestPhOptimization::doTest() done, duration: 524 seconds
[08:47:41.901] <TB3> INFO: PH scale (per ROC): 87 94 92 91 90 99 83 92 86 80 81 91 86 88 91 84
[08:47:41.901] <TB3> INFO: PH offset (per ROC): 147 148 139 163 139 142 144 150 148 145 162 159 153 144 147 146
[08:47:41.906] <TB3> INFO: Decoding statistics:
[08:47:41.906] <TB3> INFO: General information:
[08:47:41.906] <TB3> INFO: 16bit words read: 86926
[08:47:41.906] <TB3> INFO: valid events total: 10240
[08:47:41.906] <TB3> INFO: empty events: 7737
[08:47:41.906] <TB3> INFO: valid events with pixels: 2503
[08:47:41.906] <TB3> INFO: valid pixel hits: 2503
[08:47:41.906] <TB3> INFO: Event errors: 0
[08:47:41.906] <TB3> INFO: start marker: 0
[08:47:41.906] <TB3> INFO: stop marker: 0
[08:47:41.906] <TB3> INFO: overflow: 0
[08:47:41.906] <TB3> INFO: invalid 5bit words: 0
[08:47:41.906] <TB3> INFO: invalid XOR eye diagram: 0
[08:47:41.906] <TB3> INFO: frame (failed synchr.): 0
[08:47:41.906] <TB3> INFO: idle data (no TBM trl): 0
[08:47:41.906] <TB3> INFO: no data (only TBM hdr): 0
[08:47:41.906] <TB3> INFO: TBM errors: 0
[08:47:41.906] <TB3> INFO: flawed TBM headers: 0
[08:47:41.906] <TB3> INFO: flawed TBM trailers: 0
[08:47:41.906] <TB3> INFO: event ID mismatches: 0
[08:47:41.906] <TB3> INFO: ROC errors: 0
[08:47:41.906] <TB3> INFO: missing ROC header(s): 0
[08:47:41.906] <TB3> INFO: misplaced readback start: 0
[08:47:41.906] <TB3> INFO: Pixel decoding errors: 0
[08:47:41.906] <TB3> INFO: pixel data incomplete: 0
[08:47:41.906] <TB3> INFO: pixel address: 0
[08:47:41.906] <TB3> INFO: pulse height fill bit: 0
[08:47:41.906] <TB3> INFO: buffer corruption: 0
[08:47:42.082] <TB3> INFO: ######################################################################
[08:47:42.082] <TB3> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[08:47:42.082] <TB3> INFO: ######################################################################
[08:47:42.092] <TB3> INFO: scanning low vcal = 10
[08:47:42.356] <TB3> INFO: Expecting 41600 events.
[08:47:45.736] <TB3> INFO: 41600 events read in total (2823ms).
[08:47:45.736] <TB3> INFO: Test took 3644ms.
[08:47:45.738] <TB3> INFO: scanning low vcal = 20
[08:47:46.035] <TB3> INFO: Expecting 41600 events.
[08:47:49.429] <TB3> INFO: 41600 events read in total (2837ms).
[08:47:49.429] <TB3> INFO: Test took 3691ms.
[08:47:49.432] <TB3> INFO: scanning low vcal = 30
[08:47:49.726] <TB3> INFO: Expecting 41600 events.
[08:47:53.128] <TB3> INFO: 41600 events read in total (2845ms).
[08:47:53.129] <TB3> INFO: Test took 3697ms.
[08:47:53.131] <TB3> INFO: scanning low vcal = 40
[08:47:53.423] <TB3> INFO: Expecting 41600 events.
[08:47:57.270] <TB3> INFO: 41600 events read in total (3290ms).
[08:47:57.271] <TB3> INFO: Test took 4140ms.
[08:47:57.275] <TB3> INFO: scanning low vcal = 50
[08:47:57.557] <TB3> INFO: Expecting 41600 events.
[08:48:01.495] <TB3> INFO: 41600 events read in total (3381ms).
[08:48:01.496] <TB3> INFO: Test took 4221ms.
[08:48:01.500] <TB3> INFO: scanning low vcal = 60
[08:48:01.778] <TB3> INFO: Expecting 41600 events.
[08:48:05.718] <TB3> INFO: 41600 events read in total (3383ms).
[08:48:05.719] <TB3> INFO: Test took 4219ms.
[08:48:05.723] <TB3> INFO: scanning low vcal = 70
[08:48:06.003] <TB3> INFO: Expecting 41600 events.
[08:48:09.936] <TB3> INFO: 41600 events read in total (3376ms).
[08:48:09.936] <TB3> INFO: Test took 4213ms.
[08:48:09.940] <TB3> INFO: scanning low vcal = 80
[08:48:10.299] <TB3> INFO: Expecting 41600 events.
[08:48:14.271] <TB3> INFO: 41600 events read in total (3415ms).
[08:48:14.272] <TB3> INFO: Test took 4332ms.
[08:48:14.290] <TB3> INFO: scanning low vcal = 90
[08:48:14.556] <TB3> INFO: Expecting 41600 events.
[08:48:18.519] <TB3> INFO: 41600 events read in total (3406ms).
[08:48:18.520] <TB3> INFO: Test took 4230ms.
[08:48:18.523] <TB3> INFO: scanning low vcal = 100
[08:48:18.786] <TB3> INFO: Expecting 41600 events.
[08:48:22.742] <TB3> INFO: 41600 events read in total (3399ms).
[08:48:22.743] <TB3> INFO: Test took 4219ms.
[08:48:22.746] <TB3> INFO: scanning low vcal = 110
[08:48:23.008] <TB3> INFO: Expecting 41600 events.
[08:48:26.940] <TB3> INFO: 41600 events read in total (3376ms).
[08:48:26.941] <TB3> INFO: Test took 4195ms.
[08:48:26.945] <TB3> INFO: scanning low vcal = 120
[08:48:27.214] <TB3> INFO: Expecting 41600 events.
[08:48:31.180] <TB3> INFO: 41600 events read in total (3409ms).
[08:48:31.180] <TB3> INFO: Test took 4235ms.
[08:48:31.184] <TB3> INFO: scanning low vcal = 130
[08:48:31.451] <TB3> INFO: Expecting 41600 events.
[08:48:35.393] <TB3> INFO: 41600 events read in total (3385ms).
[08:48:35.394] <TB3> INFO: Test took 4210ms.
[08:48:35.397] <TB3> INFO: scanning low vcal = 140
[08:48:35.662] <TB3> INFO: Expecting 41600 events.
[08:48:39.610] <TB3> INFO: 41600 events read in total (3391ms).
[08:48:39.611] <TB3> INFO: Test took 4213ms.
[08:48:39.614] <TB3> INFO: scanning low vcal = 150
[08:48:39.898] <TB3> INFO: Expecting 41600 events.
[08:48:43.849] <TB3> INFO: 41600 events read in total (3394ms).
[08:48:43.849] <TB3> INFO: Test took 4234ms.
[08:48:43.853] <TB3> INFO: scanning low vcal = 160
[08:48:44.123] <TB3> INFO: Expecting 41600 events.
[08:48:48.078] <TB3> INFO: 41600 events read in total (3398ms).
[08:48:48.079] <TB3> INFO: Test took 4226ms.
[08:48:48.083] <TB3> INFO: scanning low vcal = 170
[08:48:48.363] <TB3> INFO: Expecting 41600 events.
[08:48:52.311] <TB3> INFO: 41600 events read in total (3391ms).
[08:48:52.312] <TB3> INFO: Test took 4229ms.
[08:48:52.317] <TB3> INFO: scanning low vcal = 180
[08:48:52.579] <TB3> INFO: Expecting 41600 events.
[08:48:56.529] <TB3> INFO: 41600 events read in total (3393ms).
[08:48:56.530] <TB3> INFO: Test took 4213ms.
[08:48:56.533] <TB3> INFO: scanning low vcal = 190
[08:48:56.801] <TB3> INFO: Expecting 41600 events.
[08:49:00.754] <TB3> INFO: 41600 events read in total (3396ms).
[08:49:00.754] <TB3> INFO: Test took 4220ms.
[08:49:00.758] <TB3> INFO: scanning low vcal = 200
[08:49:01.022] <TB3> INFO: Expecting 41600 events.
[08:49:04.966] <TB3> INFO: 41600 events read in total (3387ms).
[08:49:04.966] <TB3> INFO: Test took 4208ms.
[08:49:04.970] <TB3> INFO: scanning low vcal = 210
[08:49:05.236] <TB3> INFO: Expecting 41600 events.
[08:49:09.189] <TB3> INFO: 41600 events read in total (3396ms).
[08:49:09.190] <TB3> INFO: Test took 4220ms.
[08:49:09.193] <TB3> INFO: scanning low vcal = 220
[08:49:09.476] <TB3> INFO: Expecting 41600 events.
[08:49:13.437] <TB3> INFO: 41600 events read in total (3404ms).
[08:49:13.437] <TB3> INFO: Test took 4244ms.
[08:49:13.441] <TB3> INFO: scanning low vcal = 230
[08:49:13.710] <TB3> INFO: Expecting 41600 events.
[08:49:17.691] <TB3> INFO: 41600 events read in total (3424ms).
[08:49:17.692] <TB3> INFO: Test took 4251ms.
[08:49:17.696] <TB3> INFO: scanning low vcal = 240
[08:49:18.028] <TB3> INFO: Expecting 41600 events.
[08:49:21.969] <TB3> INFO: 41600 events read in total (3384ms).
[08:49:21.970] <TB3> INFO: Test took 4274ms.
[08:49:21.973] <TB3> INFO: scanning low vcal = 250
[08:49:22.243] <TB3> INFO: Expecting 41600 events.
[08:49:26.173] <TB3> INFO: 41600 events read in total (3373ms).
[08:49:26.173] <TB3> INFO: Test took 4200ms.
[08:49:26.179] <TB3> INFO: scanning high vcal = 30 (= 210 in low range)
[08:49:26.452] <TB3> INFO: Expecting 41600 events.
[08:49:30.415] <TB3> INFO: 41600 events read in total (3406ms).
[08:49:30.416] <TB3> INFO: Test took 4237ms.
[08:49:30.420] <TB3> INFO: scanning high vcal = 50 (= 350 in low range)
[08:49:30.691] <TB3> INFO: Expecting 41600 events.
[08:49:34.656] <TB3> INFO: 41600 events read in total (3409ms).
[08:49:34.657] <TB3> INFO: Test took 4237ms.
[08:49:34.663] <TB3> INFO: scanning high vcal = 70 (= 490 in low range)
[08:49:34.937] <TB3> INFO: Expecting 41600 events.
[08:49:38.867] <TB3> INFO: 41600 events read in total (3373ms).
[08:49:38.868] <TB3> INFO: Test took 4205ms.
[08:49:38.873] <TB3> INFO: scanning high vcal = 90 (= 630 in low range)
[08:49:39.138] <TB3> INFO: Expecting 41600 events.
[08:49:43.065] <TB3> INFO: 41600 events read in total (3370ms).
[08:49:43.066] <TB3> INFO: Test took 4193ms.
[08:49:43.069] <TB3> INFO: scanning high vcal = 200 (= 1400 in low range)
[08:49:43.334] <TB3> INFO: Expecting 41600 events.
[08:49:47.276] <TB3> INFO: 41600 events read in total (3385ms).
[08:49:47.276] <TB3> INFO: Test took 4207ms.
[08:49:47.838] <TB3> INFO: PixTestGainPedestal::measure() done
[08:50:21.539] <TB3> INFO: PixTestGainPedestal::fit() done
[08:50:21.539] <TB3> INFO: non-linearity mean: 0.950 0.952 0.949 0.956 0.946 0.951 0.956 0.955 0.953 0.961 0.953 0.950 0.959 0.962 0.955 0.952
[08:50:21.539] <TB3> INFO: non-linearity RMS: 0.006 0.006 0.006 0.005 0.007 0.005 0.005 0.006 0.007 0.006 0.006 0.006 0.006 0.005 0.006 0.006
[08:50:21.539] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[08:50:21.565] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[08:50:21.586] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[08:50:21.610] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[08:50:21.632] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[08:50:21.652] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[08:50:21.673] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[08:50:21.693] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[08:50:21.714] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[08:50:21.737] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[08:50:21.758] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[08:50:21.780] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[08:50:21.800] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[08:50:21.821] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[08:50:21.841] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[08:50:21.862] <TB3> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[08:50:21.882] <TB3> INFO: PixTestGainPedestal::fullTest() done, duration: 159 seconds
[08:50:21.882] <TB3> INFO: Decoding statistics:
[08:50:21.882] <TB3> INFO: General information:
[08:50:21.882] <TB3> INFO: 16bit words read: 2662380
[08:50:21.882] <TB3> INFO: valid events total: 166400
[08:50:21.882] <TB3> INFO: empty events: 0
[08:50:21.882] <TB3> INFO: valid events with pixels: 166400
[08:50:21.882] <TB3> INFO: valid pixel hits: 665590
[08:50:21.882] <TB3> INFO: Event errors: 0
[08:50:21.882] <TB3> INFO: start marker: 0
[08:50:21.882] <TB3> INFO: stop marker: 0
[08:50:21.882] <TB3> INFO: overflow: 0
[08:50:21.882] <TB3> INFO: invalid 5bit words: 0
[08:50:21.882] <TB3> INFO: invalid XOR eye diagram: 0
[08:50:21.882] <TB3> INFO: frame (failed synchr.): 0
[08:50:21.882] <TB3> INFO: idle data (no TBM trl): 0
[08:50:21.882] <TB3> INFO: no data (only TBM hdr): 0
[08:50:21.882] <TB3> INFO: TBM errors: 0
[08:50:21.882] <TB3> INFO: flawed TBM headers: 0
[08:50:21.882] <TB3> INFO: flawed TBM trailers: 0
[08:50:21.882] <TB3> INFO: event ID mismatches: 0
[08:50:21.882] <TB3> INFO: ROC errors: 0
[08:50:21.882] <TB3> INFO: missing ROC header(s): 0
[08:50:21.882] <TB3> INFO: misplaced readback start: 0
[08:50:21.882] <TB3> INFO: Pixel decoding errors: 0
[08:50:21.882] <TB3> INFO: pixel data incomplete: 0
[08:50:21.882] <TB3> INFO: pixel address: 0
[08:50:21.882] <TB3> INFO: pulse height fill bit: 0
[08:50:21.882] <TB3> INFO: buffer corruption: 0
[08:50:21.890] <TB3> INFO: readReadbackCal: /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C15.dat
[08:50:21.920] <TB3> INFO: ######################################################################
[08:50:21.920] <TB3> INFO: PixTestReadback::doTest()
[08:50:21.920] <TB3> INFO: ######################################################################
[08:50:21.920] <TB3> INFO: ----------------------------------------------------------------------
[08:50:21.920] <TB3> INFO: PixTestReadback::CalibrateVd()
[08:50:21.920] <TB3> INFO: ----------------------------------------------------------------------
[08:50:31.501] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C0.dat
[08:50:31.501] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C1.dat
[08:50:31.502] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C2.dat
[08:50:31.502] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C3.dat
[08:50:31.502] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C4.dat
[08:50:31.502] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C5.dat
[08:50:31.502] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C6.dat
[08:50:31.502] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C7.dat
[08:50:31.502] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C8.dat
[08:50:31.502] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C9.dat
[08:50:31.502] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C10.dat
[08:50:31.502] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C11.dat
[08:50:31.502] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C12.dat
[08:50:31.502] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C13.dat
[08:50:31.503] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C14.dat
[08:50:31.503] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C15.dat
[08:50:31.549] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[08:50:31.549] <TB3> INFO: ----------------------------------------------------------------------
[08:50:31.549] <TB3> INFO: PixTestReadback::CalibrateVa()
[08:50:31.549] <TB3> INFO: ----------------------------------------------------------------------
[08:50:41.107] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C0.dat
[08:50:41.107] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C1.dat
[08:50:41.107] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C2.dat
[08:50:41.107] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C3.dat
[08:50:41.107] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C4.dat
[08:50:41.107] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C5.dat
[08:50:41.107] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C6.dat
[08:50:41.107] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C7.dat
[08:50:41.107] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C8.dat
[08:50:41.107] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C9.dat
[08:50:41.107] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C10.dat
[08:50:41.107] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C11.dat
[08:50:41.108] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C12.dat
[08:50:41.108] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C13.dat
[08:50:41.108] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C14.dat
[08:50:41.108] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C15.dat
[08:50:41.153] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[08:50:41.154] <TB3> INFO: ----------------------------------------------------------------------
[08:50:41.154] <TB3> INFO: PixTestReadback::readbackVbg()
[08:50:41.154] <TB3> INFO: ----------------------------------------------------------------------
[08:50:48.515] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[08:50:48.515] <TB3> INFO: ----------------------------------------------------------------------
[08:50:48.515] <TB3> INFO: PixTestReadback::getCalibratedVbg()
[08:50:48.515] <TB3> INFO: ----------------------------------------------------------------------
[08:50:48.515] <TB3> INFO: Vbg will be calibrated using Vd calibration
[08:50:48.515] <TB3> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 150.8calibrated Vbg = 1.19717 :::*/*/*/*/
[08:50:48.515] <TB3> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 157.7calibrated Vbg = 1.19207 :::*/*/*/*/
[08:50:48.515] <TB3> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 153calibrated Vbg = 1.19695 :::*/*/*/*/
[08:50:48.515] <TB3> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 154.6calibrated Vbg = 1.20291 :::*/*/*/*/
[08:50:48.515] <TB3> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 142.9calibrated Vbg = 1.19887 :::*/*/*/*/
[08:50:48.515] <TB3> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 150.2calibrated Vbg = 1.20605 :::*/*/*/*/
[08:50:48.515] <TB3> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 149.9calibrated Vbg = 1.1982 :::*/*/*/*/
[08:50:48.515] <TB3> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 151.9calibrated Vbg = 1.20254 :::*/*/*/*/
[08:50:48.515] <TB3> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 148.5calibrated Vbg = 1.20664 :::*/*/*/*/
[08:50:48.515] <TB3> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 143calibrated Vbg = 1.20567 :::*/*/*/*/
[08:50:48.515] <TB3> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 160calibrated Vbg = 1.20587 :::*/*/*/*/
[08:50:48.515] <TB3> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 150.1calibrated Vbg = 1.20378 :::*/*/*/*/
[08:50:48.515] <TB3> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 156.4calibrated Vbg = 1.19267 :::*/*/*/*/
[08:50:48.515] <TB3> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 147calibrated Vbg = 1.19112 :::*/*/*/*/
[08:50:48.515] <TB3> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 155.7calibrated Vbg = 1.1956 :::*/*/*/*/
[08:50:48.515] <TB3> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 148calibrated Vbg = 1.19303 :::*/*/*/*/
[08:50:48.519] <TB3> INFO: ----------------------------------------------------------------------
[08:50:48.519] <TB3> INFO: PixTestReadback::CalibrateIa()
[08:50:48.519] <TB3> INFO: ----------------------------------------------------------------------
[08:53:24.562] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C0.dat
[08:53:24.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C1.dat
[08:53:24.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C2.dat
[08:53:24.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C3.dat
[08:53:24.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C4.dat
[08:53:24.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C5.dat
[08:53:24.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C6.dat
[08:53:24.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C7.dat
[08:53:24.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C8.dat
[08:53:24.563] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C9.dat
[08:53:24.564] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C10.dat
[08:53:24.564] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C11.dat
[08:53:24.564] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C12.dat
[08:53:24.564] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C13.dat
[08:53:24.564] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C14.dat
[08:53:24.564] <TB3> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2355_FullQualification_2016-10-07_09h23m_1475825020//000_FulltestPxar_m20//readbackCal_C15.dat
[08:53:24.610] <TB3> INFO: PixTestPattern:: pg_setup set to default.
[08:53:24.611] <TB3> INFO: PixTestReadback::doTest() done
[08:53:24.611] <TB3> INFO: Decoding statistics:
[08:53:24.611] <TB3> INFO: General information:
[08:53:24.611] <TB3> INFO: 16bit words read: 1024
[08:53:24.611] <TB3> INFO: valid events total: 128
[08:53:24.611] <TB3> INFO: empty events: 128
[08:53:24.611] <TB3> INFO: valid events with pixels: 0
[08:53:24.611] <TB3> INFO: valid pixel hits: 0
[08:53:24.611] <TB3> INFO: Event errors: 0
[08:53:24.611] <TB3> INFO: start marker: 0
[08:53:24.611] <TB3> INFO: stop marker: 0
[08:53:24.611] <TB3> INFO: overflow: 0
[08:53:24.611] <TB3> INFO: invalid 5bit words: 0
[08:53:24.611] <TB3> INFO: invalid XOR eye diagram: 0
[08:53:24.611] <TB3> INFO: frame (failed synchr.): 0
[08:53:24.611] <TB3> INFO: idle data (no TBM trl): 0
[08:53:24.611] <TB3> INFO: no data (only TBM hdr): 0
[08:53:24.611] <TB3> INFO: TBM errors: 0
[08:53:24.611] <TB3> INFO: flawed TBM headers: 0
[08:53:24.611] <TB3> INFO: flawed TBM trailers: 0
[08:53:24.611] <TB3> INFO: event ID mismatches: 0
[08:53:24.611] <TB3> INFO: ROC errors: 0
[08:53:24.611] <TB3> INFO: missing ROC header(s): 0
[08:53:24.611] <TB3> INFO: misplaced readback start: 0
[08:53:24.611] <TB3> INFO: Pixel decoding errors: 0
[08:53:24.611] <TB3> INFO: pixel data incomplete: 0
[08:53:24.611] <TB3> INFO: pixel address: 0
[08:53:24.611] <TB3> INFO: pulse height fill bit: 0
[08:53:24.611] <TB3> INFO: buffer corruption: 0
[08:53:24.632] <TB3> INFO: Decoding statistics:
[08:53:24.632] <TB3> INFO: General information:
[08:53:24.632] <TB3> INFO: 16bit words read: 2750330
[08:53:24.632] <TB3> INFO: valid events total: 176768
[08:53:24.632] <TB3> INFO: empty events: 7865
[08:53:24.632] <TB3> INFO: valid events with pixels: 168903
[08:53:24.632] <TB3> INFO: valid pixel hits: 668093
[08:53:24.632] <TB3> INFO: Event errors: 0
[08:53:24.632] <TB3> INFO: start marker: 0
[08:53:24.632] <TB3> INFO: stop marker: 0
[08:53:24.632] <TB3> INFO: overflow: 0
[08:53:24.632] <TB3> INFO: invalid 5bit words: 0
[08:53:24.632] <TB3> INFO: invalid XOR eye diagram: 0
[08:53:24.632] <TB3> INFO: frame (failed synchr.): 0
[08:53:24.632] <TB3> INFO: idle data (no TBM trl): 0
[08:53:24.632] <TB3> INFO: no data (only TBM hdr): 0
[08:53:24.632] <TB3> INFO: TBM errors: 0
[08:53:24.632] <TB3> INFO: flawed TBM headers: 0
[08:53:24.632] <TB3> INFO: flawed TBM trailers: 0
[08:53:24.632] <TB3> INFO: event ID mismatches: 0
[08:53:24.632] <TB3> INFO: ROC errors: 0
[08:53:24.632] <TB3> INFO: missing ROC header(s): 0
[08:53:24.632] <TB3> INFO: misplaced readback start: 0
[08:53:24.632] <TB3> INFO: Pixel decoding errors: 0
[08:53:24.632] <TB3> INFO: pixel data incomplete: 0
[08:53:24.632] <TB3> INFO: pixel address: 0
[08:53:24.633] <TB3> INFO: pulse height fill bit: 0
[08:53:24.633] <TB3> INFO: buffer corruption: 0
[08:53:24.633] <TB3> INFO: enter test to run
[08:53:24.633] <TB3> INFO: test: exit no parameter change
[08:53:24.781] <TB3> QUIET: Connection to board 170 closed.
[08:53:24.860] <TB3> INFO: pXar: this is the end, my friend