[12:56:47.592] <TB1> INFO: *** Welcome to pxar ***
[12:56:47.592] <TB1> INFO: *** Today: 2016/09/26
[12:56:47.702] <TB1> INFO: *** Version: fe94
[12:56:47.702] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters_C15.dat
[12:56:47.704] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//tbmParameters_C0b.dat
[12:56:47.704] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//defaultMaskFile.dat
[12:56:47.704] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//trimParameters_C15.dat
[12:56:47.773] <TB1> INFO: clk: 4
[12:56:47.773] <TB1> INFO: ctr: 4
[12:56:47.773] <TB1> INFO: sda: 19
[12:56:47.773] <TB1> INFO: tin: 9
[12:56:47.773] <TB1> INFO: level: 15
[12:56:47.773] <TB1> INFO: triggerdelay: 0
[12:56:47.773] <TB1> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[12:56:47.773] <TB1> INFO: Log level: INFO
[12:56:47.780] <TB1> INFO: Found DTB DTB_WXBYFL
[12:56:47.791] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[12:56:47.793] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[12:56:47.794] <TB1> INFO: RPC call hashes of host and DTB match: 486171790
[12:56:49.298] <TB1> INFO: DUT info:
[12:56:49.298] <TB1> INFO: The DUT currently contains the following objects:
[12:56:49.298] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[12:56:49.298] <TB1> INFO: TBM Core alpha (0): 7 registers set
[12:56:49.298] <TB1> INFO: TBM Core beta (1): 7 registers set
[12:56:49.298] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:56:49.298] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:49.298] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:49.298] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:49.298] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:49.298] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:49.298] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:49.298] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:49.298] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:49.298] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:49.298] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:49.298] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:49.299] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:49.299] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:49.299] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:49.299] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:49.299] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:56:49.700] <TB1> INFO: enter 'restricted' command line mode
[12:56:49.700] <TB1> INFO: enter test to run
[12:56:49.700] <TB1> INFO: test: pretest no parameter change
[12:56:49.700] <TB1> INFO: running: pretest
[12:56:49.706] <TB1> INFO: ----------------------------------------------------------------------
[12:56:49.706] <TB1> INFO: PixTestPretest::programROC()
[12:56:49.706] <TB1> INFO: ----------------------------------------------------------------------
[12:57:07.722] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:57:07.722] <TB1> INFO: IA differences per ROC: 20.9 19.3 17.7 18.5 19.3 17.7 20.1 16.9 20.9 18.5 20.1 20.1 20.1 20.1 17.7 20.1
[12:57:07.826] <TB1> INFO: enter test to run
[12:57:07.826] <TB1> INFO: test: pretest no parameter change
[12:57:07.826] <TB1> INFO: running: pretest
[12:57:07.827] <TB1> INFO: ----------------------------------------------------------------------
[12:57:07.827] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:57:07.827] <TB1> INFO: ----------------------------------------------------------------------
[12:57:13.713] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 383.5 mA = 23.9688 mA/ROC
[12:57:13.713] <TB1> INFO: i(loss) [mA/ROC]: 20.1 19.3 20.1 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3 19.3
[12:57:13.751] <TB1> INFO: enter test to run
[12:57:13.751] <TB1> INFO: test: pretest no parameter change
[12:57:13.751] <TB1> INFO: running: pretest
[12:57:13.751] <TB1> INFO: ----------------------------------------------------------------------
[12:57:13.751] <TB1> INFO: PixTestPretest::findTiming()
[12:57:13.751] <TB1> INFO: ----------------------------------------------------------------------
[12:57:13.752] <TB1> INFO: PixTestCmd::init()

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


[12:58:53.991] <TB1> INFO: 231680 events read in total (8699ms).
[12:58:53.997] <TB1> INFO: Test took 9348ms.
[12:58:54.201] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[12:58:54.262] <TB1> INFO: enter test to run
[12:58:54.262] <TB1> INFO: test: pretest no parameter change
[12:58:54.262] <TB1> INFO: running: pretest
[12:58:54.263] <TB1> INFO: ----------------------------------------------------------------------
[12:58:54.263] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[12:58:54.263] <TB1> INFO: ----------------------------------------------------------------------
[12:58:54.355] <TB1> INFO: Expecting 231680 events.
[12:59:03.644] <TB1> INFO: 231680 events read in total (8732ms).
[12:59:03.651] <TB1> INFO: Test took 9384ms.
[12:59:03.879] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[12:59:03.879] <TB1> INFO: CalDel: 151 129 151 143 145 157 138 129 151 164 158 148 144 140 140 157
[12:59:03.879] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[12:59:03.912] <TB1> INFO: enter test to run
[12:59:03.912] <TB1> INFO: test: pretest no parameter change
[12:59:03.912] <TB1> INFO: running: pretest
[12:59:03.912] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters_C0.dat
[12:59:03.912] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters_C1.dat
[12:59:03.912] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters_C2.dat
[12:59:03.913] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters_C3.dat
[12:59:03.913] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters_C4.dat
[12:59:03.913] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters_C5.dat
[12:59:03.913] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters_C6.dat
[12:59:03.913] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters_C7.dat
[12:59:03.913] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters_C8.dat
[12:59:03.913] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters_C9.dat
[12:59:03.914] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters_C10.dat
[12:59:03.914] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters_C11.dat
[12:59:03.914] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters_C12.dat
[12:59:03.914] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters_C13.dat
[12:59:03.914] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters_C14.dat
[12:59:03.914] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters_C15.dat
[12:59:03.914] <TB1> INFO: enter test to run
[12:59:03.914] <TB1> INFO: test: fulltest no parameter change
[12:59:03.914] <TB1> INFO: running: fulltest
[12:59:03.914] <TB1> INFO: ######################################################################
[12:59:03.914] <TB1> INFO: PixTestFullTest::doTest()
[12:59:03.914] <TB1> INFO: ######################################################################
[12:59:03.915] <TB1> INFO: ######################################################################
[12:59:03.915] <TB1> INFO: PixTestAlive::doTest()
[12:59:03.915] <TB1> INFO: ######################################################################
[12:59:03.917] <TB1> INFO: ----------------------------------------------------------------------
[12:59:03.917] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:59:03.917] <TB1> INFO: ----------------------------------------------------------------------
[12:59:04.202] <TB1> INFO: Expecting 41600 events.
[12:59:07.660] <TB1> INFO: 41600 events read in total (2902ms).
[12:59:07.661] <TB1> INFO: Test took 3743ms.
[12:59:07.885] <TB1> INFO: PixTestAlive::aliveTest() done
[12:59:07.885] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 4 0 0
[12:59:07.887] <TB1> INFO: ----------------------------------------------------------------------
[12:59:07.887] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:59:07.887] <TB1> INFO: ----------------------------------------------------------------------
[12:59:08.154] <TB1> INFO: Expecting 41600 events.
[12:59:10.928] <TB1> INFO: 41600 events read in total (2218ms).
[12:59:10.928] <TB1> INFO: Test took 3039ms.
[12:59:10.929] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:59:11.164] <TB1> INFO: PixTestAlive::maskTest() done
[12:59:11.164] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:59:11.166] <TB1> INFO: ----------------------------------------------------------------------
[12:59:11.166] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:59:11.166] <TB1> INFO: ----------------------------------------------------------------------
[12:59:11.438] <TB1> INFO: Expecting 41600 events.
[12:59:14.847] <TB1> INFO: 41600 events read in total (2853ms).
[12:59:14.848] <TB1> INFO: Test took 3681ms.
[12:59:15.078] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[12:59:15.078] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:59:15.078] <TB1> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[12:59:15.078] <TB1> INFO: Decoding statistics:
[12:59:15.078] <TB1> INFO: General information:
[12:59:15.078] <TB1> INFO: 16bit words read: 0
[12:59:15.078] <TB1> INFO: valid events total: 0
[12:59:15.078] <TB1> INFO: empty events: 0
[12:59:15.078] <TB1> INFO: valid events with pixels: 0
[12:59:15.078] <TB1> INFO: valid pixel hits: 0
[12:59:15.078] <TB1> INFO: Event errors: 0
[12:59:15.078] <TB1> INFO: start marker: 0
[12:59:15.078] <TB1> INFO: stop marker: 0
[12:59:15.078] <TB1> INFO: overflow: 0
[12:59:15.078] <TB1> INFO: invalid 5bit words: 0
[12:59:15.078] <TB1> INFO: invalid XOR eye diagram: 0
[12:59:15.078] <TB1> INFO: frame (failed synchr.): 0
[12:59:15.078] <TB1> INFO: idle data (no TBM trl): 0
[12:59:15.078] <TB1> INFO: no data (only TBM hdr): 0
[12:59:15.078] <TB1> INFO: TBM errors: 0
[12:59:15.078] <TB1> INFO: flawed TBM headers: 0
[12:59:15.078] <TB1> INFO: flawed TBM trailers: 0
[12:59:15.078] <TB1> INFO: event ID mismatches: 0
[12:59:15.078] <TB1> INFO: ROC errors: 0
[12:59:15.078] <TB1> INFO: missing ROC header(s): 0
[12:59:15.078] <TB1> INFO: misplaced readback start: 0
[12:59:15.079] <TB1> INFO: Pixel decoding errors: 0
[12:59:15.079] <TB1> INFO: pixel data incomplete: 0
[12:59:15.079] <TB1> INFO: pixel address: 0
[12:59:15.079] <TB1> INFO: pulse height fill bit: 0
[12:59:15.079] <TB1> INFO: buffer corruption: 0
[12:59:15.087] <TB1> INFO: ######################################################################
[12:59:15.087] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[12:59:15.087] <TB1> INFO: ######################################################################
[12:59:15.089] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[12:59:15.100] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[12:59:15.100] <TB1> INFO: run 1 of 1
[12:59:15.360] <TB1> INFO: Expecting 3120000 events.
[12:59:52.252] <TB1> INFO: 858535 events read in total (36336ms).
[13:00:28.845] <TB1> INFO: 1699235 events read in total (72931ms).
[13:01:05.324] <TB1> INFO: 2549500 events read in total (109408ms).
[13:01:29.770] <TB1> INFO: 3120000 events read in total (133854ms).
[13:01:29.855] <TB1> INFO: Test took 134755ms.
[13:01:53.340] <TB1> INFO: PixTestBBMap::doTest() done, duration: 158 seconds
[13:01:53.340] <TB1> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0 0
[13:01:53.340] <TB1> INFO: separation cut (per ROC): 89 79 84 85 83 83 93 89 75 78 86 86 79 80 70 87
[13:01:53.340] <TB1> INFO: Decoding statistics:
[13:01:53.340] <TB1> INFO: General information:
[13:01:53.340] <TB1> INFO: 16bit words read: 0
[13:01:53.340] <TB1> INFO: valid events total: 0
[13:01:53.340] <TB1> INFO: empty events: 0
[13:01:53.340] <TB1> INFO: valid events with pixels: 0
[13:01:53.340] <TB1> INFO: valid pixel hits: 0
[13:01:53.340] <TB1> INFO: Event errors: 0
[13:01:53.340] <TB1> INFO: start marker: 0
[13:01:53.340] <TB1> INFO: stop marker: 0
[13:01:53.340] <TB1> INFO: overflow: 0
[13:01:53.340] <TB1> INFO: invalid 5bit words: 0
[13:01:53.340] <TB1> INFO: invalid XOR eye diagram: 0
[13:01:53.340] <TB1> INFO: frame (failed synchr.): 0
[13:01:53.340] <TB1> INFO: idle data (no TBM trl): 0
[13:01:53.340] <TB1> INFO: no data (only TBM hdr): 0
[13:01:53.340] <TB1> INFO: TBM errors: 0
[13:01:53.340] <TB1> INFO: flawed TBM headers: 0
[13:01:53.340] <TB1> INFO: flawed TBM trailers: 0
[13:01:53.340] <TB1> INFO: event ID mismatches: 0
[13:01:53.340] <TB1> INFO: ROC errors: 0
[13:01:53.341] <TB1> INFO: missing ROC header(s): 0
[13:01:53.341] <TB1> INFO: misplaced readback start: 0
[13:01:53.341] <TB1> INFO: Pixel decoding errors: 0
[13:01:53.341] <TB1> INFO: pixel data incomplete: 0
[13:01:53.341] <TB1> INFO: pixel address: 0
[13:01:53.341] <TB1> INFO: pulse height fill bit: 0
[13:01:53.341] <TB1> INFO: buffer corruption: 0
[13:01:53.447] <TB1> INFO: ######################################################################
[13:01:53.447] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[13:01:53.447] <TB1> INFO: ######################################################################
[13:01:53.447] <TB1> INFO: ----------------------------------------------------------------------
[13:01:53.447] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[13:01:53.447] <TB1> INFO: ----------------------------------------------------------------------
[13:01:53.447] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[13:01:53.458] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:01:53.458] <TB1> INFO: run 1 of 1
[13:01:53.746] <TB1> INFO: Expecting 36608000 events.
[13:02:23.329] <TB1> INFO: 814450 events read in total (29026ms).
[13:02:51.250] <TB1> INFO: 1614650 events read in total (56947ms).
[13:03:19.783] <TB1> INFO: 2415000 events read in total (85480ms).
[13:03:48.275] <TB1> INFO: 3215200 events read in total (113972ms).
[13:04:17.112] <TB1> INFO: 4015300 events read in total (142809ms).
[13:04:44.879] <TB1> INFO: 4816400 events read in total (170576ms).
[13:05:13.299] <TB1> INFO: 5614450 events read in total (198996ms).
[13:05:41.629] <TB1> INFO: 6416400 events read in total (227326ms).
[13:06:09.752] <TB1> INFO: 7213850 events read in total (255449ms).
[13:06:38.107] <TB1> INFO: 8014000 events read in total (283804ms).
[13:07:06.435] <TB1> INFO: 8814350 events read in total (312132ms).
[13:07:34.958] <TB1> INFO: 9613400 events read in total (340655ms).
[13:08:03.524] <TB1> INFO: 10413150 events read in total (369221ms).
[13:08:31.583] <TB1> INFO: 11213200 events read in total (397280ms).
[13:08:58.923] <TB1> INFO: 12010950 events read in total (424620ms).
[13:09:27.455] <TB1> INFO: 12812000 events read in total (453152ms).
[13:09:55.452] <TB1> INFO: 13611350 events read in total (481149ms).
[13:10:24.079] <TB1> INFO: 14409650 events read in total (509776ms).
[13:10:52.547] <TB1> INFO: 15206350 events read in total (538244ms).
[13:11:21.039] <TB1> INFO: 16004850 events read in total (566736ms).
[13:11:48.879] <TB1> INFO: 16804200 events read in total (594576ms).
[13:12:17.206] <TB1> INFO: 17603500 events read in total (622903ms).
[13:12:45.772] <TB1> INFO: 18398900 events read in total (651469ms).
[13:13:13.688] <TB1> INFO: 19194200 events read in total (679385ms).
[13:13:41.797] <TB1> INFO: 19988900 events read in total (707494ms).
[13:14:09.705] <TB1> INFO: 20780950 events read in total (735402ms).
[13:14:37.530] <TB1> INFO: 21573150 events read in total (763227ms).
[13:15:05.474] <TB1> INFO: 22364300 events read in total (791171ms).
[13:15:33.564] <TB1> INFO: 23155100 events read in total (819261ms).
[13:16:01.416] <TB1> INFO: 23944450 events read in total (847113ms).
[13:16:29.389] <TB1> INFO: 24736600 events read in total (875086ms).
[13:16:58.098] <TB1> INFO: 25527800 events read in total (903795ms).
[13:17:26.567] <TB1> INFO: 26319350 events read in total (932264ms).
[13:17:54.730] <TB1> INFO: 27109500 events read in total (960427ms).
[13:18:22.837] <TB1> INFO: 27899850 events read in total (988534ms).
[13:18:51.161] <TB1> INFO: 28691050 events read in total (1016858ms).
[13:19:19.649] <TB1> INFO: 29481400 events read in total (1045346ms).
[13:19:48.765] <TB1> INFO: 30273700 events read in total (1074462ms).
[13:20:18.324] <TB1> INFO: 31063200 events read in total (1104021ms).
[13:20:46.801] <TB1> INFO: 31854400 events read in total (1132498ms).
[13:21:14.878] <TB1> INFO: 32646600 events read in total (1160575ms).
[13:21:42.968] <TB1> INFO: 33437850 events read in total (1188665ms).
[13:22:11.227] <TB1> INFO: 34228950 events read in total (1216924ms).
[13:22:39.351] <TB1> INFO: 35019950 events read in total (1245048ms).
[13:23:07.257] <TB1> INFO: 35811150 events read in total (1272954ms).
[13:23:35.371] <TB1> INFO: 36608000 events read in total (1301068ms).
[13:23:35.437] <TB1> INFO: Test took 1301979ms.
[13:23:35.881] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:23:37.710] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:23:39.435] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:23:41.206] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:23:43.185] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:23:45.031] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:23:46.840] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:23:48.520] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:23:50.219] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:23:51.871] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:23:53.527] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:23:55.233] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:23:56.904] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:23:58.934] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:24:00.578] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:24:02.267] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:24:03.997] <TB1> INFO: PixTestScurves::scurves() done
[13:24:03.997] <TB1> INFO: Vcal mean: 88.73 77.79 83.12 89.24 79.45 84.14 89.10 95.26 82.89 83.58 82.11 84.20 83.63 81.56 75.45 88.64
[13:24:03.997] <TB1> INFO: Vcal RMS: 4.83 4.32 4.52 4.98 3.68 4.45 4.81 4.93 4.38 4.85 4.74 4.98 4.70 4.78 4.13 4.92
[13:24:03.997] <TB1> INFO: PixTestScurves::fullTest() done, duration: 1330 seconds
[13:24:03.997] <TB1> INFO: Decoding statistics:
[13:24:03.997] <TB1> INFO: General information:
[13:24:03.997] <TB1> INFO: 16bit words read: 0
[13:24:03.997] <TB1> INFO: valid events total: 0
[13:24:03.997] <TB1> INFO: empty events: 0
[13:24:03.997] <TB1> INFO: valid events with pixels: 0
[13:24:03.997] <TB1> INFO: valid pixel hits: 0
[13:24:03.997] <TB1> INFO: Event errors: 0
[13:24:03.997] <TB1> INFO: start marker: 0
[13:24:03.997] <TB1> INFO: stop marker: 0
[13:24:03.997] <TB1> INFO: overflow: 0
[13:24:03.997] <TB1> INFO: invalid 5bit words: 0
[13:24:03.997] <TB1> INFO: invalid XOR eye diagram: 0
[13:24:03.997] <TB1> INFO: frame (failed synchr.): 0
[13:24:03.997] <TB1> INFO: idle data (no TBM trl): 0
[13:24:03.997] <TB1> INFO: no data (only TBM hdr): 0
[13:24:03.997] <TB1> INFO: TBM errors: 0
[13:24:03.997] <TB1> INFO: flawed TBM headers: 0
[13:24:03.997] <TB1> INFO: flawed TBM trailers: 0
[13:24:03.997] <TB1> INFO: event ID mismatches: 0
[13:24:03.997] <TB1> INFO: ROC errors: 0
[13:24:03.997] <TB1> INFO: missing ROC header(s): 0
[13:24:03.997] <TB1> INFO: misplaced readback start: 0
[13:24:03.997] <TB1> INFO: Pixel decoding errors: 0
[13:24:03.997] <TB1> INFO: pixel data incomplete: 0
[13:24:03.997] <TB1> INFO: pixel address: 0
[13:24:03.997] <TB1> INFO: pulse height fill bit: 0
[13:24:03.997] <TB1> INFO: buffer corruption: 0
[13:24:04.073] <TB1> INFO: ######################################################################
[13:24:04.073] <TB1> INFO: PixTestTrim::doTest()
[13:24:04.073] <TB1> INFO: ######################################################################
[13:24:04.074] <TB1> INFO: ----------------------------------------------------------------------
[13:24:04.074] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[13:24:04.075] <TB1> INFO: ----------------------------------------------------------------------
[13:24:04.155] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[13:24:04.155] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:24:04.163] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:24:04.163] <TB1> INFO: run 1 of 1
[13:24:04.428] <TB1> INFO: Expecting 6281600 events.
[13:24:41.710] <TB1> INFO: 1098440 events read in total (36723ms).
[13:25:17.171] <TB1> INFO: 2187190 events read in total (72184ms).
[13:25:52.546] <TB1> INFO: 3267290 events read in total (107559ms).
[13:26:28.076] <TB1> INFO: 4352150 events read in total (143089ms).
[13:27:03.308] <TB1> INFO: 5439530 events read in total (178321ms).
[13:27:31.008] <TB1> INFO: 6281600 events read in total (206021ms).
[13:27:31.056] <TB1> INFO: Test took 206894ms.
[13:27:51.764] <TB1> INFO: ROC 0 VthrComp = 99
[13:27:51.764] <TB1> INFO: ROC 1 VthrComp = 87
[13:27:51.765] <TB1> INFO: ROC 2 VthrComp = 90
[13:27:51.765] <TB1> INFO: ROC 3 VthrComp = 95
[13:27:51.765] <TB1> INFO: ROC 4 VthrComp = 88
[13:27:51.765] <TB1> INFO: ROC 5 VthrComp = 90
[13:27:51.765] <TB1> INFO: ROC 6 VthrComp = 99
[13:27:51.765] <TB1> INFO: ROC 7 VthrComp = 100
[13:27:51.765] <TB1> INFO: ROC 8 VthrComp = 90
[13:27:51.765] <TB1> INFO: ROC 9 VthrComp = 89
[13:27:51.765] <TB1> INFO: ROC 10 VthrComp = 88
[13:27:51.766] <TB1> INFO: ROC 11 VthrComp = 89
[13:27:51.766] <TB1> INFO: ROC 12 VthrComp = 92
[13:27:51.766] <TB1> INFO: ROC 13 VthrComp = 88
[13:27:51.766] <TB1> INFO: ROC 14 VthrComp = 81
[13:27:51.766] <TB1> INFO: ROC 15 VthrComp = 94
[13:27:51.766] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[13:27:51.766] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:27:51.775] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:27:51.775] <TB1> INFO: run 1 of 1
[13:27:52.053] <TB1> INFO: Expecting 6281600 events.
[13:28:23.998] <TB1> INFO: 756160 events read in total (31388ms).
[13:28:54.676] <TB1> INFO: 1510690 events read in total (62066ms).
[13:29:25.336] <TB1> INFO: 2265850 events read in total (92726ms).
[13:29:56.106] <TB1> INFO: 3022340 events read in total (123496ms).
[13:30:26.843] <TB1> INFO: 3774730 events read in total (154233ms).
[13:30:57.595] <TB1> INFO: 4523580 events read in total (184985ms).
[13:31:28.100] <TB1> INFO: 5271090 events read in total (215490ms).
[13:31:58.563] <TB1> INFO: 6019370 events read in total (245953ms).
[13:32:09.248] <TB1> INFO: 6281600 events read in total (256638ms).
[13:32:09.307] <TB1> INFO: Test took 257532ms.
[13:32:35.015] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 56.4553 for pixel 13/2 mean/min/max = 44.2627/31.904/56.6215
[13:32:35.015] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 57.6385 for pixel 28/0 mean/min/max = 45.0395/32.4387/57.6403
[13:32:35.015] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 56.7908 for pixel 1/55 mean/min/max = 45.3624/33.901/56.8237
[13:32:35.015] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 57.4637 for pixel 22/3 mean/min/max = 45.0361/32.5969/57.4752
[13:32:35.016] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 55.1526 for pixel 7/18 mean/min/max = 44.3521/33.4523/55.2519
[13:32:35.016] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 57.2024 for pixel 0/27 mean/min/max = 45.2645/33.2667/57.2624
[13:32:35.016] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 56.3955 for pixel 18/10 mean/min/max = 44.3878/32.0684/56.7072
[13:32:35.016] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 58.2045 for pixel 21/2 mean/min/max = 45.1054/31.8653/58.3455
[13:32:35.017] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 57.0397 for pixel 1/15 mean/min/max = 45.2699/33.3393/57.2005
[13:32:35.017] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 58.3611 for pixel 23/46 mean/min/max = 45.9232/33.4153/58.431
[13:32:35.017] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 57.2418 for pixel 3/72 mean/min/max = 45.3337/33.1119/57.5555
[13:32:35.017] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 58.3405 for pixel 24/64 mean/min/max = 45.76/33.0488/58.4713
[13:32:35.018] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 58.0285 for pixel 0/56 mean/min/max = 45.0759/32.1204/58.0315
[13:32:35.018] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 55.9635 for pixel 51/70 mean/min/max = 44.8426/33.5785/56.1066
[13:32:35.018] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 56.6862 for pixel 11/37 mean/min/max = 44.8158/32.8388/56.7928
[13:32:35.018] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 57.3002 for pixel 20/75 mean/min/max = 44.4926/31.5122/57.473
[13:32:35.019] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:32:35.107] <TB1> INFO: Expecting 514560 events.
[13:32:46.053] <TB1> INFO: 514560 events read in total (10390ms).
[13:32:46.061] <TB1> INFO: Expecting 514560 events.
[13:32:56.924] <TB1> INFO: 514560 events read in total (10461ms).
[13:32:56.935] <TB1> INFO: Expecting 514560 events.
[13:33:07.761] <TB1> INFO: 514560 events read in total (10424ms).
[13:33:07.775] <TB1> INFO: Expecting 514560 events.
[13:33:18.579] <TB1> INFO: 514560 events read in total (10402ms).
[13:33:18.594] <TB1> INFO: Expecting 514560 events.
[13:33:29.357] <TB1> INFO: 514560 events read in total (10361ms).
[13:33:29.375] <TB1> INFO: Expecting 514560 events.
[13:33:40.069] <TB1> INFO: 514560 events read in total (10292ms).
[13:33:40.091] <TB1> INFO: Expecting 514560 events.
[13:33:50.921] <TB1> INFO: 514560 events read in total (10429ms).
[13:33:50.947] <TB1> INFO: Expecting 514560 events.
[13:34:01.622] <TB1> INFO: 514560 events read in total (10273ms).
[13:34:01.649] <TB1> INFO: Expecting 514560 events.
[13:34:12.422] <TB1> INFO: 514560 events read in total (10371ms).
[13:34:12.456] <TB1> INFO: Expecting 514560 events.
[13:34:23.176] <TB1> INFO: 514560 events read in total (10318ms).
[13:34:23.215] <TB1> INFO: Expecting 514560 events.
[13:34:33.984] <TB1> INFO: 514560 events read in total (10368ms).
[13:34:34.018] <TB1> INFO: Expecting 514560 events.
[13:34:44.744] <TB1> INFO: 514560 events read in total (10324ms).
[13:34:44.782] <TB1> INFO: Expecting 514560 events.
[13:34:55.529] <TB1> INFO: 514560 events read in total (10345ms).
[13:34:55.570] <TB1> INFO: Expecting 514560 events.
[13:35:06.299] <TB1> INFO: 514560 events read in total (10327ms).
[13:35:06.340] <TB1> INFO: Expecting 514560 events.
[13:35:17.051] <TB1> INFO: 514560 events read in total (10309ms).
[13:35:17.109] <TB1> INFO: Expecting 514560 events.
[13:35:27.847] <TB1> INFO: 514560 events read in total (10336ms).
[13:35:27.893] <TB1> INFO: Test took 172874ms.
[13:35:29.049] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[13:35:29.057] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:35:29.057] <TB1> INFO: run 1 of 1
[13:35:29.331] <TB1> INFO: Expecting 6281600 events.
[13:36:00.413] <TB1> INFO: 751540 events read in total (30526ms).
[13:36:31.342] <TB1> INFO: 1502290 events read in total (61455ms).
[13:37:01.944] <TB1> INFO: 2254660 events read in total (92057ms).
[13:37:32.654] <TB1> INFO: 3007930 events read in total (122767ms).
[13:38:03.329] <TB1> INFO: 3756690 events read in total (153442ms).
[13:38:33.752] <TB1> INFO: 4500850 events read in total (183865ms).
[13:39:04.491] <TB1> INFO: 5244070 events read in total (214604ms).
[13:39:34.792] <TB1> INFO: 5986620 events read in total (244905ms).
[13:39:47.191] <TB1> INFO: 6281600 events read in total (257304ms).
[13:39:47.256] <TB1> INFO: Test took 258198ms.
[13:40:12.157] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 12.743126 .. 53.800472
[13:40:12.445] <TB1> INFO: Expecting 208000 events.
[13:40:22.303] <TB1> INFO: 208000 events read in total (9302ms).
[13:40:22.304] <TB1> INFO: Test took 10146ms.
[13:40:22.415] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 2 .. 63 (-1/-1) hits flags = 528 (plus default)
[13:40:22.423] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:40:22.423] <TB1> INFO: run 1 of 1
[13:40:22.716] <TB1> INFO: Expecting 2579200 events.
[13:40:57.509] <TB1> INFO: 936790 events read in total (34237ms).
[13:41:31.568] <TB1> INFO: 1873340 events read in total (68296ms).
[13:41:57.209] <TB1> INFO: 2579200 events read in total (93937ms).
[13:41:57.241] <TB1> INFO: Test took 94817ms.
[13:42:12.144] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 25.694294 .. 47.435618
[13:42:12.426] <TB1> INFO: Expecting 208000 events.
[13:42:22.456] <TB1> INFO: 208000 events read in total (9474ms).
[13:42:22.457] <TB1> INFO: Test took 10312ms.
[13:42:22.579] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 15 .. 57 (-1/-1) hits flags = 528 (plus default)
[13:42:22.590] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:42:22.590] <TB1> INFO: run 1 of 1
[13:42:22.925] <TB1> INFO: Expecting 1788800 events.
[13:42:57.065] <TB1> INFO: 899640 events read in total (33584ms).
[13:43:30.326] <TB1> INFO: 1788800 events read in total (66845ms).
[13:43:30.357] <TB1> INFO: Test took 67767ms.
[13:43:44.312] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 25.897860 .. 44.922311
[13:43:44.596] <TB1> INFO: Expecting 208000 events.
[13:43:54.400] <TB1> INFO: 208000 events read in total (9248ms).
[13:43:54.401] <TB1> INFO: Test took 10088ms.
[13:43:54.486] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 15 .. 54 (-1/-1) hits flags = 528 (plus default)
[13:43:54.494] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:43:54.494] <TB1> INFO: run 1 of 1
[13:43:54.761] <TB1> INFO: Expecting 1664000 events.
[13:44:29.178] <TB1> INFO: 916590 events read in total (33860ms).
[13:44:56.797] <TB1> INFO: 1664000 events read in total (61479ms).
[13:44:56.816] <TB1> INFO: Test took 62322ms.
[13:45:10.288] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 27.053566 .. 44.922311
[13:45:10.602] <TB1> INFO: Expecting 208000 events.
[13:45:20.305] <TB1> INFO: 208000 events read in total (9147ms).
[13:45:20.306] <TB1> INFO: Test took 10017ms.
[13:45:20.391] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 17 .. 54 (-1/-1) hits flags = 528 (plus default)
[13:45:20.399] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:45:20.399] <TB1> INFO: run 1 of 1
[13:45:20.662] <TB1> INFO: Expecting 1580800 events.
[13:45:54.678] <TB1> INFO: 900580 events read in total (33460ms).
[13:46:20.467] <TB1> INFO: 1580800 events read in total (59249ms).
[13:46:20.490] <TB1> INFO: Test took 60092ms.
[13:46:34.828] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[13:46:34.828] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:46:34.836] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:46:34.836] <TB1> INFO: run 1 of 1
[13:46:35.118] <TB1> INFO: Expecting 1705600 events.
[13:47:09.607] <TB1> INFO: 903490 events read in total (33933ms).
[13:47:39.575] <TB1> INFO: 1705600 events read in total (63901ms).
[13:47:39.595] <TB1> INFO: Test took 64759ms.
[13:47:53.549] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C0.dat
[13:47:53.549] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C1.dat
[13:47:53.549] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C2.dat
[13:47:53.549] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C3.dat
[13:47:53.549] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C4.dat
[13:47:53.549] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C5.dat
[13:47:53.549] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C6.dat
[13:47:53.550] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C7.dat
[13:47:53.550] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C8.dat
[13:47:53.550] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C9.dat
[13:47:53.550] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C10.dat
[13:47:53.550] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C11.dat
[13:47:53.550] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C12.dat
[13:47:53.550] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C13.dat
[13:47:53.551] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C14.dat
[13:47:53.551] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C15.dat
[13:47:53.551] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//trimParameters35_C0.dat
[13:47:53.559] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//trimParameters35_C1.dat
[13:47:53.567] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//trimParameters35_C2.dat
[13:47:53.576] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//trimParameters35_C3.dat
[13:47:53.585] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//trimParameters35_C4.dat
[13:47:53.593] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//trimParameters35_C5.dat
[13:47:53.600] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//trimParameters35_C6.dat
[13:47:53.607] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//trimParameters35_C7.dat
[13:47:53.614] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//trimParameters35_C8.dat
[13:47:53.622] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//trimParameters35_C9.dat
[13:47:53.629] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//trimParameters35_C10.dat
[13:47:53.637] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//trimParameters35_C11.dat
[13:47:53.646] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//trimParameters35_C12.dat
[13:47:53.654] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//trimParameters35_C13.dat
[13:47:53.661] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//trimParameters35_C14.dat
[13:47:53.668] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//trimParameters35_C15.dat
[13:47:53.674] <TB1> INFO: PixTestTrim::trimTest() done
[13:47:53.674] <TB1> INFO: vtrim: 109 115 106 113 110 111 117 109 123 110 109 126 111 100 110 106
[13:47:53.674] <TB1> INFO: vthrcomp: 99 87 90 95 88 90 99 100 90 89 88 89 92 88 81 94
[13:47:53.674] <TB1> INFO: vcal mean: 35.29 35.39 35.67 35.35 35.67 35.69 35.36 35.46 35.41 35.66 35.64 35.51 35.39 35.42 35.46 35.32
[13:47:53.674] <TB1> INFO: vcal RMS: 0.89 0.99 1.23 1.00 1.23 1.21 0.92 1.07 1.01 1.23 1.20 1.13 1.03 1.48 1.07 0.97
[13:47:53.674] <TB1> INFO: bits mean: 8.26 7.44 6.16 7.56 6.93 6.50 7.99 7.57 7.70 6.86 6.33 7.19 7.63 6.97 7.55 7.81
[13:47:53.674] <TB1> INFO: bits RMS: 2.43 2.41 2.41 2.39 2.19 2.37 2.48 2.29 2.21 2.31 2.49 2.34 2.54 2.46 2.22 2.52
[13:47:53.682] <TB1> INFO: ----------------------------------------------------------------------
[13:47:53.682] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[13:47:53.682] <TB1> INFO: ----------------------------------------------------------------------
[13:47:53.685] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[13:47:53.693] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:47:53.693] <TB1> INFO: run 1 of 1
[13:47:53.967] <TB1> INFO: Expecting 4160000 events.
[13:48:32.749] <TB1> INFO: 981245 events read in total (38225ms).
[13:49:11.054] <TB1> INFO: 1953035 events read in total (76530ms).
[13:49:50.012] <TB1> INFO: 2911635 events read in total (115488ms).
[13:50:28.950] <TB1> INFO: 3868425 events read in total (154426ms).
[13:50:41.021] <TB1> INFO: 4160000 events read in total (166497ms).
[13:50:41.079] <TB1> INFO: Test took 167386ms.
[13:51:09.397] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 184 (-1/-1) hits flags = 528 (plus default)
[13:51:09.405] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:51:09.405] <TB1> INFO: run 1 of 1
[13:51:09.674] <TB1> INFO: Expecting 3848000 events.
[13:51:49.519] <TB1> INFO: 977850 events read in total (39289ms).
[13:52:28.340] <TB1> INFO: 1945855 events read in total (78111ms).
[13:53:06.028] <TB1> INFO: 2900475 events read in total (115798ms).
[13:53:43.292] <TB1> INFO: 3848000 events read in total (153062ms).
[13:53:43.355] <TB1> INFO: Test took 153950ms.
[13:54:11.493] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 168 (-1/-1) hits flags = 528 (plus default)
[13:54:11.501] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:54:11.501] <TB1> INFO: run 1 of 1
[13:54:11.766] <TB1> INFO: Expecting 3515200 events.
[13:54:52.251] <TB1> INFO: 1024630 events read in total (39928ms).
[13:55:31.419] <TB1> INFO: 2033220 events read in total (79096ms).
[13:56:10.970] <TB1> INFO: 3029435 events read in total (118647ms).
[13:56:30.062] <TB1> INFO: 3515200 events read in total (137739ms).
[13:56:30.115] <TB1> INFO: Test took 138613ms.
[13:56:56.705] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 171 (-1/-1) hits flags = 528 (plus default)
[13:56:56.717] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:56:56.717] <TB1> INFO: run 1 of 1
[13:56:56.979] <TB1> INFO: Expecting 3577600 events.
[13:57:38.316] <TB1> INFO: 1015505 events read in total (40781ms).
[13:58:16.899] <TB1> INFO: 2016375 events read in total (79364ms).
[13:58:55.756] <TB1> INFO: 3004330 events read in total (118221ms).
[13:59:18.400] <TB1> INFO: 3577600 events read in total (140865ms).
[13:59:18.453] <TB1> INFO: Test took 141737ms.
[13:59:46.450] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 171 (-1/-1) hits flags = 528 (plus default)
[13:59:46.458] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:59:46.458] <TB1> INFO: run 1 of 1
[13:59:46.724] <TB1> INFO: Expecting 3577600 events.
[14:00:26.923] <TB1> INFO: 989485 events read in total (39643ms).
[14:01:06.169] <TB1> INFO: 1956315 events read in total (78889ms).
[14:01:44.311] <TB1> INFO: 2904750 events read in total (117031ms).
[14:02:11.304] <TB1> INFO: 3577600 events read in total (144024ms).
[14:02:11.389] <TB1> INFO: Test took 144930ms.
[14:02:40.818] <TB1> INFO: PixTestTrim::trimBitTest() done
[14:02:40.820] <TB1> INFO: PixTestTrim::doTest() done, duration: 2316 seconds
[14:02:40.820] <TB1> INFO: Decoding statistics:
[14:02:40.820] <TB1> INFO: General information:
[14:02:40.820] <TB1> INFO: 16bit words read: 0
[14:02:40.820] <TB1> INFO: valid events total: 0
[14:02:40.820] <TB1> INFO: empty events: 0
[14:02:40.820] <TB1> INFO: valid events with pixels: 0
[14:02:40.820] <TB1> INFO: valid pixel hits: 0
[14:02:40.820] <TB1> INFO: Event errors: 0
[14:02:40.820] <TB1> INFO: start marker: 0
[14:02:40.820] <TB1> INFO: stop marker: 0
[14:02:40.820] <TB1> INFO: overflow: 0
[14:02:40.820] <TB1> INFO: invalid 5bit words: 0
[14:02:40.820] <TB1> INFO: invalid XOR eye diagram: 0
[14:02:40.820] <TB1> INFO: frame (failed synchr.): 0
[14:02:40.820] <TB1> INFO: idle data (no TBM trl): 0
[14:02:40.820] <TB1> INFO: no data (only TBM hdr): 0
[14:02:40.820] <TB1> INFO: TBM errors: 0
[14:02:40.820] <TB1> INFO: flawed TBM headers: 0
[14:02:40.820] <TB1> INFO: flawed TBM trailers: 0
[14:02:40.820] <TB1> INFO: event ID mismatches: 0
[14:02:40.820] <TB1> INFO: ROC errors: 0
[14:02:40.820] <TB1> INFO: missing ROC header(s): 0
[14:02:40.820] <TB1> INFO: misplaced readback start: 0
[14:02:40.820] <TB1> INFO: Pixel decoding errors: 0
[14:02:40.820] <TB1> INFO: pixel data incomplete: 0
[14:02:40.820] <TB1> INFO: pixel address: 0
[14:02:40.820] <TB1> INFO: pulse height fill bit: 0
[14:02:40.820] <TB1> INFO: buffer corruption: 0
[14:02:41.523] <TB1> INFO: ######################################################################
[14:02:41.523] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[14:02:41.523] <TB1> INFO: ######################################################################
[14:02:41.788] <TB1> INFO: Expecting 41600 events.
[14:02:45.187] <TB1> INFO: 41600 events read in total (2842ms).
[14:02:45.187] <TB1> INFO: Test took 3663ms.
[14:02:45.663] <TB1> INFO: Expecting 41600 events.
[14:02:49.106] <TB1> INFO: 41600 events read in total (2887ms).
[14:02:49.107] <TB1> INFO: Test took 3723ms.
[14:02:49.390] <TB1> INFO: Expecting 41600 events.
[14:02:53.043] <TB1> INFO: 41600 events read in total (3097ms).
[14:02:53.044] <TB1> INFO: Test took 3915ms.
[14:02:53.299] <TB1> INFO: Expecting 2560 events.
[14:02:54.153] <TB1> INFO: 2560 events read in total (298ms).
[14:02:54.153] <TB1> INFO: Test took 1096ms.
[14:02:54.458] <TB1> INFO: Expecting 2560 events.
[14:02:55.309] <TB1> INFO: 2560 events read in total (295ms).
[14:02:55.309] <TB1> INFO: Test took 1156ms.
[14:02:55.614] <TB1> INFO: Expecting 2560 events.
[14:02:56.465] <TB1> INFO: 2560 events read in total (294ms).
[14:02:56.465] <TB1> INFO: Test took 1155ms.
[14:02:56.770] <TB1> INFO: Expecting 2560 events.
[14:02:57.621] <TB1> INFO: 2560 events read in total (295ms).
[14:02:57.621] <TB1> INFO: Test took 1155ms.
[14:02:57.926] <TB1> INFO: Expecting 2560 events.
[14:02:58.778] <TB1> INFO: 2560 events read in total (296ms).
[14:02:58.778] <TB1> INFO: Test took 1150ms.
[14:02:59.083] <TB1> INFO: Expecting 2560 events.
[14:02:59.934] <TB1> INFO: 2560 events read in total (295ms).
[14:02:59.934] <TB1> INFO: Test took 1156ms.
[14:03:00.239] <TB1> INFO: Expecting 2560 events.
[14:03:01.091] <TB1> INFO: 2560 events read in total (296ms).
[14:03:01.091] <TB1> INFO: Test took 1156ms.
[14:03:01.396] <TB1> INFO: Expecting 2560 events.
[14:03:02.247] <TB1> INFO: 2560 events read in total (295ms).
[14:03:02.247] <TB1> INFO: Test took 1156ms.
[14:03:02.552] <TB1> INFO: Expecting 2560 events.
[14:03:03.403] <TB1> INFO: 2560 events read in total (295ms).
[14:03:03.403] <TB1> INFO: Test took 1156ms.
[14:03:03.708] <TB1> INFO: Expecting 2560 events.
[14:03:04.560] <TB1> INFO: 2560 events read in total (296ms).
[14:03:04.560] <TB1> INFO: Test took 1157ms.
[14:03:04.865] <TB1> INFO: Expecting 2560 events.
[14:03:05.717] <TB1> INFO: 2560 events read in total (295ms).
[14:03:05.717] <TB1> INFO: Test took 1156ms.
[14:03:06.022] <TB1> INFO: Expecting 2560 events.
[14:03:06.874] <TB1> INFO: 2560 events read in total (296ms).
[14:03:06.874] <TB1> INFO: Test took 1157ms.
[14:03:07.179] <TB1> INFO: Expecting 2560 events.
[14:03:08.033] <TB1> INFO: 2560 events read in total (297ms).
[14:03:08.033] <TB1> INFO: Test took 1158ms.
[14:03:08.338] <TB1> INFO: Expecting 2560 events.
[14:03:09.194] <TB1> INFO: 2560 events read in total (300ms).
[14:03:09.194] <TB1> INFO: Test took 1161ms.
[14:03:09.499] <TB1> INFO: Expecting 2560 events.
[14:03:10.354] <TB1> INFO: 2560 events read in total (299ms).
[14:03:10.355] <TB1> INFO: Test took 1161ms.
[14:03:10.659] <TB1> INFO: Expecting 2560 events.
[14:03:11.511] <TB1> INFO: 2560 events read in total (296ms).
[14:03:11.511] <TB1> INFO: Test took 1156ms.
[14:03:11.514] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:03:11.817] <TB1> INFO: Expecting 655360 events.
[14:03:25.724] <TB1> INFO: 655360 events read in total (13351ms).
[14:03:25.737] <TB1> INFO: Expecting 655360 events.
[14:03:39.560] <TB1> INFO: 655360 events read in total (13421ms).
[14:03:39.576] <TB1> INFO: Expecting 655360 events.
[14:03:53.639] <TB1> INFO: 655360 events read in total (13662ms).
[14:03:53.659] <TB1> INFO: Expecting 655360 events.
[14:04:07.415] <TB1> INFO: 655360 events read in total (13355ms).
[14:04:07.440] <TB1> INFO: Expecting 655360 events.
[14:04:21.155] <TB1> INFO: 655360 events read in total (13314ms).
[14:04:21.183] <TB1> INFO: Expecting 655360 events.
[14:04:34.964] <TB1> INFO: 655360 events read in total (13379ms).
[14:04:34.995] <TB1> INFO: Expecting 655360 events.
[14:04:48.687] <TB1> INFO: 655360 events read in total (13290ms).
[14:04:48.723] <TB1> INFO: Expecting 655360 events.
[14:05:02.384] <TB1> INFO: 655360 events read in total (13259ms).
[14:05:02.423] <TB1> INFO: Expecting 655360 events.
[14:05:16.172] <TB1> INFO: 655360 events read in total (13348ms).
[14:05:16.216] <TB1> INFO: Expecting 655360 events.
[14:05:29.880] <TB1> INFO: 655360 events read in total (13262ms).
[14:05:29.927] <TB1> INFO: Expecting 655360 events.
[14:05:43.733] <TB1> INFO: 655360 events read in total (13404ms).
[14:05:43.790] <TB1> INFO: Expecting 655360 events.
[14:05:57.534] <TB1> INFO: 655360 events read in total (13342ms).
[14:05:57.590] <TB1> INFO: Expecting 655360 events.
[14:06:11.278] <TB1> INFO: 655360 events read in total (13286ms).
[14:06:11.351] <TB1> INFO: Expecting 655360 events.
[14:06:25.148] <TB1> INFO: 655360 events read in total (13395ms).
[14:06:25.218] <TB1> INFO: Expecting 655360 events.
[14:06:38.989] <TB1> INFO: 655360 events read in total (13369ms).
[14:06:39.057] <TB1> INFO: Expecting 655360 events.
[14:06:52.846] <TB1> INFO: 655360 events read in total (13387ms).
[14:06:52.931] <TB1> INFO: Test took 221417ms.
[14:06:53.019] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:06:53.249] <TB1> INFO: Expecting 655360 events.
[14:07:07.283] <TB1> INFO: 655360 events read in total (13477ms).
[14:07:07.296] <TB1> INFO: Expecting 655360 events.
[14:07:21.109] <TB1> INFO: 655360 events read in total (13411ms).
[14:07:21.125] <TB1> INFO: Expecting 655360 events.
[14:07:34.956] <TB1> INFO: 655360 events read in total (13430ms).
[14:07:34.979] <TB1> INFO: Expecting 655360 events.
[14:07:48.675] <TB1> INFO: 655360 events read in total (13294ms).
[14:07:48.699] <TB1> INFO: Expecting 655360 events.
[14:08:02.492] <TB1> INFO: 655360 events read in total (13392ms).
[14:08:02.525] <TB1> INFO: Expecting 655360 events.
[14:08:16.350] <TB1> INFO: 655360 events read in total (13423ms).
[14:08:16.381] <TB1> INFO: Expecting 655360 events.
[14:08:30.111] <TB1> INFO: 655360 events read in total (13328ms).
[14:08:30.148] <TB1> INFO: Expecting 655360 events.
[14:08:43.886] <TB1> INFO: 655360 events read in total (13336ms).
[14:08:43.927] <TB1> INFO: Expecting 655360 events.
[14:08:57.720] <TB1> INFO: 655360 events read in total (13391ms).
[14:08:57.765] <TB1> INFO: Expecting 655360 events.
[14:09:11.582] <TB1> INFO: 655360 events read in total (13415ms).
[14:09:11.638] <TB1> INFO: Expecting 655360 events.
[14:09:25.383] <TB1> INFO: 655360 events read in total (13343ms).
[14:09:25.439] <TB1> INFO: Expecting 655360 events.
[14:09:39.342] <TB1> INFO: 655360 events read in total (13501ms).
[14:09:39.400] <TB1> INFO: Expecting 655360 events.
[14:09:53.095] <TB1> INFO: 655360 events read in total (13293ms).
[14:09:53.161] <TB1> INFO: Expecting 655360 events.
[14:10:07.044] <TB1> INFO: 655360 events read in total (13481ms).
[14:10:07.110] <TB1> INFO: Expecting 655360 events.
[14:10:20.928] <TB1> INFO: 655360 events read in total (13416ms).
[14:10:20.999] <TB1> INFO: Expecting 655360 events.
[14:10:34.767] <TB1> INFO: 655360 events read in total (13366ms).
[14:10:34.842] <TB1> INFO: Test took 221823ms.
[14:10:35.039] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:10:35.046] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[14:10:35.053] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:10:35.060] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:10:35.066] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:10:35.073] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:10:35.080] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:10:35.087] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:10:35.094] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:10:35.100] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:10:35.107] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:10:35.115] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:10:35.122] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:10:35.128] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:10:35.135] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:10:35.142] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:10:35.149] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:10:35.177] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C0.dat
[14:10:35.177] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C1.dat
[14:10:35.177] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C2.dat
[14:10:35.177] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C3.dat
[14:10:35.178] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C4.dat
[14:10:35.178] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C5.dat
[14:10:35.209] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C6.dat
[14:10:35.209] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C7.dat
[14:10:35.209] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C8.dat
[14:10:35.209] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C9.dat
[14:10:35.209] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C10.dat
[14:10:35.210] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C11.dat
[14:10:35.210] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C12.dat
[14:10:35.210] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C13.dat
[14:10:35.210] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C14.dat
[14:10:35.210] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//dacParameters35_C15.dat
[14:10:35.484] <TB1> INFO: Expecting 41600 events.
[14:10:38.629] <TB1> INFO: 41600 events read in total (2588ms).
[14:10:38.629] <TB1> INFO: Test took 3417ms.
[14:10:39.096] <TB1> INFO: Expecting 41600 events.
[14:10:42.208] <TB1> INFO: 41600 events read in total (2556ms).
[14:10:42.209] <TB1> INFO: Test took 3403ms.
[14:10:42.687] <TB1> INFO: Expecting 41600 events.
[14:10:45.811] <TB1> INFO: 41600 events read in total (2568ms).
[14:10:45.812] <TB1> INFO: Test took 3428ms.
[14:10:45.995] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:10:46.085] <TB1> INFO: Expecting 2560 events.
[14:10:46.941] <TB1> INFO: 2560 events read in total (299ms).
[14:10:46.941] <TB1> INFO: Test took 946ms.
[14:10:46.943] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:10:47.247] <TB1> INFO: Expecting 2560 events.
[14:10:48.099] <TB1> INFO: 2560 events read in total (296ms).
[14:10:48.099] <TB1> INFO: Test took 1156ms.
[14:10:48.101] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:10:48.405] <TB1> INFO: Expecting 2560 events.
[14:10:49.265] <TB1> INFO: 2560 events read in total (303ms).
[14:10:49.265] <TB1> INFO: Test took 1164ms.
[14:10:49.268] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:10:49.570] <TB1> INFO: Expecting 2560 events.
[14:10:50.426] <TB1> INFO: 2560 events read in total (300ms).
[14:10:50.426] <TB1> INFO: Test took 1158ms.
[14:10:50.429] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:10:50.731] <TB1> INFO: Expecting 2560 events.
[14:10:51.590] <TB1> INFO: 2560 events read in total (302ms).
[14:10:51.590] <TB1> INFO: Test took 1161ms.
[14:10:51.592] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:10:51.896] <TB1> INFO: Expecting 2560 events.
[14:10:52.753] <TB1> INFO: 2560 events read in total (301ms).
[14:10:52.754] <TB1> INFO: Test took 1162ms.
[14:10:52.755] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:10:53.064] <TB1> INFO: Expecting 2560 events.
[14:10:53.921] <TB1> INFO: 2560 events read in total (301ms).
[14:10:53.921] <TB1> INFO: Test took 1166ms.
[14:10:53.923] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:10:54.226] <TB1> INFO: Expecting 2560 events.
[14:10:55.078] <TB1> INFO: 2560 events read in total (296ms).
[14:10:55.078] <TB1> INFO: Test took 1155ms.
[14:10:55.081] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:10:55.384] <TB1> INFO: Expecting 2560 events.
[14:10:56.236] <TB1> INFO: 2560 events read in total (295ms).
[14:10:56.236] <TB1> INFO: Test took 1155ms.
[14:10:56.238] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:10:56.541] <TB1> INFO: Expecting 2560 events.
[14:10:57.398] <TB1> INFO: 2560 events read in total (300ms).
[14:10:57.398] <TB1> INFO: Test took 1160ms.
[14:10:57.400] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:10:57.703] <TB1> INFO: Expecting 2560 events.
[14:10:58.555] <TB1> INFO: 2560 events read in total (296ms).
[14:10:58.556] <TB1> INFO: Test took 1156ms.
[14:10:58.558] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:10:58.861] <TB1> INFO: Expecting 2560 events.
[14:10:59.714] <TB1> INFO: 2560 events read in total (297ms).
[14:10:59.715] <TB1> INFO: Test took 1157ms.
[14:10:59.717] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:00.020] <TB1> INFO: Expecting 2560 events.
[14:11:00.874] <TB1> INFO: 2560 events read in total (298ms).
[14:11:00.874] <TB1> INFO: Test took 1157ms.
[14:11:00.876] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:01.179] <TB1> INFO: Expecting 2560 events.
[14:11:02.033] <TB1> INFO: 2560 events read in total (297ms).
[14:11:02.033] <TB1> INFO: Test took 1157ms.
[14:11:02.037] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:02.338] <TB1> INFO: Expecting 2560 events.
[14:11:03.197] <TB1> INFO: 2560 events read in total (302ms).
[14:11:03.197] <TB1> INFO: Test took 1160ms.
[14:11:03.200] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:03.502] <TB1> INFO: Expecting 2560 events.
[14:11:04.363] <TB1> INFO: 2560 events read in total (304ms).
[14:11:04.363] <TB1> INFO: Test took 1163ms.
[14:11:04.366] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:04.668] <TB1> INFO: Expecting 2560 events.
[14:11:05.520] <TB1> INFO: 2560 events read in total (296ms).
[14:11:05.521] <TB1> INFO: Test took 1155ms.
[14:11:05.523] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:05.827] <TB1> INFO: Expecting 2560 events.
[14:11:06.688] <TB1> INFO: 2560 events read in total (305ms).
[14:11:06.688] <TB1> INFO: Test took 1165ms.
[14:11:06.690] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:06.994] <TB1> INFO: Expecting 2560 events.
[14:11:07.849] <TB1> INFO: 2560 events read in total (298ms).
[14:11:07.850] <TB1> INFO: Test took 1160ms.
[14:11:07.852] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:08.154] <TB1> INFO: Expecting 2560 events.
[14:11:09.006] <TB1> INFO: 2560 events read in total (296ms).
[14:11:09.006] <TB1> INFO: Test took 1154ms.
[14:11:09.008] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:09.312] <TB1> INFO: Expecting 2560 events.
[14:11:10.165] <TB1> INFO: 2560 events read in total (297ms).
[14:11:10.166] <TB1> INFO: Test took 1159ms.
[14:11:10.169] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:10.470] <TB1> INFO: Expecting 2560 events.
[14:11:11.321] <TB1> INFO: 2560 events read in total (295ms).
[14:11:11.321] <TB1> INFO: Test took 1153ms.
[14:11:11.323] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:11.627] <TB1> INFO: Expecting 2560 events.
[14:11:12.483] <TB1> INFO: 2560 events read in total (300ms).
[14:11:12.484] <TB1> INFO: Test took 1161ms.
[14:11:12.485] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:12.790] <TB1> INFO: Expecting 2560 events.
[14:11:13.652] <TB1> INFO: 2560 events read in total (305ms).
[14:11:13.652] <TB1> INFO: Test took 1167ms.
[14:11:13.654] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:13.957] <TB1> INFO: Expecting 2560 events.
[14:11:14.813] <TB1> INFO: 2560 events read in total (300ms).
[14:11:14.813] <TB1> INFO: Test took 1159ms.
[14:11:14.816] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:15.119] <TB1> INFO: Expecting 2560 events.
[14:11:15.977] <TB1> INFO: 2560 events read in total (302ms).
[14:11:15.977] <TB1> INFO: Test took 1161ms.
[14:11:15.980] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:16.281] <TB1> INFO: Expecting 2560 events.
[14:11:17.142] <TB1> INFO: 2560 events read in total (304ms).
[14:11:17.142] <TB1> INFO: Test took 1163ms.
[14:11:17.144] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:17.448] <TB1> INFO: Expecting 2560 events.
[14:11:18.305] <TB1> INFO: 2560 events read in total (300ms).
[14:11:18.306] <TB1> INFO: Test took 1162ms.
[14:11:18.309] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:18.612] <TB1> INFO: Expecting 2560 events.
[14:11:19.470] <TB1> INFO: 2560 events read in total (302ms).
[14:11:19.471] <TB1> INFO: Test took 1163ms.
[14:11:19.488] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:19.776] <TB1> INFO: Expecting 2560 events.
[14:11:20.630] <TB1> INFO: 2560 events read in total (298ms).
[14:11:20.630] <TB1> INFO: Test took 1142ms.
[14:11:20.632] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:20.936] <TB1> INFO: Expecting 2560 events.
[14:11:21.789] <TB1> INFO: 2560 events read in total (297ms).
[14:11:21.789] <TB1> INFO: Test took 1157ms.
[14:11:21.791] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:22.094] <TB1> INFO: Expecting 2560 events.
[14:11:22.946] <TB1> INFO: 2560 events read in total (296ms).
[14:11:22.946] <TB1> INFO: Test took 1155ms.
[14:11:23.421] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 521 seconds
[14:11:23.421] <TB1> INFO: PH scale (per ROC): 80 80 80 84 84 85 90 81 82 82 83 84 82 89 83 85
[14:11:23.421] <TB1> INFO: PH offset (per ROC): 157 158 160 155 157 149 150 141 156 151 147 155 144 162 151 161
[14:11:23.427] <TB1> INFO: Decoding statistics:
[14:11:23.427] <TB1> INFO: General information:
[14:11:23.427] <TB1> INFO: 16bit words read: 86934
[14:11:23.427] <TB1> INFO: valid events total: 10240
[14:11:23.427] <TB1> INFO: empty events: 7733
[14:11:23.427] <TB1> INFO: valid events with pixels: 2507
[14:11:23.427] <TB1> INFO: valid pixel hits: 2507
[14:11:23.427] <TB1> INFO: Event errors: 0
[14:11:23.427] <TB1> INFO: start marker: 0
[14:11:23.427] <TB1> INFO: stop marker: 0
[14:11:23.427] <TB1> INFO: overflow: 0
[14:11:23.427] <TB1> INFO: invalid 5bit words: 0
[14:11:23.427] <TB1> INFO: invalid XOR eye diagram: 0
[14:11:23.427] <TB1> INFO: frame (failed synchr.): 0
[14:11:23.427] <TB1> INFO: idle data (no TBM trl): 0
[14:11:23.427] <TB1> INFO: no data (only TBM hdr): 0
[14:11:23.427] <TB1> INFO: TBM errors: 0
[14:11:23.427] <TB1> INFO: flawed TBM headers: 0
[14:11:23.427] <TB1> INFO: flawed TBM trailers: 0
[14:11:23.427] <TB1> INFO: event ID mismatches: 0
[14:11:23.427] <TB1> INFO: ROC errors: 0
[14:11:23.427] <TB1> INFO: missing ROC header(s): 0
[14:11:23.427] <TB1> INFO: misplaced readback start: 0
[14:11:23.427] <TB1> INFO: Pixel decoding errors: 0
[14:11:23.427] <TB1> INFO: pixel data incomplete: 0
[14:11:23.427] <TB1> INFO: pixel address: 0
[14:11:23.427] <TB1> INFO: pulse height fill bit: 0
[14:11:23.427] <TB1> INFO: buffer corruption: 0
[14:11:23.605] <TB1> INFO: ######################################################################
[14:11:23.605] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[14:11:23.605] <TB1> INFO: ######################################################################
[14:11:23.615] <TB1> INFO: scanning low vcal = 10
[14:11:23.881] <TB1> INFO: Expecting 41600 events.
[14:11:27.251] <TB1> INFO: 41600 events read in total (2814ms).
[14:11:27.252] <TB1> INFO: Test took 3637ms.
[14:11:27.253] <TB1> INFO: scanning low vcal = 20
[14:11:27.550] <TB1> INFO: Expecting 41600 events.
[14:11:30.975] <TB1> INFO: 41600 events read in total (2868ms).
[14:11:30.975] <TB1> INFO: Test took 3722ms.
[14:11:30.977] <TB1> INFO: scanning low vcal = 30
[14:11:31.264] <TB1> INFO: Expecting 41600 events.
[14:11:34.875] <TB1> INFO: 41600 events read in total (3055ms).
[14:11:34.876] <TB1> INFO: Test took 3899ms.
[14:11:34.878] <TB1> INFO: scanning low vcal = 40
[14:11:35.152] <TB1> INFO: Expecting 41600 events.
[14:11:39.116] <TB1> INFO: 41600 events read in total (3408ms).
[14:11:39.116] <TB1> INFO: Test took 4238ms.
[14:11:39.120] <TB1> INFO: scanning low vcal = 50
[14:11:39.395] <TB1> INFO: Expecting 41600 events.
[14:11:43.356] <TB1> INFO: 41600 events read in total (3404ms).
[14:11:43.356] <TB1> INFO: Test took 4236ms.
[14:11:43.359] <TB1> INFO: scanning low vcal = 60
[14:11:43.625] <TB1> INFO: Expecting 41600 events.
[14:11:47.577] <TB1> INFO: 41600 events read in total (3395ms).
[14:11:47.577] <TB1> INFO: Test took 4218ms.
[14:11:47.580] <TB1> INFO: scanning low vcal = 70
[14:11:47.859] <TB1> INFO: Expecting 41600 events.
[14:11:51.805] <TB1> INFO: 41600 events read in total (3389ms).
[14:11:51.805] <TB1> INFO: Test took 4225ms.
[14:11:51.808] <TB1> INFO: scanning low vcal = 80
[14:11:52.089] <TB1> INFO: Expecting 41600 events.
[14:11:56.024] <TB1> INFO: 41600 events read in total (3379ms).
[14:11:56.025] <TB1> INFO: Test took 4217ms.
[14:11:56.027] <TB1> INFO: scanning low vcal = 90
[14:11:56.310] <TB1> INFO: Expecting 41600 events.
[14:12:00.255] <TB1> INFO: 41600 events read in total (3389ms).
[14:12:00.255] <TB1> INFO: Test took 4228ms.
[14:12:00.259] <TB1> INFO: scanning low vcal = 100
[14:12:00.518] <TB1> INFO: Expecting 41600 events.
[14:12:04.443] <TB1> INFO: 41600 events read in total (3369ms).
[14:12:04.444] <TB1> INFO: Test took 4185ms.
[14:12:04.448] <TB1> INFO: scanning low vcal = 110
[14:12:04.714] <TB1> INFO: Expecting 41600 events.
[14:12:08.649] <TB1> INFO: 41600 events read in total (3379ms).
[14:12:08.649] <TB1> INFO: Test took 4201ms.
[14:12:08.652] <TB1> INFO: scanning low vcal = 120
[14:12:08.916] <TB1> INFO: Expecting 41600 events.
[14:12:12.883] <TB1> INFO: 41600 events read in total (3410ms).
[14:12:12.884] <TB1> INFO: Test took 4232ms.
[14:12:12.887] <TB1> INFO: scanning low vcal = 130
[14:12:13.155] <TB1> INFO: Expecting 41600 events.
[14:12:17.117] <TB1> INFO: 41600 events read in total (3406ms).
[14:12:17.118] <TB1> INFO: Test took 4231ms.
[14:12:17.120] <TB1> INFO: scanning low vcal = 140
[14:12:17.384] <TB1> INFO: Expecting 41600 events.
[14:12:21.346] <TB1> INFO: 41600 events read in total (3406ms).
[14:12:21.347] <TB1> INFO: Test took 4227ms.
[14:12:21.350] <TB1> INFO: scanning low vcal = 150
[14:12:21.617] <TB1> INFO: Expecting 41600 events.
[14:12:25.576] <TB1> INFO: 41600 events read in total (3403ms).
[14:12:25.577] <TB1> INFO: Test took 4227ms.
[14:12:25.579] <TB1> INFO: scanning low vcal = 160
[14:12:25.844] <TB1> INFO: Expecting 41600 events.
[14:12:29.818] <TB1> INFO: 41600 events read in total (3418ms).
[14:12:29.819] <TB1> INFO: Test took 4239ms.
[14:12:29.821] <TB1> INFO: scanning low vcal = 170
[14:12:30.087] <TB1> INFO: Expecting 41600 events.
[14:12:34.053] <TB1> INFO: 41600 events read in total (3410ms).
[14:12:34.054] <TB1> INFO: Test took 4232ms.
[14:12:34.058] <TB1> INFO: scanning low vcal = 180
[14:12:34.324] <TB1> INFO: Expecting 41600 events.
[14:12:38.256] <TB1> INFO: 41600 events read in total (3376ms).
[14:12:38.257] <TB1> INFO: Test took 4199ms.
[14:12:38.259] <TB1> INFO: scanning low vcal = 190
[14:12:38.523] <TB1> INFO: Expecting 41600 events.
[14:12:42.462] <TB1> INFO: 41600 events read in total (3383ms).
[14:12:42.463] <TB1> INFO: Test took 4204ms.
[14:12:42.465] <TB1> INFO: scanning low vcal = 200
[14:12:42.745] <TB1> INFO: Expecting 41600 events.
[14:12:46.726] <TB1> INFO: 41600 events read in total (3425ms).
[14:12:46.727] <TB1> INFO: Test took 4261ms.
[14:12:46.729] <TB1> INFO: scanning low vcal = 210
[14:12:47.020] <TB1> INFO: Expecting 41600 events.
[14:12:50.992] <TB1> INFO: 41600 events read in total (3415ms).
[14:12:50.992] <TB1> INFO: Test took 4263ms.
[14:12:50.995] <TB1> INFO: scanning low vcal = 220
[14:12:51.261] <TB1> INFO: Expecting 41600 events.
[14:12:55.240] <TB1> INFO: 41600 events read in total (3422ms).
[14:12:55.241] <TB1> INFO: Test took 4246ms.
[14:12:55.244] <TB1> INFO: scanning low vcal = 230
[14:12:55.509] <TB1> INFO: Expecting 41600 events.
[14:12:59.467] <TB1> INFO: 41600 events read in total (3401ms).
[14:12:59.468] <TB1> INFO: Test took 4224ms.
[14:12:59.470] <TB1> INFO: scanning low vcal = 240
[14:12:59.734] <TB1> INFO: Expecting 41600 events.
[14:13:03.674] <TB1> INFO: 41600 events read in total (3384ms).
[14:13:03.675] <TB1> INFO: Test took 4205ms.
[14:13:03.694] <TB1> INFO: scanning low vcal = 250
[14:13:03.960] <TB1> INFO: Expecting 41600 events.
[14:13:07.925] <TB1> INFO: 41600 events read in total (3409ms).
[14:13:07.926] <TB1> INFO: Test took 4232ms.
[14:13:07.930] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[14:13:08.196] <TB1> INFO: Expecting 41600 events.
[14:13:12.172] <TB1> INFO: 41600 events read in total (3419ms).
[14:13:12.173] <TB1> INFO: Test took 4243ms.
[14:13:12.176] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[14:13:12.463] <TB1> INFO: Expecting 41600 events.
[14:13:16.402] <TB1> INFO: 41600 events read in total (3383ms).
[14:13:16.403] <TB1> INFO: Test took 4227ms.
[14:13:16.405] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[14:13:16.677] <TB1> INFO: Expecting 41600 events.
[14:13:20.611] <TB1> INFO: 41600 events read in total (3377ms).
[14:13:20.611] <TB1> INFO: Test took 4206ms.
[14:13:20.614] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[14:13:20.873] <TB1> INFO: Expecting 41600 events.
[14:13:24.815] <TB1> INFO: 41600 events read in total (3385ms).
[14:13:24.815] <TB1> INFO: Test took 4201ms.
[14:13:24.818] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[14:13:25.082] <TB1> INFO: Expecting 41600 events.
[14:13:28.995] <TB1> INFO: 41600 events read in total (3357ms).
[14:13:28.996] <TB1> INFO: Test took 4178ms.
[14:13:29.477] <TB1> INFO: PixTestGainPedestal::measure() done
[14:14:00.196] <TB1> INFO: PixTestGainPedestal::fit() done
[14:14:00.196] <TB1> INFO: non-linearity mean: 0.956 0.948 0.952 0.947 0.948 0.953 0.950 0.949 0.955 0.951 0.950 0.959 0.954 0.959 0.955 0.955
[14:14:00.196] <TB1> INFO: non-linearity RMS: 0.006 0.007 0.006 0.007 0.005 0.007 0.006 0.006 0.006 0.006 0.007 0.006 0.005 0.007 0.006 0.006
[14:14:00.197] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[14:14:00.215] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[14:14:00.234] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[14:14:00.257] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[14:14:00.281] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[14:14:00.301] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[14:14:00.322] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[14:14:00.346] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[14:14:00.369] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[14:14:00.391] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[14:14:00.410] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[14:14:00.428] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[14:14:00.447] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[14:14:00.466] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[14:14:00.484] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[14:14:00.503] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[14:14:00.522] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 156 seconds
[14:14:00.522] <TB1> INFO: Decoding statistics:
[14:14:00.522] <TB1> INFO: General information:
[14:14:00.522] <TB1> INFO: 16bit words read: 2662320
[14:14:00.522] <TB1> INFO: valid events total: 166400
[14:14:00.522] <TB1> INFO: empty events: 0
[14:14:00.522] <TB1> INFO: valid events with pixels: 166400
[14:14:00.522] <TB1> INFO: valid pixel hits: 665560
[14:14:00.522] <TB1> INFO: Event errors: 0
[14:14:00.522] <TB1> INFO: start marker: 0
[14:14:00.522] <TB1> INFO: stop marker: 0
[14:14:00.522] <TB1> INFO: overflow: 0
[14:14:00.522] <TB1> INFO: invalid 5bit words: 0
[14:14:00.522] <TB1> INFO: invalid XOR eye diagram: 0
[14:14:00.522] <TB1> INFO: frame (failed synchr.): 0
[14:14:00.522] <TB1> INFO: idle data (no TBM trl): 0
[14:14:00.522] <TB1> INFO: no data (only TBM hdr): 0
[14:14:00.522] <TB1> INFO: TBM errors: 0
[14:14:00.522] <TB1> INFO: flawed TBM headers: 0
[14:14:00.522] <TB1> INFO: flawed TBM trailers: 0
[14:14:00.522] <TB1> INFO: event ID mismatches: 0
[14:14:00.522] <TB1> INFO: ROC errors: 0
[14:14:00.522] <TB1> INFO: missing ROC header(s): 0
[14:14:00.522] <TB1> INFO: misplaced readback start: 0
[14:14:00.522] <TB1> INFO: Pixel decoding errors: 0
[14:14:00.522] <TB1> INFO: pixel data incomplete: 0
[14:14:00.522] <TB1> INFO: pixel address: 0
[14:14:00.522] <TB1> INFO: pulse height fill bit: 0
[14:14:00.522] <TB1> INFO: buffer corruption: 0
[14:14:00.529] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C15.dat
[14:14:00.530] <TB1> INFO: ######################################################################
[14:14:00.530] <TB1> INFO: PixTestReadback::doTest()
[14:14:00.530] <TB1> INFO: ######################################################################
[14:14:00.530] <TB1> INFO: ----------------------------------------------------------------------
[14:14:00.530] <TB1> INFO: PixTestReadback::CalibrateVd()
[14:14:00.530] <TB1> INFO: ----------------------------------------------------------------------
[14:14:10.015] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C0.dat
[14:14:10.016] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C1.dat
[14:14:10.016] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C2.dat
[14:14:10.016] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C3.dat
[14:14:10.016] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C4.dat
[14:14:10.016] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C5.dat
[14:14:10.016] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C6.dat
[14:14:10.016] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C7.dat
[14:14:10.016] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C8.dat
[14:14:10.016] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C9.dat
[14:14:10.016] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C10.dat
[14:14:10.017] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C11.dat
[14:14:10.017] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C12.dat
[14:14:10.017] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C13.dat
[14:14:10.017] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C14.dat
[14:14:10.017] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C15.dat
[14:14:10.048] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:14:10.048] <TB1> INFO: ----------------------------------------------------------------------
[14:14:10.048] <TB1> INFO: PixTestReadback::CalibrateVa()
[14:14:10.048] <TB1> INFO: ----------------------------------------------------------------------
[14:14:19.586] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C0.dat
[14:14:19.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C1.dat
[14:14:19.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C2.dat
[14:14:19.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C3.dat
[14:14:19.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C4.dat
[14:14:19.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C5.dat
[14:14:19.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C6.dat
[14:14:19.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C7.dat
[14:14:19.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C8.dat
[14:14:19.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C9.dat
[14:14:19.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C10.dat
[14:14:19.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C11.dat
[14:14:19.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C12.dat
[14:14:19.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C13.dat
[14:14:19.587] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C14.dat
[14:14:19.588] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C15.dat
[14:14:19.617] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:14:19.617] <TB1> INFO: ----------------------------------------------------------------------
[14:14:19.617] <TB1> INFO: PixTestReadback::readbackVbg()
[14:14:19.617] <TB1> INFO: ----------------------------------------------------------------------
[14:14:26.899] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:14:26.899] <TB1> INFO: ----------------------------------------------------------------------
[14:14:26.899] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[14:14:26.899] <TB1> INFO: ----------------------------------------------------------------------
[14:14:26.899] <TB1> INFO: Vbg will be calibrated using Vd calibration
[14:14:26.899] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 148.5calibrated Vbg = 1.19478 :::*/*/*/*/
[14:14:26.899] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 154.1calibrated Vbg = 1.19114 :::*/*/*/*/
[14:14:26.899] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 152.6calibrated Vbg = 1.1947 :::*/*/*/*/
[14:14:26.899] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 150.8calibrated Vbg = 1.19309 :::*/*/*/*/
[14:14:26.899] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 147.4calibrated Vbg = 1.20497 :::*/*/*/*/
[14:14:26.899] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 152calibrated Vbg = 1.20153 :::*/*/*/*/
[14:14:26.899] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 154.5calibrated Vbg = 1.20696 :::*/*/*/*/
[14:14:26.899] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 155.4calibrated Vbg = 1.19957 :::*/*/*/*/
[14:14:26.899] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 153.1calibrated Vbg = 1.20418 :::*/*/*/*/
[14:14:26.899] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 147calibrated Vbg = 1.20819 :::*/*/*/*/
[14:14:26.899] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 151calibrated Vbg = 1.20423 :::*/*/*/*/
[14:14:26.899] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 143calibrated Vbg = 1.19883 :::*/*/*/*/
[14:14:26.899] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 148.4calibrated Vbg = 1.1808 :::*/*/*/*/
[14:14:26.899] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 140.5calibrated Vbg = 1.18867 :::*/*/*/*/
[14:14:26.899] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 146.6calibrated Vbg = 1.19145 :::*/*/*/*/
[14:14:26.899] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 155.9calibrated Vbg = 1.18884 :::*/*/*/*/
[14:14:26.902] <TB1> INFO: ----------------------------------------------------------------------
[14:14:26.902] <TB1> INFO: PixTestReadback::CalibrateIa()
[14:14:26.902] <TB1> INFO: ----------------------------------------------------------------------
[14:17:02.134] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C0.dat
[14:17:02.134] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C1.dat
[14:17:02.134] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C2.dat
[14:17:02.134] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C3.dat
[14:17:02.134] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C4.dat
[14:17:02.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C5.dat
[14:17:02.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C6.dat
[14:17:02.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C7.dat
[14:17:02.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C8.dat
[14:17:02.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C9.dat
[14:17:02.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C10.dat
[14:17:02.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C11.dat
[14:17:02.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C12.dat
[14:17:02.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C13.dat
[14:17:02.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C14.dat
[14:17:02.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2328_FullQualification_2016-09-26_10h32m_1474878746//002_FulltestPxar_m20//readbackCal_C15.dat
[14:17:02.157] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:17:02.158] <TB1> INFO: PixTestReadback::doTest() done
[14:17:02.158] <TB1> INFO: Decoding statistics:
[14:17:02.158] <TB1> INFO: General information:
[14:17:02.158] <TB1> INFO: 16bit words read: 1024
[14:17:02.158] <TB1> INFO: valid events total: 128
[14:17:02.158] <TB1> INFO: empty events: 128
[14:17:02.158] <TB1> INFO: valid events with pixels: 0
[14:17:02.158] <TB1> INFO: valid pixel hits: 0
[14:17:02.158] <TB1> INFO: Event errors: 0
[14:17:02.158] <TB1> INFO: start marker: 0
[14:17:02.158] <TB1> INFO: stop marker: 0
[14:17:02.158] <TB1> INFO: overflow: 0
[14:17:02.158] <TB1> INFO: invalid 5bit words: 0
[14:17:02.158] <TB1> INFO: invalid XOR eye diagram: 0
[14:17:02.158] <TB1> INFO: frame (failed synchr.): 0
[14:17:02.158] <TB1> INFO: idle data (no TBM trl): 0
[14:17:02.158] <TB1> INFO: no data (only TBM hdr): 0
[14:17:02.158] <TB1> INFO: TBM errors: 0
[14:17:02.158] <TB1> INFO: flawed TBM headers: 0
[14:17:02.158] <TB1> INFO: flawed TBM trailers: 0
[14:17:02.158] <TB1> INFO: event ID mismatches: 0
[14:17:02.158] <TB1> INFO: ROC errors: 0
[14:17:02.158] <TB1> INFO: missing ROC header(s): 0
[14:17:02.158] <TB1> INFO: misplaced readback start: 0
[14:17:02.158] <TB1> INFO: Pixel decoding errors: 0
[14:17:02.158] <TB1> INFO: pixel data incomplete: 0
[14:17:02.158] <TB1> INFO: pixel address: 0
[14:17:02.158] <TB1> INFO: pulse height fill bit: 0
[14:17:02.158] <TB1> INFO: buffer corruption: 0
[14:17:02.177] <TB1> INFO: Decoding statistics:
[14:17:02.177] <TB1> INFO: General information:
[14:17:02.178] <TB1> INFO: 16bit words read: 2750278
[14:17:02.178] <TB1> INFO: valid events total: 176768
[14:17:02.178] <TB1> INFO: empty events: 7861
[14:17:02.178] <TB1> INFO: valid events with pixels: 168907
[14:17:02.178] <TB1> INFO: valid pixel hits: 668067
[14:17:02.178] <TB1> INFO: Event errors: 0
[14:17:02.178] <TB1> INFO: start marker: 0
[14:17:02.178] <TB1> INFO: stop marker: 0
[14:17:02.178] <TB1> INFO: overflow: 0
[14:17:02.178] <TB1> INFO: invalid 5bit words: 0
[14:17:02.178] <TB1> INFO: invalid XOR eye diagram: 0
[14:17:02.178] <TB1> INFO: frame (failed synchr.): 0
[14:17:02.178] <TB1> INFO: idle data (no TBM trl): 0
[14:17:02.178] <TB1> INFO: no data (only TBM hdr): 0
[14:17:02.178] <TB1> INFO: TBM errors: 0
[14:17:02.178] <TB1> INFO: flawed TBM headers: 0
[14:17:02.178] <TB1> INFO: flawed TBM trailers: 0
[14:17:02.178] <TB1> INFO: event ID mismatches: 0
[14:17:02.178] <TB1> INFO: ROC errors: 0
[14:17:02.178] <TB1> INFO: missing ROC header(s): 0
[14:17:02.178] <TB1> INFO: misplaced readback start: 0
[14:17:02.178] <TB1> INFO: Pixel decoding errors: 0
[14:17:02.178] <TB1> INFO: pixel data incomplete: 0
[14:17:02.178] <TB1> INFO: pixel address: 0
[14:17:02.178] <TB1> INFO: pulse height fill bit: 0
[14:17:02.178] <TB1> INFO: buffer corruption: 0
[14:17:02.178] <TB1> INFO: enter test to run
[14:17:02.178] <TB1> INFO: test: exit no parameter change
[14:17:02.368] <TB1> QUIET: Connection to board 153 closed.
[14:17:02.448] <TB1> INFO: pXar: this is the end, my friend