[17:04:35.644] <TB0> INFO: *** Welcome to pxar ***
[17:04:35.644] <TB0> INFO: *** Today: 2016/02/08
[17:04:36.391] <TB0> INFO: *** Version: 9751-dirty
[17:04:36.391] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C15.dat
[17:04:36.392] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//tbmParameters_C0b.dat
[17:04:36.392] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//defaultMaskFile.dat
[17:04:36.392] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters_C15.dat
[17:04:36.466] <TB0> INFO: clk: 4
[17:04:36.466] <TB0> INFO: ctr: 4
[17:04:36.466] <TB0> INFO: sda: 19
[17:04:36.466] <TB0> INFO: tin: 9
[17:04:36.466] <TB0> INFO: level: 15
[17:04:36.466] <TB0> INFO: triggerdelay: 0
[17:04:36.466] <TB0> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[17:04:36.466] <TB0> INFO: Log level: INFO
[17:04:36.473] <TB0> INFO: Found DTB DTB_WS6AYH
[17:04:36.484] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[17:04:36.486] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[17:04:36.488] <TB0> INFO: RPC call hashes of host and DTB match: 484264910
[17:04:38.021] <TB0> INFO: DUT info:
[17:04:38.021] <TB0> INFO: The DUT currently contains the following objects:
[17:04:38.021] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[17:04:38.021] <TB0> INFO: TBM Core alpha (0): 7 registers set
[17:04:38.021] <TB0> INFO: TBM Core beta (1): 7 registers set
[17:04:38.021] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:04:38.021] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:38.022] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:38.022] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:38.022] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:38.022] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:38.022] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:38.022] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:38.022] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:38.022] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:38.022] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:38.022] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:38.022] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:38.022] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:38.022] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:38.022] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:38.022] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:04:38.422] <TB0> INFO: enter 'restricted' command line mode
[17:04:38.422] <TB0> INFO: enter test to run
[17:04:38.422] <TB0> INFO: test: pretest no parameter change
[17:04:38.422] <TB0> INFO: running: pretest
[17:04:38.427] <TB0> INFO: ----------------------------------------------------------------------
[17:04:38.427] <TB0> INFO: PixTestPretest::programROC()
[17:04:38.427] <TB0> INFO: ----------------------------------------------------------------------
[17:04:56.441] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[17:04:56.441] <TB0> INFO: IA differences per ROC: 18.5 16.9 17.7 17.7 16.1 18.5 16.1 17.7 16.9 16.1 18.5 16.9 19.3 17.7 17.7 17.7
[17:04:56.528] <TB0> INFO: enter test to run
[17:04:56.528] <TB0> INFO: test: pretest no parameter change
[17:04:56.528] <TB0> INFO: running: pretest
[17:04:56.529] <TB0> INFO: ----------------------------------------------------------------------
[17:04:56.529] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[17:04:56.529] <TB0> INFO: ----------------------------------------------------------------------
[17:05:17.784] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 390.7 mA = 24.4187 mA/ROC
[17:05:17.784] <TB0> INFO: i(loss) [mA/ROC]: 20.1 20.1 19.3 19.3 20.1 20.1 20.1 19.3 19.3 19.3 19.3 19.3 20.1 19.3 18.5 19.3
[17:05:17.817] <TB0> INFO: enter test to run
[17:05:17.817] <TB0> INFO: test: pretest no parameter change
[17:05:17.817] <TB0> INFO: running: pretest
[17:05:17.817] <TB0> INFO: ----------------------------------------------------------------------
[17:05:17.817] <TB0> INFO: PixTestPretest::findTiming()
[17:05:17.817] <TB0> INFO: ----------------------------------------------------------------------
[17:05:17.817] <TB0> INFO: PixTestCmd::init()

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


[17:06:57.848] <TB0> INFO: 231680 events read in total (9889ms).
[17:06:57.853] <TB0> INFO: Test took 10538ms.
[17:06:58.079] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[17:06:58.139] <TB0> INFO: enter test to run
[17:06:58.139] <TB0> INFO: test: pretest no parameter change
[17:06:58.139] <TB0> INFO: running: pretest
[17:06:58.140] <TB0> INFO: ----------------------------------------------------------------------
[17:06:58.140] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[17:06:58.140] <TB0> INFO: ----------------------------------------------------------------------
[17:06:58.232] <TB0> INFO: Expecting 231680 events.
[17:07:08.501] <TB0> INFO: 231680 events read in total (9713ms).
[17:07:08.506] <TB0> INFO: Test took 10362ms.
[17:07:08.755] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[17:07:08.755] <TB0> INFO: CalDel: 135 113 151 121 130 112 111 129 111 141 128 139 144 122 105 141
[17:07:08.755] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[17:07:08.789] <TB0> INFO: enter test to run
[17:07:08.789] <TB0> INFO: test: pretest no parameter change
[17:07:08.789] <TB0> INFO: running: pretest
[17:07:08.789] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C0.dat
[17:07:08.789] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C1.dat
[17:07:08.789] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C2.dat
[17:07:08.789] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C3.dat
[17:07:08.789] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C4.dat
[17:07:08.789] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C5.dat
[17:07:08.789] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C6.dat
[17:07:08.790] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C7.dat
[17:07:08.790] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C8.dat
[17:07:08.790] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C9.dat
[17:07:08.790] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C10.dat
[17:07:08.790] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C11.dat
[17:07:08.790] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C12.dat
[17:07:08.790] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C13.dat
[17:07:08.790] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C14.dat
[17:07:08.791] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters_C15.dat
[17:07:08.791] <TB0> INFO: enter test to run
[17:07:08.791] <TB0> INFO: test: fulltest no parameter change
[17:07:08.791] <TB0> INFO: running: fulltest
[17:07:08.791] <TB0> INFO: ######################################################################
[17:07:08.791] <TB0> INFO: PixTestFullTest::doTest()
[17:07:08.791] <TB0> INFO: ######################################################################
[17:07:08.792] <TB0> INFO: ######################################################################
[17:07:08.792] <TB0> INFO: PixTestAlive::doTest()
[17:07:08.792] <TB0> INFO: ######################################################################
[17:07:08.793] <TB0> INFO: ----------------------------------------------------------------------
[17:07:08.793] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:07:08.793] <TB0> INFO: ----------------------------------------------------------------------
[17:07:09.071] <TB0> INFO: Expecting 41600 events.
[17:07:12.905] <TB0> INFO: 41600 events read in total (3278ms).
[17:07:12.905] <TB0> INFO: Test took 4110ms.
[17:07:13.135] <TB0> INFO: PixTestAlive::aliveTest() done
[17:07:13.135] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 2 0 0 0 0 0
[17:07:13.136] <TB0> INFO: ----------------------------------------------------------------------
[17:07:13.136] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:07:13.136] <TB0> INFO: ----------------------------------------------------------------------
[17:07:13.411] <TB0> INFO: Expecting 41600 events.
[17:07:16.420] <TB0> INFO: 41600 events read in total (2453ms).
[17:07:16.420] <TB0> INFO: Test took 3282ms.
[17:07:16.421] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[17:07:16.671] <TB0> INFO: PixTestAlive::maskTest() done
[17:07:16.671] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:07:16.672] <TB0> INFO: ----------------------------------------------------------------------
[17:07:16.672] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:07:16.672] <TB0> INFO: ----------------------------------------------------------------------
[17:07:16.943] <TB0> INFO: Expecting 41600 events.
[17:07:20.792] <TB0> INFO: 41600 events read in total (3293ms).
[17:07:20.792] <TB0> INFO: Test took 4118ms.
[17:07:21.015] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[17:07:21.015] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[17:07:21.015] <TB0> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[17:07:21.015] <TB0> INFO: Decoding statistics:
[17:07:21.015] <TB0> INFO: General information:
[17:07:21.015] <TB0> INFO: 16bit words read: 0
[17:07:21.015] <TB0> INFO: valid events total: 0
[17:07:21.015] <TB0> INFO: empty events: 0
[17:07:21.015] <TB0> INFO: valid events with pixels: 0
[17:07:21.015] <TB0> INFO: valid pixel hits: 0
[17:07:21.015] <TB0> INFO: Event errors: 0
[17:07:21.015] <TB0> INFO: start marker: 0
[17:07:21.015] <TB0> INFO: stop marker: 0
[17:07:21.015] <TB0> INFO: overflow: 0
[17:07:21.015] <TB0> INFO: invalid 5bit words: 0
[17:07:21.015] <TB0> INFO: invalid XOR eye diagram: 0
[17:07:21.015] <TB0> INFO: frame (failed synchr.): 0
[17:07:21.015] <TB0> INFO: idle data (no TBM trl): 0
[17:07:21.015] <TB0> INFO: no data (only TBM hdr): 0
[17:07:21.015] <TB0> INFO: TBM errors: 0
[17:07:21.015] <TB0> INFO: flawed TBM headers: 0
[17:07:21.015] <TB0> INFO: flawed TBM trailers: 0
[17:07:21.015] <TB0> INFO: event ID mismatches: 0
[17:07:21.015] <TB0> INFO: ROC errors: 0
[17:07:21.015] <TB0> INFO: missing ROC header(s): 0
[17:07:21.015] <TB0> INFO: misplaced readback start: 0
[17:07:21.015] <TB0> INFO: Pixel decoding errors: 0
[17:07:21.015] <TB0> INFO: pixel data incomplete: 0
[17:07:21.016] <TB0> INFO: pixel address: 0
[17:07:21.016] <TB0> INFO: pulse height fill bit: 0
[17:07:21.016] <TB0> INFO: buffer corruption: 0
[17:07:21.024] <TB0> INFO: ######################################################################
[17:07:21.024] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[17:07:21.024] <TB0> INFO: ######################################################################
[17:07:21.027] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[17:07:21.044] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[17:07:21.044] <TB0> INFO: run 1 of 1
[17:07:21.320] <TB0> INFO: Expecting 3120000 events.
[17:08:03.448] <TB0> INFO: 869045 events read in total (41572ms).
[17:08:44.939] <TB0> INFO: 1726175 events read in total (83063ms).
[17:09:26.366] <TB0> INFO: 2588790 events read in total (124490ms).
[17:09:51.543] <TB0> INFO: 3120000 events read in total (149667ms).
[17:09:51.587] <TB0> INFO: Test took 150543ms.
[17:10:17.861] <TB0> INFO: PixTestBBMap::doTest() done, duration: 176 seconds
[17:10:17.862] <TB0> INFO: number of dead bumps (per ROC): 16 0 9 2 1 0 1 1 0 0 0 5 0 0 5 12
[17:10:17.862] <TB0> INFO: separation cut (per ROC): 83 100 96 92 96 103 102 98 90 93 97 102 106 105 97 81
[17:10:17.862] <TB0> INFO: Decoding statistics:
[17:10:17.862] <TB0> INFO: General information:
[17:10:17.862] <TB0> INFO: 16bit words read: 0
[17:10:17.862] <TB0> INFO: valid events total: 0
[17:10:17.862] <TB0> INFO: empty events: 0
[17:10:17.862] <TB0> INFO: valid events with pixels: 0
[17:10:17.862] <TB0> INFO: valid pixel hits: 0
[17:10:17.862] <TB0> INFO: Event errors: 0
[17:10:17.862] <TB0> INFO: start marker: 0
[17:10:17.862] <TB0> INFO: stop marker: 0
[17:10:17.862] <TB0> INFO: overflow: 0
[17:10:17.862] <TB0> INFO: invalid 5bit words: 0
[17:10:17.862] <TB0> INFO: invalid XOR eye diagram: 0
[17:10:17.862] <TB0> INFO: frame (failed synchr.): 0
[17:10:17.862] <TB0> INFO: idle data (no TBM trl): 0
[17:10:17.862] <TB0> INFO: no data (only TBM hdr): 0
[17:10:17.862] <TB0> INFO: TBM errors: 0
[17:10:17.862] <TB0> INFO: flawed TBM headers: 0
[17:10:17.862] <TB0> INFO: flawed TBM trailers: 0
[17:10:17.862] <TB0> INFO: event ID mismatches: 0
[17:10:17.862] <TB0> INFO: ROC errors: 0
[17:10:17.862] <TB0> INFO: missing ROC header(s): 0
[17:10:17.862] <TB0> INFO: misplaced readback start: 0
[17:10:17.862] <TB0> INFO: Pixel decoding errors: 0
[17:10:17.862] <TB0> INFO: pixel data incomplete: 0
[17:10:17.862] <TB0> INFO: pixel address: 0
[17:10:17.862] <TB0> INFO: pulse height fill bit: 0
[17:10:17.862] <TB0> INFO: buffer corruption: 0
[17:10:17.902] <TB0> INFO: ######################################################################
[17:10:17.902] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[17:10:17.902] <TB0> INFO: ######################################################################
[17:10:17.902] <TB0> INFO: ----------------------------------------------------------------------
[17:10:17.902] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[17:10:17.902] <TB0> INFO: ----------------------------------------------------------------------
[17:10:17.902] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[17:10:17.911] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[17:10:17.911] <TB0> INFO: run 1 of 1
[17:10:18.179] <TB0> INFO: Expecting 26208000 events.
[17:10:53.615] <TB0> INFO: 976900 events read in total (34879ms).
[17:11:28.195] <TB0> INFO: 1937250 events read in total (69459ms).
[17:12:02.907] <TB0> INFO: 2894500 events read in total (104171ms).
[17:12:37.390] <TB0> INFO: 3852050 events read in total (138654ms).
[17:13:11.830] <TB0> INFO: 4808050 events read in total (173094ms).
[17:13:46.535] <TB0> INFO: 5764050 events read in total (207799ms).
[17:14:20.919] <TB0> INFO: 6720050 events read in total (242183ms).
[17:14:55.897] <TB0> INFO: 7672600 events read in total (277161ms).
[17:15:30.497] <TB0> INFO: 8625100 events read in total (311761ms).
[17:16:04.981] <TB0> INFO: 9578500 events read in total (346245ms).
[17:16:39.264] <TB0> INFO: 10528500 events read in total (380528ms).
[17:17:13.713] <TB0> INFO: 11477800 events read in total (414977ms).
[17:17:48.454] <TB0> INFO: 12428250 events read in total (449718ms).
[17:18:22.713] <TB0> INFO: 13372000 events read in total (483977ms).
[17:18:57.297] <TB0> INFO: 14309350 events read in total (518561ms).
[17:19:31.576] <TB0> INFO: 15245650 events read in total (552840ms).
[17:20:05.858] <TB0> INFO: 16181000 events read in total (587122ms).
[17:20:40.609] <TB0> INFO: 17113150 events read in total (621873ms).
[17:21:15.528] <TB0> INFO: 18044650 events read in total (656792ms).
[17:21:49.944] <TB0> INFO: 18977450 events read in total (691208ms).
[17:22:23.937] <TB0> INFO: 19909250 events read in total (725201ms).
[17:22:57.916] <TB0> INFO: 20841900 events read in total (759180ms).
[17:23:32.160] <TB0> INFO: 21776000 events read in total (793424ms).
[17:24:06.593] <TB0> INFO: 22704700 events read in total (827857ms).
[17:24:41.184] <TB0> INFO: 23638300 events read in total (862449ms).
[17:25:15.587] <TB0> INFO: 24573050 events read in total (896851ms).
[17:25:49.830] <TB0> INFO: 25507150 events read in total (931094ms).
[17:26:15.079] <TB0> INFO: 26208000 events read in total (956343ms).
[17:26:15.106] <TB0> INFO: Test took 957195ms.
[17:26:15.325] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:26:16.812] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:26:18.424] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:26:20.016] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:26:21.562] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:26:23.019] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:26:24.698] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:26:26.358] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:26:28.041] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:26:29.762] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:26:31.236] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:26:32.682] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:26:34.267] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:26:35.676] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:26:37.077] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:26:38.566] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[17:26:40.047] <TB0> INFO: PixTestScurves::scurves() done
[17:26:40.047] <TB0> INFO: Vcal mean: 97.31 105.19 109.47 91.25 94.31 106.26 102.19 105.21 93.66 108.54 102.02 100.51 104.73 106.24 102.60 96.64
[17:26:40.047] <TB0> INFO: Vcal RMS: 5.31 5.24 4.92 4.66 5.64 5.38 4.92 5.08 4.67 5.11 5.97 5.33 5.92 5.64 4.93 4.73
[17:26:40.047] <TB0> INFO: PixTestScurves::fullTest() done, duration: 982 seconds
[17:26:40.047] <TB0> INFO: Decoding statistics:
[17:26:40.047] <TB0> INFO: General information:
[17:26:40.047] <TB0> INFO: 16bit words read: 0
[17:26:40.047] <TB0> INFO: valid events total: 0
[17:26:40.047] <TB0> INFO: empty events: 0
[17:26:40.047] <TB0> INFO: valid events with pixels: 0
[17:26:40.047] <TB0> INFO: valid pixel hits: 0
[17:26:40.047] <TB0> INFO: Event errors: 0
[17:26:40.047] <TB0> INFO: start marker: 0
[17:26:40.047] <TB0> INFO: stop marker: 0
[17:26:40.047] <TB0> INFO: overflow: 0
[17:26:40.047] <TB0> INFO: invalid 5bit words: 0
[17:26:40.047] <TB0> INFO: invalid XOR eye diagram: 0
[17:26:40.047] <TB0> INFO: frame (failed synchr.): 0
[17:26:40.047] <TB0> INFO: idle data (no TBM trl): 0
[17:26:40.047] <TB0> INFO: no data (only TBM hdr): 0
[17:26:40.047] <TB0> INFO: TBM errors: 0
[17:26:40.047] <TB0> INFO: flawed TBM headers: 0
[17:26:40.047] <TB0> INFO: flawed TBM trailers: 0
[17:26:40.047] <TB0> INFO: event ID mismatches: 0
[17:26:40.047] <TB0> INFO: ROC errors: 0
[17:26:40.047] <TB0> INFO: missing ROC header(s): 0
[17:26:40.048] <TB0> INFO: misplaced readback start: 0
[17:26:40.048] <TB0> INFO: Pixel decoding errors: 0
[17:26:40.048] <TB0> INFO: pixel data incomplete: 0
[17:26:40.048] <TB0> INFO: pixel address: 0
[17:26:40.048] <TB0> INFO: pulse height fill bit: 0
[17:26:40.048] <TB0> INFO: buffer corruption: 0
[17:26:40.132] <TB0> INFO: ######################################################################
[17:26:40.132] <TB0> INFO: PixTestTrim::doTest()
[17:26:40.132] <TB0> INFO: ######################################################################
[17:26:40.133] <TB0> INFO: ----------------------------------------------------------------------
[17:26:40.133] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[17:26:40.133] <TB0> INFO: ----------------------------------------------------------------------
[17:26:40.212] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[17:26:40.212] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[17:26:40.221] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[17:26:40.221] <TB0> INFO: run 1 of 1
[17:26:40.485] <TB0> INFO: Expecting 6281600 events.
[17:27:20.787] <TB0> INFO: 1082410 events read in total (39746ms).
[17:28:00.299] <TB0> INFO: 2160260 events read in total (79258ms).
[17:28:40.139] <TB0> INFO: 3231600 events read in total (119099ms).
[17:29:20.246] <TB0> INFO: 4308490 events read in total (159205ms).
[17:30:00.285] <TB0> INFO: 5389130 events read in total (199244ms).
[17:30:32.942] <TB0> INFO: 6281600 events read in total (231901ms).
[17:30:32.994] <TB0> INFO: Test took 232773ms.
[17:30:52.937] <TB0> INFO: ROC 0 VthrComp = 97
[17:30:52.937] <TB0> INFO: ROC 1 VthrComp = 105
[17:30:52.937] <TB0> INFO: ROC 2 VthrComp = 102
[17:30:52.938] <TB0> INFO: ROC 3 VthrComp = 97
[17:30:52.938] <TB0> INFO: ROC 4 VthrComp = 99
[17:30:52.938] <TB0> INFO: ROC 5 VthrComp = 107
[17:30:52.938] <TB0> INFO: ROC 6 VthrComp = 105
[17:30:52.938] <TB0> INFO: ROC 7 VthrComp = 103
[17:30:52.938] <TB0> INFO: ROC 8 VthrComp = 99
[17:30:52.938] <TB0> INFO: ROC 9 VthrComp = 101
[17:30:52.938] <TB0> INFO: ROC 10 VthrComp = 101
[17:30:52.938] <TB0> INFO: ROC 11 VthrComp = 98
[17:30:52.939] <TB0> INFO: ROC 12 VthrComp = 103
[17:30:52.939] <TB0> INFO: ROC 13 VthrComp = 105
[17:30:52.939] <TB0> INFO: ROC 14 VthrComp = 103
[17:30:52.939] <TB0> INFO: ROC 15 VthrComp = 97
[17:30:52.939] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[17:30:52.939] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[17:30:52.948] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[17:30:52.948] <TB0> INFO: run 1 of 1
[17:30:53.209] <TB0> INFO: Expecting 6281600 events.
[17:31:29.644] <TB0> INFO: 758260 events read in total (35879ms).
[17:32:05.148] <TB0> INFO: 1514470 events read in total (71383ms).
[17:32:40.834] <TB0> INFO: 2271190 events read in total (107069ms).
[17:33:16.376] <TB0> INFO: 3028530 events read in total (142611ms).
[17:33:51.538] <TB0> INFO: 3779930 events read in total (177773ms).
[17:34:26.589] <TB0> INFO: 4527320 events read in total (212824ms).
[17:35:02.278] <TB0> INFO: 5272690 events read in total (248513ms).
[17:35:37.540] <TB0> INFO: 6018050 events read in total (283775ms).
[17:35:50.188] <TB0> INFO: 6281600 events read in total (296423ms).
[17:35:50.234] <TB0> INFO: Test took 297286ms.
[17:36:15.768] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 59.1916 for pixel 14/79 mean/min/max = 45.7517/32.3037/59.1996
[17:36:15.769] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 59.2978 for pixel 8/6 mean/min/max = 46.8932/34.4265/59.3599
[17:36:15.769] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 62.8032 for pixel 26/21 mean/min/max = 48.3935/33.9/62.8871
[17:36:15.769] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 55.5509 for pixel 1/3 mean/min/max = 43.7847/31.8809/55.6885
[17:36:15.769] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 56.7142 for pixel 22/1 mean/min/max = 44.1428/31.4182/56.8673
[17:36:15.769] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 60.0657 for pixel 0/8 mean/min/max = 46.9374/33.7633/60.1114
[17:36:15.770] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 58.47 for pixel 7/79 mean/min/max = 46.6554/34.8/58.5108
[17:36:15.770] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 59.3475 for pixel 16/65 mean/min/max = 45.7207/32.0725/59.369
[17:36:15.770] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 55.7316 for pixel 1/79 mean/min/max = 44.15/31.9186/56.3813
[17:36:15.771] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 63.8787 for pixel 9/7 mean/min/max = 48.3474/32.7496/63.9452
[17:36:15.771] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 59.7766 for pixel 2/78 mean/min/max = 46.1084/32.3722/59.8446
[17:36:15.771] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 58.4418 for pixel 9/79 mean/min/max = 45.3566/32.0969/58.6163
[17:36:15.771] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 63.785 for pixel 6/41 mean/min/max = 48.3298/32.8499/63.8097
[17:36:15.772] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 63.6038 for pixel 21/23 mean/min/max = 48.9611/34.2139/63.7084
[17:36:15.772] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 57.5961 for pixel 2/75 mean/min/max = 45.3703/33.0418/57.6987
[17:36:15.772] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 56.2346 for pixel 2/74 mean/min/max = 44.6251/32.7862/56.4641
[17:36:15.773] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[17:36:15.861] <TB0> INFO: Expecting 514560 events.
[17:36:28.756] <TB0> INFO: 514560 events read in total (12339ms).
[17:36:28.762] <TB0> INFO: Expecting 514560 events.
[17:36:41.689] <TB0> INFO: 514560 events read in total (12513ms).
[17:36:41.696] <TB0> INFO: Expecting 514560 events.
[17:36:54.742] <TB0> INFO: 514560 events read in total (12628ms).
[17:36:54.751] <TB0> INFO: Expecting 514560 events.
[17:37:07.762] <TB0> INFO: 514560 events read in total (12598ms).
[17:37:07.774] <TB0> INFO: Expecting 514560 events.
[17:37:20.820] <TB0> INFO: 514560 events read in total (12645ms).
[17:37:20.835] <TB0> INFO: Expecting 514560 events.
[17:37:33.744] <TB0> INFO: 514560 events read in total (12507ms).
[17:37:33.760] <TB0> INFO: Expecting 514560 events.
[17:37:46.807] <TB0> INFO: 514560 events read in total (12643ms).
[17:37:46.827] <TB0> INFO: Expecting 514560 events.
[17:37:59.814] <TB0> INFO: 514560 events read in total (12585ms).
[17:37:59.835] <TB0> INFO: Expecting 514560 events.
[17:38:12.785] <TB0> INFO: 514560 events read in total (12548ms).
[17:38:12.806] <TB0> INFO: Expecting 514560 events.
[17:38:25.760] <TB0> INFO: 514560 events read in total (12551ms).
[17:38:25.783] <TB0> INFO: Expecting 514560 events.
[17:38:38.691] <TB0> INFO: 514560 events read in total (12505ms).
[17:38:38.719] <TB0> INFO: Expecting 514560 events.
[17:38:51.699] <TB0> INFO: 514560 events read in total (12578ms).
[17:38:51.732] <TB0> INFO: Expecting 514560 events.
[17:39:05.035] <TB0> INFO: 514560 events read in total (12901ms).
[17:39:05.073] <TB0> INFO: Expecting 514560 events.
[17:39:18.251] <TB0> INFO: 514560 events read in total (12777ms).
[17:39:18.287] <TB0> INFO: Expecting 514560 events.
[17:39:31.404] <TB0> INFO: 514560 events read in total (12715ms).
[17:39:31.441] <TB0> INFO: Expecting 514560 events.
[17:39:44.264] <TB0> INFO: 514560 events read in total (12421ms).
[17:39:44.299] <TB0> INFO: Test took 208526ms.
[17:39:45.308] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[17:39:45.318] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[17:39:45.318] <TB0> INFO: run 1 of 1
[17:39:45.589] <TB0> INFO: Expecting 6281600 events.
[17:40:21.295] <TB0> INFO: 743510 events read in total (35150ms).
[17:40:56.635] <TB0> INFO: 1484840 events read in total (70490ms).
[17:41:31.994] <TB0> INFO: 2226430 events read in total (105849ms).
[17:42:07.169] <TB0> INFO: 2968240 events read in total (141024ms).
[17:42:42.619] <TB0> INFO: 3704230 events read in total (176474ms).
[17:43:17.574] <TB0> INFO: 4435620 events read in total (211429ms).
[17:43:52.335] <TB0> INFO: 5164880 events read in total (246190ms).
[17:44:27.356] <TB0> INFO: 5893820 events read in total (281211ms).
[17:44:46.490] <TB0> INFO: 6281600 events read in total (300345ms).
[17:44:46.535] <TB0> INFO: Test took 301217ms.
[17:45:12.648] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.179458 .. 255.000000
[17:45:12.726] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[17:45:12.735] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[17:45:12.735] <TB0> INFO: run 1 of 1
[17:45:13.001] <TB0> INFO: Expecting 10649600 events.
[17:45:48.227] <TB0> INFO: 712150 events read in total (34670ms).
[17:46:22.796] <TB0> INFO: 1424720 events read in total (69239ms).
[17:46:57.485] <TB0> INFO: 2137240 events read in total (103928ms).
[17:47:32.706] <TB0> INFO: 2850250 events read in total (139149ms).
[17:48:07.219] <TB0> INFO: 3562920 events read in total (173662ms).
[17:48:42.074] <TB0> INFO: 4276010 events read in total (208517ms).
[17:49:16.422] <TB0> INFO: 4988980 events read in total (242865ms).
[17:49:51.083] <TB0> INFO: 5702010 events read in total (277526ms).
[17:50:26.411] <TB0> INFO: 6414590 events read in total (312854ms).
[17:51:00.886] <TB0> INFO: 7126530 events read in total (347329ms).
[17:51:35.786] <TB0> INFO: 7838460 events read in total (382229ms).
[17:52:10.996] <TB0> INFO: 8549790 events read in total (417439ms).
[17:52:45.685] <TB0> INFO: 9260960 events read in total (452128ms).
[17:53:20.194] <TB0> INFO: 9972370 events read in total (486637ms).
[17:53:53.384] <TB0> INFO: 10649600 events read in total (519827ms).
[17:53:53.534] <TB0> INFO: Test took 520799ms.
[17:54:25.636] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 15.029739 .. 44.365698
[17:54:25.719] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 5 .. 54 (-1/-1) hits flags = 528 (plus default)
[17:54:25.731] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[17:54:25.731] <TB0> INFO: run 1 of 1
[17:54:26.033] <TB0> INFO: Expecting 2080000 events.
[17:55:04.410] <TB0> INFO: 945940 events read in total (37821ms).
[17:55:43.019] <TB0> INFO: 1889550 events read in total (76430ms).
[17:55:51.165] <TB0> INFO: 2080000 events read in total (84576ms).
[17:55:51.177] <TB0> INFO: Test took 85446ms.
[17:56:04.195] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 3.500000 .. 41.385236
[17:56:04.273] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 3 .. 51 (-1/-1) hits flags = 528 (plus default)
[17:56:04.282] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[17:56:04.282] <TB0> INFO: run 1 of 1
[17:56:04.551] <TB0> INFO: Expecting 2038400 events.
[17:56:44.424] <TB0> INFO: 985230 events read in total (39317ms).
[17:57:23.302] <TB0> INFO: 1969170 events read in total (78195ms).
[17:57:26.451] <TB0> INFO: 2038400 events read in total (81344ms).
[17:57:26.467] <TB0> INFO: Test took 82186ms.
[17:57:39.293] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 3.500000 .. 40.603297
[17:57:39.370] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 3 .. 50 (-1/-1) hits flags = 528 (plus default)
[17:57:39.378] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[17:57:39.378] <TB0> INFO: run 1 of 1
[17:57:39.641] <TB0> INFO: Expecting 1996800 events.
[17:58:19.348] <TB0> INFO: 995800 events read in total (39151ms).
[17:58:58.654] <TB0> INFO: 1990900 events read in total (78457ms).
[17:58:59.246] <TB0> INFO: 1996800 events read in total (79050ms).
[17:58:59.262] <TB0> INFO: Test took 79884ms.
[17:59:12.766] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[17:59:12.766] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[17:59:12.775] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[17:59:12.775] <TB0> INFO: run 1 of 1
[17:59:13.063] <TB0> INFO: Expecting 1705600 events.
[17:59:51.406] <TB0> INFO: 878520 events read in total (37787ms).
[18:00:26.955] <TB0> INFO: 1705600 events read in total (73336ms).
[18:00:26.974] <TB0> INFO: Test took 74200ms.
[18:00:42.009] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C0.dat
[18:00:42.009] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C1.dat
[18:00:42.010] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C2.dat
[18:00:42.010] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C3.dat
[18:00:42.011] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C4.dat
[18:00:42.011] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C5.dat
[18:00:42.011] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C6.dat
[18:00:42.012] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C7.dat
[18:00:42.012] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C8.dat
[18:00:42.013] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C9.dat
[18:00:42.013] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C10.dat
[18:00:42.014] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C11.dat
[18:00:42.015] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C12.dat
[18:00:42.016] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C13.dat
[18:00:42.016] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C14.dat
[18:00:42.017] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C15.dat
[18:00:42.017] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C0.dat
[18:00:42.026] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C1.dat
[18:00:42.034] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C2.dat
[18:00:42.041] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C3.dat
[18:00:42.049] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C4.dat
[18:00:42.055] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C5.dat
[18:00:42.062] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C6.dat
[18:00:42.068] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C7.dat
[18:00:42.074] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C8.dat
[18:00:42.081] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C9.dat
[18:00:42.090] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C10.dat
[18:00:42.098] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C11.dat
[18:00:42.104] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C12.dat
[18:00:42.111] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C13.dat
[18:00:42.117] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C14.dat
[18:00:42.124] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//trimParameters35_C15.dat
[18:00:42.130] <TB0> INFO: PixTestTrim::trimTest() done
[18:00:42.130] <TB0> INFO: vtrim: 110 108 114 103 104 112 110 114 95 117 112 102 136 137 116 90
[18:00:42.130] <TB0> INFO: vthrcomp: 97 105 102 97 99 107 105 103 99 101 101 98 103 105 103 97
[18:00:42.130] <TB0> INFO: vcal mean: 34.99 35.02 35.03 34.95 34.98 34.99 35.01 35.02 34.96 34.99 34.97 34.96 35.01 35.02 34.97 34.97
[18:00:42.130] <TB0> INFO: vcal RMS: 0.89 0.83 0.92 0.87 0.89 0.83 0.82 0.91 0.81 0.95 1.18 1.04 0.90 0.90 0.89 0.89
[18:00:42.130] <TB0> INFO: bits mean: 9.37 8.84 8.88 10.02 10.11 8.58 8.39 9.56 9.63 8.88 9.52 9.79 8.99 8.53 9.83 9.92
[18:00:42.130] <TB0> INFO: bits RMS: 2.74 2.54 2.49 2.58 2.55 2.71 2.70 2.71 2.73 2.64 2.60 2.58 2.56 2.53 2.46 2.45
[18:00:42.137] <TB0> INFO: ----------------------------------------------------------------------
[18:00:42.137] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[18:00:42.137] <TB0> INFO: ----------------------------------------------------------------------
[18:00:42.140] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[18:00:42.150] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[18:00:42.150] <TB0> INFO: run 1 of 1
[18:00:42.435] <TB0> INFO: Expecting 4160000 events.
[18:01:26.247] <TB0> INFO: 983925 events read in total (43256ms).
[18:02:09.335] <TB0> INFO: 1959425 events read in total (86344ms).
[18:02:52.554] <TB0> INFO: 2923795 events read in total (129563ms).
[18:03:35.674] <TB0> INFO: 3886745 events read in total (172683ms).
[18:03:48.238] <TB0> INFO: 4160000 events read in total (185247ms).
[18:03:48.270] <TB0> INFO: Test took 186120ms.
[18:04:17.674] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 177 (-1/-1) hits flags = 528 (plus default)
[18:04:17.682] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[18:04:17.682] <TB0> INFO: run 1 of 1
[18:04:17.941] <TB0> INFO: Expecting 3702400 events.
[18:05:02.108] <TB0> INFO: 1001890 events read in total (43611ms).
[18:05:45.497] <TB0> INFO: 1993025 events read in total (87000ms).
[18:06:28.629] <TB0> INFO: 2972565 events read in total (130132ms).
[18:07:00.680] <TB0> INFO: 3702400 events read in total (162183ms).
[18:07:00.718] <TB0> INFO: Test took 163036ms.
[18:07:28.598] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 165 (-1/-1) hits flags = 528 (plus default)
[18:07:28.606] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[18:07:28.606] <TB0> INFO: run 1 of 1
[18:07:28.869] <TB0> INFO: Expecting 3452800 events.
[18:08:13.834] <TB0> INFO: 1039360 events read in total (44409ms).
[18:08:57.904] <TB0> INFO: 2064750 events read in total (88479ms).
[18:09:41.621] <TB0> INFO: 3079015 events read in total (132196ms).
[18:09:57.788] <TB0> INFO: 3452800 events read in total (148364ms).
[18:09:57.821] <TB0> INFO: Test took 149215ms.
[18:10:24.843] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 165 (-1/-1) hits flags = 528 (plus default)
[18:10:24.855] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[18:10:24.855] <TB0> INFO: run 1 of 1
[18:10:25.198] <TB0> INFO: Expecting 3452800 events.
[18:11:09.840] <TB0> INFO: 1039135 events read in total (44086ms).
[18:11:53.928] <TB0> INFO: 2064100 events read in total (88174ms).
[18:12:37.696] <TB0> INFO: 3077940 events read in total (131942ms).
[18:12:54.128] <TB0> INFO: 3452800 events read in total (148374ms).
[18:12:54.155] <TB0> INFO: Test took 149300ms.
[18:13:19.331] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 165 (-1/-1) hits flags = 528 (plus default)
[18:13:19.339] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[18:13:19.340] <TB0> INFO: run 1 of 1
[18:13:19.602] <TB0> INFO: Expecting 3452800 events.
[18:14:04.466] <TB0> INFO: 1038960 events read in total (44308ms).
[18:14:47.959] <TB0> INFO: 2063335 events read in total (87801ms).
[18:15:32.054] <TB0> INFO: 3076790 events read in total (131896ms).
[18:15:48.726] <TB0> INFO: 3452800 events read in total (148568ms).
[18:15:48.754] <TB0> INFO: Test took 149414ms.
[18:16:15.588] <TB0> INFO: PixTestTrim::trimBitTest() done
[18:16:15.589] <TB0> INFO: PixTestTrim::doTest() done, duration: 2975 seconds
[18:16:15.600] <TB0> INFO: Decoding statistics:
[18:16:15.600] <TB0> INFO: General information:
[18:16:15.600] <TB0> INFO: 16bit words read: 0
[18:16:15.600] <TB0> INFO: valid events total: 0
[18:16:15.600] <TB0> INFO: empty events: 0
[18:16:15.600] <TB0> INFO: valid events with pixels: 0
[18:16:15.600] <TB0> INFO: valid pixel hits: 0
[18:16:15.600] <TB0> INFO: Event errors: 0
[18:16:15.600] <TB0> INFO: start marker: 0
[18:16:15.600] <TB0> INFO: stop marker: 0
[18:16:15.600] <TB0> INFO: overflow: 0
[18:16:15.600] <TB0> INFO: invalid 5bit words: 0
[18:16:15.600] <TB0> INFO: invalid XOR eye diagram: 0
[18:16:15.600] <TB0> INFO: frame (failed synchr.): 0
[18:16:15.600] <TB0> INFO: idle data (no TBM trl): 0
[18:16:15.600] <TB0> INFO: no data (only TBM hdr): 0
[18:16:15.600] <TB0> INFO: TBM errors: 0
[18:16:15.600] <TB0> INFO: flawed TBM headers: 0
[18:16:15.600] <TB0> INFO: flawed TBM trailers: 0
[18:16:15.600] <TB0> INFO: event ID mismatches: 0
[18:16:15.600] <TB0> INFO: ROC errors: 0
[18:16:15.600] <TB0> INFO: missing ROC header(s): 0
[18:16:15.600] <TB0> INFO: misplaced readback start: 0
[18:16:15.600] <TB0> INFO: Pixel decoding errors: 0
[18:16:15.600] <TB0> INFO: pixel data incomplete: 0
[18:16:15.600] <TB0> INFO: pixel address: 0
[18:16:15.600] <TB0> INFO: pulse height fill bit: 0
[18:16:15.600] <TB0> INFO: buffer corruption: 0
[18:16:16.558] <TB0> INFO: ######################################################################
[18:16:16.558] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[18:16:16.558] <TB0> INFO: ######################################################################
[18:16:16.847] <TB0> INFO: Expecting 41600 events.
[18:16:20.599] <TB0> INFO: 41600 events read in total (3196ms).
[18:16:20.600] <TB0> INFO: Test took 4040ms.
[18:16:21.071] <TB0> INFO: Expecting 41600 events.
[18:16:24.878] <TB0> INFO: 41600 events read in total (3251ms).
[18:16:24.879] <TB0> INFO: Test took 4076ms.
[18:16:25.164] <TB0> INFO: Expecting 41600 events.
[18:16:28.968] <TB0> INFO: 41600 events read in total (3248ms).
[18:16:28.968] <TB0> INFO: Test took 4067ms.
[18:16:29.224] <TB0> INFO: Expecting 2560 events.
[18:16:30.077] <TB0> INFO: 2560 events read in total (297ms).
[18:16:30.077] <TB0> INFO: Test took 1095ms.
[18:16:30.382] <TB0> INFO: Expecting 2560 events.
[18:16:31.234] <TB0> INFO: 2560 events read in total (296ms).
[18:16:31.234] <TB0> INFO: Test took 1157ms.
[18:16:31.539] <TB0> INFO: Expecting 2560 events.
[18:16:32.391] <TB0> INFO: 2560 events read in total (296ms).
[18:16:32.392] <TB0> INFO: Test took 1158ms.
[18:16:32.697] <TB0> INFO: Expecting 2560 events.
[18:16:33.553] <TB0> INFO: 2560 events read in total (300ms).
[18:16:33.553] <TB0> INFO: Test took 1161ms.
[18:16:33.859] <TB0> INFO: Expecting 2560 events.
[18:16:34.718] <TB0> INFO: 2560 events read in total (303ms).
[18:16:34.718] <TB0> INFO: Test took 1164ms.
[18:16:35.023] <TB0> INFO: Expecting 2560 events.
[18:16:35.879] <TB0> INFO: 2560 events read in total (300ms).
[18:16:35.879] <TB0> INFO: Test took 1161ms.
[18:16:36.183] <TB0> INFO: Expecting 2560 events.
[18:16:37.037] <TB0> INFO: 2560 events read in total (298ms).
[18:16:37.037] <TB0> INFO: Test took 1157ms.
[18:16:37.342] <TB0> INFO: Expecting 2560 events.
[18:16:38.202] <TB0> INFO: 2560 events read in total (303ms).
[18:16:38.202] <TB0> INFO: Test took 1164ms.
[18:16:38.507] <TB0> INFO: Expecting 2560 events.
[18:16:39.366] <TB0> INFO: 2560 events read in total (302ms).
[18:16:39.367] <TB0> INFO: Test took 1164ms.
[18:16:39.671] <TB0> INFO: Expecting 2560 events.
[18:16:40.529] <TB0> INFO: 2560 events read in total (302ms).
[18:16:40.529] <TB0> INFO: Test took 1162ms.
[18:16:40.834] <TB0> INFO: Expecting 2560 events.
[18:16:41.686] <TB0> INFO: 2560 events read in total (296ms).
[18:16:41.687] <TB0> INFO: Test took 1158ms.
[18:16:41.992] <TB0> INFO: Expecting 2560 events.
[18:16:42.845] <TB0> INFO: 2560 events read in total (297ms).
[18:16:42.845] <TB0> INFO: Test took 1158ms.
[18:16:43.150] <TB0> INFO: Expecting 2560 events.
[18:16:44.003] <TB0> INFO: 2560 events read in total (297ms).
[18:16:44.004] <TB0> INFO: Test took 1159ms.
[18:16:44.308] <TB0> INFO: Expecting 2560 events.
[18:16:45.160] <TB0> INFO: 2560 events read in total (296ms).
[18:16:45.160] <TB0> INFO: Test took 1156ms.
[18:16:45.465] <TB0> INFO: Expecting 2560 events.
[18:16:46.319] <TB0> INFO: 2560 events read in total (298ms).
[18:16:46.319] <TB0> INFO: Test took 1158ms.
[18:16:46.624] <TB0> INFO: Expecting 2560 events.
[18:16:47.477] <TB0> INFO: 2560 events read in total (297ms).
[18:16:47.478] <TB0> INFO: Test took 1158ms.
[18:16:47.481] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:16:47.783] <TB0> INFO: Expecting 655360 events.
[18:17:04.596] <TB0> INFO: 655360 events read in total (16257ms).
[18:17:04.606] <TB0> INFO: Expecting 655360 events.
[18:17:21.376] <TB0> INFO: 655360 events read in total (16369ms).
[18:17:21.388] <TB0> INFO: Expecting 655360 events.
[18:17:38.020] <TB0> INFO: 655360 events read in total (16230ms).
[18:17:38.035] <TB0> INFO: Expecting 655360 events.
[18:17:54.667] <TB0> INFO: 655360 events read in total (16230ms).
[18:17:54.685] <TB0> INFO: Expecting 655360 events.
[18:18:11.316] <TB0> INFO: 655360 events read in total (16229ms).
[18:18:11.338] <TB0> INFO: Expecting 655360 events.
[18:18:27.944] <TB0> INFO: 655360 events read in total (16204ms).
[18:18:27.971] <TB0> INFO: Expecting 655360 events.
[18:18:44.733] <TB0> INFO: 655360 events read in total (16360ms).
[18:18:44.764] <TB0> INFO: Expecting 655360 events.
[18:19:01.432] <TB0> INFO: 655360 events read in total (16266ms).
[18:19:01.470] <TB0> INFO: Expecting 655360 events.
[18:19:18.231] <TB0> INFO: 655360 events read in total (16359ms).
[18:19:18.268] <TB0> INFO: Expecting 655360 events.
[18:19:34.994] <TB0> INFO: 655360 events read in total (16324ms).
[18:19:35.033] <TB0> INFO: Expecting 655360 events.
[18:19:51.892] <TB0> INFO: 655360 events read in total (16457ms).
[18:19:51.935] <TB0> INFO: Expecting 655360 events.
[18:20:08.757] <TB0> INFO: 655360 events read in total (16420ms).
[18:20:08.809] <TB0> INFO: Expecting 655360 events.
[18:20:25.347] <TB0> INFO: 655360 events read in total (16136ms).
[18:20:25.400] <TB0> INFO: Expecting 655360 events.
[18:20:42.009] <TB0> INFO: 655360 events read in total (16207ms).
[18:20:42.064] <TB0> INFO: Expecting 655360 events.
[18:20:58.664] <TB0> INFO: 655360 events read in total (16198ms).
[18:20:58.735] <TB0> INFO: Expecting 655360 events.
[18:21:15.263] <TB0> INFO: 655360 events read in total (16126ms).
[18:21:15.325] <TB0> INFO: Test took 267844ms.
[18:21:15.405] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:21:15.635] <TB0> INFO: Expecting 655360 events.
[18:21:32.241] <TB0> INFO: 655360 events read in total (16049ms).
[18:21:32.254] <TB0> INFO: Expecting 655360 events.
[18:21:48.853] <TB0> INFO: 655360 events read in total (16198ms).
[18:21:48.866] <TB0> INFO: Expecting 655360 events.
[18:22:05.324] <TB0> INFO: 655360 events read in total (16056ms).
[18:22:05.357] <TB0> INFO: Expecting 655360 events.
[18:22:22.107] <TB0> INFO: 655360 events read in total (16348ms).
[18:22:22.130] <TB0> INFO: Expecting 655360 events.
[18:22:38.908] <TB0> INFO: 655360 events read in total (16377ms).
[18:22:38.935] <TB0> INFO: Expecting 655360 events.
[18:22:55.816] <TB0> INFO: 655360 events read in total (16479ms).
[18:22:55.846] <TB0> INFO: Expecting 655360 events.
[18:23:12.462] <TB0> INFO: 655360 events read in total (16214ms).
[18:23:12.498] <TB0> INFO: Expecting 655360 events.
[18:23:29.408] <TB0> INFO: 655360 events read in total (16508ms).
[18:23:29.445] <TB0> INFO: Expecting 655360 events.
[18:23:46.106] <TB0> INFO: 655360 events read in total (16259ms).
[18:23:46.151] <TB0> INFO: Expecting 655360 events.
[18:24:02.650] <TB0> INFO: 655360 events read in total (16098ms).
[18:24:02.695] <TB0> INFO: Expecting 655360 events.
[18:24:19.463] <TB0> INFO: 655360 events read in total (16367ms).
[18:24:19.512] <TB0> INFO: Expecting 655360 events.
[18:24:36.306] <TB0> INFO: 655360 events read in total (16392ms).
[18:24:36.356] <TB0> INFO: Expecting 655360 events.
[18:24:53.137] <TB0> INFO: 655360 events read in total (16379ms).
[18:24:53.194] <TB0> INFO: Expecting 655360 events.
[18:25:10.178] <TB0> INFO: 655360 events read in total (16583ms).
[18:25:10.234] <TB0> INFO: Expecting 655360 events.
[18:25:26.884] <TB0> INFO: 655360 events read in total (16249ms).
[18:25:26.942] <TB0> INFO: Expecting 655360 events.
[18:25:43.554] <TB0> INFO: 655360 events read in total (16210ms).
[18:25:43.622] <TB0> INFO: Test took 268217ms.
[18:25:43.828] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:25:43.837] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:25:43.844] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:25:43.853] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:25:43.862] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:25:43.868] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:25:43.876] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:25:43.885] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:25:43.894] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:25:43.903] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:25:43.912] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:25:43.921] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[18:25:43.929] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[18:25:43.937] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[18:25:43.946] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[18:25:43.954] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[18:25:43.963] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[18:25:43.969] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[18:25:43.976] <TB0> INFO: safety margin for low PH: adding 8, margin is now 28
[18:25:43.983] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:25:43.991] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:25:43.998] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:25:44.005] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:25:44.012] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[18:25:44.048] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C0.dat
[18:25:44.048] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C1.dat
[18:25:44.048] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C2.dat
[18:25:44.048] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C3.dat
[18:25:44.048] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C4.dat
[18:25:44.049] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C5.dat
[18:25:44.049] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C6.dat
[18:25:44.049] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C7.dat
[18:25:44.049] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C8.dat
[18:25:44.049] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C9.dat
[18:25:44.049] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C10.dat
[18:25:44.049] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C11.dat
[18:25:44.049] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C12.dat
[18:25:44.049] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C13.dat
[18:25:44.050] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C14.dat
[18:25:44.050] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//dacParameters35_C15.dat
[18:25:44.323] <TB0> INFO: Expecting 41600 events.
[18:25:47.794] <TB0> INFO: 41600 events read in total (2915ms).
[18:25:47.795] <TB0> INFO: Test took 3743ms.
[18:25:48.250] <TB0> INFO: Expecting 41600 events.
[18:25:51.720] <TB0> INFO: 41600 events read in total (2913ms).
[18:25:51.720] <TB0> INFO: Test took 3748ms.
[18:25:52.164] <TB0> INFO: Expecting 41600 events.
[18:25:55.678] <TB0> INFO: 41600 events read in total (2958ms).
[18:25:55.679] <TB0> INFO: Test took 3782ms.
[18:25:55.859] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:25:55.947] <TB0> INFO: Expecting 2560 events.
[18:25:56.806] <TB0> INFO: 2560 events read in total (302ms).
[18:25:56.806] <TB0> INFO: Test took 947ms.
[18:25:56.809] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:25:57.112] <TB0> INFO: Expecting 2560 events.
[18:25:57.967] <TB0> INFO: 2560 events read in total (299ms).
[18:25:57.967] <TB0> INFO: Test took 1158ms.
[18:25:57.969] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:25:58.273] <TB0> INFO: Expecting 2560 events.
[18:25:59.129] <TB0> INFO: 2560 events read in total (300ms).
[18:25:59.130] <TB0> INFO: Test took 1161ms.
[18:25:59.132] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:25:59.434] <TB0> INFO: Expecting 2560 events.
[18:26:00.287] <TB0> INFO: 2560 events read in total (297ms).
[18:26:00.288] <TB0> INFO: Test took 1156ms.
[18:26:00.289] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:00.593] <TB0> INFO: Expecting 2560 events.
[18:26:01.448] <TB0> INFO: 2560 events read in total (299ms).
[18:26:01.448] <TB0> INFO: Test took 1159ms.
[18:26:01.449] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:01.754] <TB0> INFO: Expecting 2560 events.
[18:26:02.609] <TB0> INFO: 2560 events read in total (298ms).
[18:26:02.609] <TB0> INFO: Test took 1160ms.
[18:26:02.611] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:02.914] <TB0> INFO: Expecting 2560 events.
[18:26:03.768] <TB0> INFO: 2560 events read in total (298ms).
[18:26:03.768] <TB0> INFO: Test took 1157ms.
[18:26:03.771] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:04.073] <TB0> INFO: Expecting 2560 events.
[18:26:04.931] <TB0> INFO: 2560 events read in total (302ms).
[18:26:04.931] <TB0> INFO: Test took 1160ms.
[18:26:04.933] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:05.236] <TB0> INFO: Expecting 2560 events.
[18:26:06.090] <TB0> INFO: 2560 events read in total (297ms).
[18:26:06.091] <TB0> INFO: Test took 1158ms.
[18:26:06.094] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:06.396] <TB0> INFO: Expecting 2560 events.
[18:26:07.249] <TB0> INFO: 2560 events read in total (297ms).
[18:26:07.249] <TB0> INFO: Test took 1156ms.
[18:26:07.251] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:07.555] <TB0> INFO: Expecting 2560 events.
[18:26:08.413] <TB0> INFO: 2560 events read in total (302ms).
[18:26:08.413] <TB0> INFO: Test took 1162ms.
[18:26:08.416] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:08.719] <TB0> INFO: Expecting 2560 events.
[18:26:09.573] <TB0> INFO: 2560 events read in total (298ms).
[18:26:09.573] <TB0> INFO: Test took 1157ms.
[18:26:09.575] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:09.879] <TB0> INFO: Expecting 2560 events.
[18:26:10.738] <TB0> INFO: 2560 events read in total (302ms).
[18:26:10.738] <TB0> INFO: Test took 1163ms.
[18:26:10.741] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:11.044] <TB0> INFO: Expecting 2560 events.
[18:26:11.902] <TB0> INFO: 2560 events read in total (302ms).
[18:26:11.903] <TB0> INFO: Test took 1162ms.
[18:26:11.906] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:12.208] <TB0> INFO: Expecting 2560 events.
[18:26:13.067] <TB0> INFO: 2560 events read in total (302ms).
[18:26:13.068] <TB0> INFO: Test took 1162ms.
[18:26:13.072] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:13.373] <TB0> INFO: Expecting 2560 events.
[18:26:14.232] <TB0> INFO: 2560 events read in total (303ms).
[18:26:14.232] <TB0> INFO: Test took 1160ms.
[18:26:14.235] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:14.538] <TB0> INFO: Expecting 2560 events.
[18:26:15.396] <TB0> INFO: 2560 events read in total (301ms).
[18:26:15.396] <TB0> INFO: Test took 1162ms.
[18:26:15.398] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:15.702] <TB0> INFO: Expecting 2560 events.
[18:26:16.558] <TB0> INFO: 2560 events read in total (300ms).
[18:26:16.558] <TB0> INFO: Test took 1160ms.
[18:26:16.560] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:16.864] <TB0> INFO: Expecting 2560 events.
[18:26:17.720] <TB0> INFO: 2560 events read in total (300ms).
[18:26:17.720] <TB0> INFO: Test took 1160ms.
[18:26:17.721] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:18.026] <TB0> INFO: Expecting 2560 events.
[18:26:18.883] <TB0> INFO: 2560 events read in total (301ms).
[18:26:18.883] <TB0> INFO: Test took 1162ms.
[18:26:18.885] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:19.190] <TB0> INFO: Expecting 2560 events.
[18:26:20.044] <TB0> INFO: 2560 events read in total (298ms).
[18:26:20.044] <TB0> INFO: Test took 1159ms.
[18:26:20.049] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:20.351] <TB0> INFO: Expecting 2560 events.
[18:26:21.203] <TB0> INFO: 2560 events read in total (296ms).
[18:26:21.203] <TB0> INFO: Test took 1155ms.
[18:26:21.205] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:21.510] <TB0> INFO: Expecting 2560 events.
[18:26:22.364] <TB0> INFO: 2560 events read in total (298ms).
[18:26:22.365] <TB0> INFO: Test took 1160ms.
[18:26:22.367] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:22.670] <TB0> INFO: Expecting 2560 events.
[18:26:23.524] <TB0> INFO: 2560 events read in total (298ms).
[18:26:23.524] <TB0> INFO: Test took 1158ms.
[18:26:23.527] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:23.829] <TB0> INFO: Expecting 2560 events.
[18:26:24.688] <TB0> INFO: 2560 events read in total (303ms).
[18:26:24.688] <TB0> INFO: Test took 1161ms.
[18:26:24.691] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:24.994] <TB0> INFO: Expecting 2560 events.
[18:26:25.849] <TB0> INFO: 2560 events read in total (299ms).
[18:26:25.850] <TB0> INFO: Test took 1159ms.
[18:26:25.853] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:26.156] <TB0> INFO: Expecting 2560 events.
[18:26:27.013] <TB0> INFO: 2560 events read in total (301ms).
[18:26:27.013] <TB0> INFO: Test took 1160ms.
[18:26:27.015] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:27.320] <TB0> INFO: Expecting 2560 events.
[18:26:28.177] <TB0> INFO: 2560 events read in total (301ms).
[18:26:28.178] <TB0> INFO: Test took 1163ms.
[18:26:28.180] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:28.484] <TB0> INFO: Expecting 2560 events.
[18:26:29.339] <TB0> INFO: 2560 events read in total (299ms).
[18:26:29.339] <TB0> INFO: Test took 1159ms.
[18:26:29.342] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:29.645] <TB0> INFO: Expecting 2560 events.
[18:26:30.504] <TB0> INFO: 2560 events read in total (302ms).
[18:26:30.505] <TB0> INFO: Test took 1163ms.
[18:26:30.509] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:30.810] <TB0> INFO: Expecting 2560 events.
[18:26:31.669] <TB0> INFO: 2560 events read in total (303ms).
[18:26:31.669] <TB0> INFO: Test took 1160ms.
[18:26:31.671] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[18:26:31.975] <TB0> INFO: Expecting 2560 events.
[18:26:32.829] <TB0> INFO: 2560 events read in total (298ms).
[18:26:32.829] <TB0> INFO: Test took 1158ms.
[18:26:33.299] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 616 seconds
[18:26:33.299] <TB0> INFO: PH scale (per ROC): 72 70 68 75 76 72 70 71 79 67 80 78 72 71 71 65
[18:26:33.299] <TB0> INFO: PH offset (per ROC): 184 190 187 175 167 179 203 187 172 189 176 181 192 181 198 176
[18:26:33.306] <TB0> INFO: Decoding statistics:
[18:26:33.306] <TB0> INFO: General information:
[18:26:33.306] <TB0> INFO: 16bit words read: 91932
[18:26:33.306] <TB0> INFO: valid events total: 10240
[18:26:33.306] <TB0> INFO: empty events: 7737
[18:26:33.306] <TB0> INFO: valid events with pixels: 2503
[18:26:33.306] <TB0> INFO: valid pixel hits: 2503
[18:26:33.307] <TB0> INFO: Event errors: 0
[18:26:33.307] <TB0> INFO: start marker: 0
[18:26:33.307] <TB0> INFO: stop marker: 0
[18:26:33.307] <TB0> INFO: overflow: 0
[18:26:33.307] <TB0> INFO: invalid 5bit words: 0
[18:26:33.307] <TB0> INFO: invalid XOR eye diagram: 0
[18:26:33.307] <TB0> INFO: frame (failed synchr.): 0
[18:26:33.307] <TB0> INFO: idle data (no TBM trl): 0
[18:26:33.307] <TB0> INFO: no data (only TBM hdr): 0
[18:26:33.307] <TB0> INFO: TBM errors: 0
[18:26:33.307] <TB0> INFO: flawed TBM headers: 0
[18:26:33.307] <TB0> INFO: flawed TBM trailers: 0
[18:26:33.307] <TB0> INFO: event ID mismatches: 0
[18:26:33.307] <TB0> INFO: ROC errors: 0
[18:26:33.307] <TB0> INFO: missing ROC header(s): 0
[18:26:33.307] <TB0> INFO: misplaced readback start: 0
[18:26:33.307] <TB0> INFO: Pixel decoding errors: 0
[18:26:33.307] <TB0> INFO: pixel data incomplete: 0
[18:26:33.307] <TB0> INFO: pixel address: 0
[18:26:33.307] <TB0> INFO: pulse height fill bit: 0
[18:26:33.307] <TB0> INFO: buffer corruption: 0
[18:26:33.474] <TB0> INFO: ######################################################################
[18:26:33.474] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[18:26:33.474] <TB0> INFO: ######################################################################
[18:26:33.484] <TB0> INFO: scanning low vcal = 10
[18:26:33.743] <TB0> INFO: Expecting 41600 events.
[18:26:37.286] <TB0> INFO: 41600 events read in total (2987ms).
[18:26:37.286] <TB0> INFO: Test took 3802ms.
[18:26:37.287] <TB0> INFO: scanning low vcal = 20
[18:26:37.591] <TB0> INFO: Expecting 41600 events.
[18:26:41.138] <TB0> INFO: 41600 events read in total (2991ms).
[18:26:41.138] <TB0> INFO: Test took 3850ms.
[18:26:41.139] <TB0> INFO: scanning low vcal = 30
[18:26:41.443] <TB0> INFO: Expecting 41600 events.
[18:26:45.040] <TB0> INFO: 41600 events read in total (3041ms).
[18:26:45.041] <TB0> INFO: Test took 3902ms.
[18:26:45.042] <TB0> INFO: scanning low vcal = 40
[18:26:45.331] <TB0> INFO: Expecting 41600 events.
[18:26:49.480] <TB0> INFO: 41600 events read in total (3593ms).
[18:26:49.481] <TB0> INFO: Test took 4439ms.
[18:26:49.484] <TB0> INFO: scanning low vcal = 50
[18:26:49.749] <TB0> INFO: Expecting 41600 events.
[18:26:53.943] <TB0> INFO: 41600 events read in total (3638ms).
[18:26:53.943] <TB0> INFO: Test took 4459ms.
[18:26:53.946] <TB0> INFO: scanning low vcal = 60
[18:26:54.208] <TB0> INFO: Expecting 41600 events.
[18:26:58.441] <TB0> INFO: 41600 events read in total (3676ms).
[18:26:58.442] <TB0> INFO: Test took 4496ms.
[18:26:58.444] <TB0> INFO: scanning low vcal = 70
[18:26:58.707] <TB0> INFO: Expecting 41600 events.
[18:27:02.952] <TB0> INFO: 41600 events read in total (3689ms).
[18:27:02.953] <TB0> INFO: Test took 4508ms.
[18:27:02.955] <TB0> INFO: scanning low vcal = 80
[18:27:03.245] <TB0> INFO: Expecting 41600 events.
[18:27:07.438] <TB0> INFO: 41600 events read in total (3637ms).
[18:27:07.438] <TB0> INFO: Test took 4482ms.
[18:27:07.441] <TB0> INFO: scanning low vcal = 90
[18:27:07.699] <TB0> INFO: Expecting 41600 events.
[18:27:11.898] <TB0> INFO: 41600 events read in total (3643ms).
[18:27:11.899] <TB0> INFO: Test took 4458ms.
[18:27:11.906] <TB0> INFO: scanning low vcal = 100
[18:27:12.168] <TB0> INFO: Expecting 41600 events.
[18:27:16.352] <TB0> INFO: 41600 events read in total (3627ms).
[18:27:16.352] <TB0> INFO: Test took 4446ms.
[18:27:16.356] <TB0> INFO: scanning low vcal = 110
[18:27:16.625] <TB0> INFO: Expecting 41600 events.
[18:27:20.812] <TB0> INFO: 41600 events read in total (3630ms).
[18:27:20.812] <TB0> INFO: Test took 4456ms.
[18:27:20.815] <TB0> INFO: scanning low vcal = 120
[18:27:21.079] <TB0> INFO: Expecting 41600 events.
[18:27:25.263] <TB0> INFO: 41600 events read in total (3628ms).
[18:27:25.263] <TB0> INFO: Test took 4448ms.
[18:27:25.267] <TB0> INFO: scanning low vcal = 130
[18:27:25.540] <TB0> INFO: Expecting 41600 events.
[18:27:29.741] <TB0> INFO: 41600 events read in total (3644ms).
[18:27:29.741] <TB0> INFO: Test took 4474ms.
[18:27:29.744] <TB0> INFO: scanning low vcal = 140
[18:27:30.010] <TB0> INFO: Expecting 41600 events.
[18:27:34.187] <TB0> INFO: 41600 events read in total (3620ms).
[18:27:34.188] <TB0> INFO: Test took 4444ms.
[18:27:34.190] <TB0> INFO: scanning low vcal = 150
[18:27:34.464] <TB0> INFO: Expecting 41600 events.
[18:27:38.647] <TB0> INFO: 41600 events read in total (3626ms).
[18:27:38.647] <TB0> INFO: Test took 4458ms.
[18:27:38.650] <TB0> INFO: scanning low vcal = 160
[18:27:38.908] <TB0> INFO: Expecting 41600 events.
[18:27:43.135] <TB0> INFO: 41600 events read in total (3671ms).
[18:27:43.137] <TB0> INFO: Test took 4487ms.
[18:27:43.139] <TB0> INFO: scanning low vcal = 170
[18:27:43.438] <TB0> INFO: Expecting 41600 events.
[18:27:47.617] <TB0> INFO: 41600 events read in total (3623ms).
[18:27:47.618] <TB0> INFO: Test took 4479ms.
[18:27:47.622] <TB0> INFO: scanning low vcal = 180
[18:27:47.887] <TB0> INFO: Expecting 41600 events.
[18:27:52.072] <TB0> INFO: 41600 events read in total (3628ms).
[18:27:52.073] <TB0> INFO: Test took 4451ms.
[18:27:52.075] <TB0> INFO: scanning low vcal = 190
[18:27:52.381] <TB0> INFO: Expecting 41600 events.
[18:27:56.575] <TB0> INFO: 41600 events read in total (3638ms).
[18:27:56.576] <TB0> INFO: Test took 4501ms.
[18:27:56.578] <TB0> INFO: scanning low vcal = 200
[18:27:56.837] <TB0> INFO: Expecting 41600 events.
[18:28:01.019] <TB0> INFO: 41600 events read in total (3626ms).
[18:28:01.019] <TB0> INFO: Test took 4441ms.
[18:28:01.022] <TB0> INFO: scanning low vcal = 210
[18:28:01.279] <TB0> INFO: Expecting 41600 events.
[18:28:05.471] <TB0> INFO: 41600 events read in total (3636ms).
[18:28:05.472] <TB0> INFO: Test took 4450ms.
[18:28:05.474] <TB0> INFO: scanning low vcal = 220
[18:28:05.748] <TB0> INFO: Expecting 41600 events.
[18:28:09.965] <TB0> INFO: 41600 events read in total (3661ms).
[18:28:09.965] <TB0> INFO: Test took 4491ms.
[18:28:09.968] <TB0> INFO: scanning low vcal = 230
[18:28:10.230] <TB0> INFO: Expecting 41600 events.
[18:28:14.409] <TB0> INFO: 41600 events read in total (3623ms).
[18:28:14.409] <TB0> INFO: Test took 4441ms.
[18:28:14.412] <TB0> INFO: scanning low vcal = 240
[18:28:14.691] <TB0> INFO: Expecting 41600 events.
[18:28:18.890] <TB0> INFO: 41600 events read in total (3643ms).
[18:28:18.890] <TB0> INFO: Test took 4478ms.
[18:28:18.893] <TB0> INFO: scanning low vcal = 250
[18:28:19.158] <TB0> INFO: Expecting 41600 events.
[18:28:23.334] <TB0> INFO: 41600 events read in total (3620ms).
[18:28:23.334] <TB0> INFO: Test took 4441ms.
[18:28:23.338] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[18:28:23.601] <TB0> INFO: Expecting 41600 events.
[18:28:27.808] <TB0> INFO: 41600 events read in total (3651ms).
[18:28:27.808] <TB0> INFO: Test took 4470ms.
[18:28:27.811] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[18:28:28.066] <TB0> INFO: Expecting 41600 events.
[18:28:32.293] <TB0> INFO: 41600 events read in total (3670ms).
[18:28:32.294] <TB0> INFO: Test took 4483ms.
[18:28:32.296] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[18:28:32.602] <TB0> INFO: Expecting 41600 events.
[18:28:36.815] <TB0> INFO: 41600 events read in total (3657ms).
[18:28:36.816] <TB0> INFO: Test took 4520ms.
[18:28:36.819] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[18:28:37.119] <TB0> INFO: Expecting 41600 events.
[18:28:41.317] <TB0> INFO: 41600 events read in total (3641ms).
[18:28:41.318] <TB0> INFO: Test took 4499ms.
[18:28:41.320] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[18:28:41.608] <TB0> INFO: Expecting 41600 events.
[18:28:45.791] <TB0> INFO: 41600 events read in total (3627ms).
[18:28:45.792] <TB0> INFO: Test took 4472ms.
[18:28:46.243] <TB0> INFO: PixTestGainPedestal::measure() done
[18:29:20.166] <TB0> INFO: PixTestGainPedestal::fit() done
[18:29:20.166] <TB0> INFO: non-linearity mean: 0.947 0.962 0.963 0.954 0.958 0.959 0.959 0.956 0.957 0.957 0.965 0.963 0.953 0.954 0.961 0.956
[18:29:20.166] <TB0> INFO: non-linearity RMS: 0.009 0.007 0.008 0.007 0.006 0.007 0.007 0.007 0.007 0.007 0.004 0.008 0.008 0.006 0.006 0.006
[18:29:20.166] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[18:29:20.196] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[18:29:20.225] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[18:29:20.249] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[18:29:20.282] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[18:29:20.301] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[18:29:20.320] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[18:29:20.349] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[18:29:20.379] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[18:29:20.409] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[18:29:20.437] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[18:29:20.454] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[18:29:20.472] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[18:29:20.490] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[18:29:20.508] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[18:29:20.526] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[18:29:20.544] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 167 seconds
[18:29:20.544] <TB0> INFO: Decoding statistics:
[18:29:20.544] <TB0> INFO: General information:
[18:29:20.544] <TB0> INFO: 16bit words read: 2662400
[18:29:20.544] <TB0> INFO: valid events total: 166400
[18:29:20.544] <TB0> INFO: empty events: 0
[18:29:20.544] <TB0> INFO: valid events with pixels: 166400
[18:29:20.544] <TB0> INFO: valid pixel hits: 665535
[18:29:20.544] <TB0> INFO: Event errors: 0
[18:29:20.544] <TB0> INFO: start marker: 0
[18:29:20.544] <TB0> INFO: stop marker: 0
[18:29:20.544] <TB0> INFO: overflow: 0
[18:29:20.544] <TB0> INFO: invalid 5bit words: 0
[18:29:20.544] <TB0> INFO: invalid XOR eye diagram: 0
[18:29:20.544] <TB0> INFO: frame (failed synchr.): 0
[18:29:20.544] <TB0> INFO: idle data (no TBM trl): 0
[18:29:20.544] <TB0> INFO: no data (only TBM hdr): 0
[18:29:20.544] <TB0> INFO: TBM errors: 0
[18:29:20.544] <TB0> INFO: flawed TBM headers: 0
[18:29:20.544] <TB0> INFO: flawed TBM trailers: 0
[18:29:20.544] <TB0> INFO: event ID mismatches: 0
[18:29:20.544] <TB0> INFO: ROC errors: 0
[18:29:20.544] <TB0> INFO: missing ROC header(s): 0
[18:29:20.544] <TB0> INFO: misplaced readback start: 0
[18:29:20.544] <TB0> INFO: Pixel decoding errors: 0
[18:29:20.544] <TB0> INFO: pixel data incomplete: 0
[18:29:20.544] <TB0> INFO: pixel address: 0
[18:29:20.544] <TB0> INFO: pulse height fill bit: 0
[18:29:20.544] <TB0> INFO: buffer corruption: 0
[18:29:20.552] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C15.dat
[18:29:20.596] <TB0> INFO: ######################################################################
[18:29:20.596] <TB0> INFO: PixTestReadback::doTest()
[18:29:20.596] <TB0> INFO: ######################################################################
[18:29:20.596] <TB0> INFO: ----------------------------------------------------------------------
[18:29:20.596] <TB0> INFO: PixTestReadback::CalibrateVd()
[18:29:20.596] <TB0> INFO: ----------------------------------------------------------------------
[18:29:30.051] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C0.dat
[18:29:30.051] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C1.dat
[18:29:30.051] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C2.dat
[18:29:30.051] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C3.dat
[18:29:30.051] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C4.dat
[18:29:30.051] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C5.dat
[18:29:30.051] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C6.dat
[18:29:30.051] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C7.dat
[18:29:30.051] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C8.dat
[18:29:30.051] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C9.dat
[18:29:30.052] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C10.dat
[18:29:30.052] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C11.dat
[18:29:30.052] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C12.dat
[18:29:30.052] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C13.dat
[18:29:30.052] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C14.dat
[18:29:30.052] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C15.dat
[18:29:30.080] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[18:29:30.080] <TB0> INFO: ----------------------------------------------------------------------
[18:29:30.080] <TB0> INFO: PixTestReadback::CalibrateVa()
[18:29:30.080] <TB0> INFO: ----------------------------------------------------------------------
[18:29:39.557] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C0.dat
[18:29:39.557] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C1.dat
[18:29:39.557] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C2.dat
[18:29:39.557] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C3.dat
[18:29:39.557] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C4.dat
[18:29:39.557] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C5.dat
[18:29:39.557] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C6.dat
[18:29:39.558] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C7.dat
[18:29:39.558] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C8.dat
[18:29:39.558] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C9.dat
[18:29:39.558] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C10.dat
[18:29:39.558] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C11.dat
[18:29:39.558] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C12.dat
[18:29:39.558] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C13.dat
[18:29:39.558] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C14.dat
[18:29:39.558] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C15.dat
[18:29:39.586] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[18:29:39.586] <TB0> INFO: ----------------------------------------------------------------------
[18:29:39.586] <TB0> INFO: PixTestReadback::readbackVbg()
[18:29:39.586] <TB0> INFO: ----------------------------------------------------------------------
[18:29:46.901] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[18:29:46.901] <TB0> INFO: ----------------------------------------------------------------------
[18:29:46.901] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[18:29:46.901] <TB0> INFO: ----------------------------------------------------------------------
[18:29:46.901] <TB0> INFO: Vbg will be calibrated using Vd calibration
[18:29:46.901] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 152.5calibrated Vbg = 1.24048 :::*/*/*/*/
[18:29:46.901] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 148.7calibrated Vbg = 1.23801 :::*/*/*/*/
[18:29:46.901] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 148.4calibrated Vbg = 1.24841 :::*/*/*/*/
[18:29:46.901] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 148.2calibrated Vbg = 1.23956 :::*/*/*/*/
[18:29:46.901] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 156.6calibrated Vbg = 1.25295 :::*/*/*/*/
[18:29:46.901] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 150.6calibrated Vbg = 1.2487 :::*/*/*/*/
[18:29:46.901] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 155.6calibrated Vbg = 1.24988 :::*/*/*/*/
[18:29:46.901] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 154.4calibrated Vbg = 1.25259 :::*/*/*/*/
[18:29:46.901] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 153.4calibrated Vbg = 1.26788 :::*/*/*/*/
[18:29:46.901] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 155.8calibrated Vbg = 1.26518 :::*/*/*/*/
[18:29:46.901] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 152.3calibrated Vbg = 1.26412 :::*/*/*/*/
[18:29:46.901] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 153.4calibrated Vbg = 1.26525 :::*/*/*/*/
[18:29:46.901] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 147.7calibrated Vbg = 1.25968 :::*/*/*/*/
[18:29:46.901] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 155.7calibrated Vbg = 1.25867 :::*/*/*/*/
[18:29:46.901] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 157.1calibrated Vbg = 1.26008 :::*/*/*/*/
[18:29:46.901] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 153.2calibrated Vbg = 1.26165 :::*/*/*/*/
[18:29:46.903] <TB0> INFO: ----------------------------------------------------------------------
[18:29:46.903] <TB0> INFO: PixTestReadback::CalibrateIa()
[18:29:46.903] <TB0> INFO: ----------------------------------------------------------------------
[18:32:22.332] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C0.dat
[18:32:22.332] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C1.dat
[18:32:22.333] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C2.dat
[18:32:22.333] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C3.dat
[18:32:22.333] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C4.dat
[18:32:22.333] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C5.dat
[18:32:22.333] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C6.dat
[18:32:22.333] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C7.dat
[18:32:22.333] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C8.dat
[18:32:22.333] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C9.dat
[18:32:22.333] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C10.dat
[18:32:22.333] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C11.dat
[18:32:22.333] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C12.dat
[18:32:22.333] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C13.dat
[18:32:22.333] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C14.dat
[18:32:22.333] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2152_FullQualification17C_2016-02-08_18h00m_1454950856//000_FulltestPxar_p17//readbackCal_C15.dat
[18:32:22.362] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[18:32:22.363] <TB0> INFO: PixTestReadback::doTest() done
[18:32:22.363] <TB0> INFO: Decoding statistics:
[18:32:22.363] <TB0> INFO: General information:
[18:32:22.363] <TB0> INFO: 16bit words read: 1024
[18:32:22.363] <TB0> INFO: valid events total: 128
[18:32:22.363] <TB0> INFO: empty events: 128
[18:32:22.363] <TB0> INFO: valid events with pixels: 0
[18:32:22.363] <TB0> INFO: valid pixel hits: 0
[18:32:22.363] <TB0> INFO: Event errors: 0
[18:32:22.363] <TB0> INFO: start marker: 0
[18:32:22.363] <TB0> INFO: stop marker: 0
[18:32:22.363] <TB0> INFO: overflow: 0
[18:32:22.363] <TB0> INFO: invalid 5bit words: 0
[18:32:22.363] <TB0> INFO: invalid XOR eye diagram: 0
[18:32:22.363] <TB0> INFO: frame (failed synchr.): 0
[18:32:22.363] <TB0> INFO: idle data (no TBM trl): 0
[18:32:22.363] <TB0> INFO: no data (only TBM hdr): 0
[18:32:22.363] <TB0> INFO: TBM errors: 0
[18:32:22.363] <TB0> INFO: flawed TBM headers: 0
[18:32:22.363] <TB0> INFO: flawed TBM trailers: 0
[18:32:22.363] <TB0> INFO: event ID mismatches: 0
[18:32:22.363] <TB0> INFO: ROC errors: 0
[18:32:22.363] <TB0> INFO: missing ROC header(s): 0
[18:32:22.363] <TB0> INFO: misplaced readback start: 0
[18:32:22.363] <TB0> INFO: Pixel decoding errors: 0
[18:32:22.363] <TB0> INFO: pixel data incomplete: 0
[18:32:22.363] <TB0> INFO: pixel address: 0
[18:32:22.363] <TB0> INFO: pulse height fill bit: 0
[18:32:22.363] <TB0> INFO: buffer corruption: 0
[18:32:22.382] <TB0> INFO: Decoding statistics:
[18:32:22.382] <TB0> INFO: General information:
[18:32:22.382] <TB0> INFO: 16bit words read: 2755356
[18:32:22.382] <TB0> INFO: valid events total: 176768
[18:32:22.382] <TB0> INFO: empty events: 7865
[18:32:22.382] <TB0> INFO: valid events with pixels: 168903
[18:32:22.382] <TB0> INFO: valid pixel hits: 668038
[18:32:22.382] <TB0> INFO: Event errors: 0
[18:32:22.382] <TB0> INFO: start marker: 0
[18:32:22.382] <TB0> INFO: stop marker: 0
[18:32:22.382] <TB0> INFO: overflow: 0
[18:32:22.382] <TB0> INFO: invalid 5bit words: 0
[18:32:22.382] <TB0> INFO: invalid XOR eye diagram: 0
[18:32:22.382] <TB0> INFO: frame (failed synchr.): 0
[18:32:22.382] <TB0> INFO: idle data (no TBM trl): 0
[18:32:22.382] <TB0> INFO: no data (only TBM hdr): 0
[18:32:22.382] <TB0> INFO: TBM errors: 0
[18:32:22.382] <TB0> INFO: flawed TBM headers: 0
[18:32:22.382] <TB0> INFO: flawed TBM trailers: 0
[18:32:22.382] <TB0> INFO: event ID mismatches: 0
[18:32:22.382] <TB0> INFO: ROC errors: 0
[18:32:22.382] <TB0> INFO: missing ROC header(s): 0
[18:32:22.382] <TB0> INFO: misplaced readback start: 0
[18:32:22.382] <TB0> INFO: Pixel decoding errors: 0
[18:32:22.382] <TB0> INFO: pixel data incomplete: 0
[18:32:22.382] <TB0> INFO: pixel address: 0
[18:32:22.382] <TB0> INFO: pulse height fill bit: 0
[18:32:22.382] <TB0> INFO: buffer corruption: 0
[18:32:22.382] <TB0> INFO: enter test to run
[18:32:22.382] <TB0> INFO: test: exit no parameter change
[18:32:22.571] <TB0> QUIET: Connection to board 73 closed.
[18:32:22.665] <TB0> INFO: pXar: this is the end, my friend