[12:59:10.993] <TB1> INFO: *** Welcome to pxar ***
[12:59:10.993] <TB1> INFO: *** Today: 2016/10/10
[12:59:11.097] <TB1> INFO: *** Version: 0b00-dirty
[12:59:11.097] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C15.dat
[12:59:11.098] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//tbmParameters_C0b.dat
[12:59:11.098] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//defaultMaskFile.dat
[12:59:11.099] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters_C15.dat
[12:59:11.168] <TB1> INFO: clk: 4
[12:59:11.168] <TB1> INFO: ctr: 4
[12:59:11.168] <TB1> INFO: sda: 19
[12:59:11.168] <TB1> INFO: tin: 9
[12:59:11.168] <TB1> INFO: level: 15
[12:59:11.169] <TB1> INFO: triggerdelay: 0
[12:59:11.169] <TB1> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[12:59:11.169] <TB1> INFO: Log level: INFO
[12:59:11.177] <TB1> INFO: Found DTB DTB_WXBYFL
[12:59:11.190] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[12:59:11.192] <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:59:11.194] <TB1> INFO: RPC call hashes of host and DTB match: 486171790
[12:59:12.697] <TB1> INFO: DUT info:
[12:59:12.698] <TB1> INFO: The DUT currently contains the following objects:
[12:59:12.698] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[12:59:12.698] <TB1> INFO: TBM Core alpha (0): 7 registers set
[12:59:12.698] <TB1> INFO: TBM Core beta (1): 7 registers set
[12:59:12.698] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:59:12.698] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:12.698] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:12.698] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:12.698] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:12.698] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:12.698] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:12.698] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:12.698] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:12.698] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:12.698] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:12.698] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:12.698] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:12.698] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:12.698] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:12.698] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:12.698] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:59:13.098] <TB1> INFO: enter 'restricted' command line mode
[12:59:13.098] <TB1> INFO: enter test to run
[12:59:13.099] <TB1> INFO: test: pretest no parameter change
[12:59:13.099] <TB1> INFO: running: pretest
[12:59:13.105] <TB1> INFO: ----------------------------------------------------------------------
[12:59:13.105] <TB1> INFO: PixTestPretest::programROC()
[12:59:13.105] <TB1> INFO: ----------------------------------------------------------------------
[12:59:31.120] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:59:31.120] <TB1> INFO: IA differences per ROC: 17.7 18.5 17.7 16.9 18.5 17.7 20.1 18.5 15.3 18.5 18.5 17.7 16.9 18.5 17.7 20.1
[12:59:31.216] <TB1> INFO: enter test to run
[12:59:31.216] <TB1> INFO: test: pretest no parameter change
[12:59:31.216] <TB1> INFO: running: pretest
[12:59:31.217] <TB1> INFO: ----------------------------------------------------------------------
[12:59:31.217] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:59:31.217] <TB1> INFO: ----------------------------------------------------------------------
[12:59:52.499] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 386.7 mA = 24.1687 mA/ROC
[12:59:52.499] <TB1> INFO: i(loss) [mA/ROC]: 20.1 19.3 20.1 20.1 20.1 20.1 19.3 20.1 19.3 18.5 20.1 19.3 19.3 19.3 20.1 20.1
[12:59:52.536] <TB1> INFO: enter test to run
[12:59:52.536] <TB1> INFO: test: pretest no parameter change
[12:59:52.536] <TB1> INFO: running: pretest
[12:59:52.536] <TB1> INFO: ----------------------------------------------------------------------
[12:59:52.536] <TB1> INFO: PixTestPretest::findTiming()
[12:59:52.536] <TB1> INFO: ----------------------------------------------------------------------
[12:59:52.536] <TB1> INFO: PixTestCmd::init()

[13:01:22.932] <TB1> INFO: TBM phases: 160MHz: 0, 400MHz: 4, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[13:01:22.932] <TB1> INFO: (success/tries = 100/100), width = 4
[13:01:22.933] <TB1> INFO: enter test to run
[13:01:22.933] <TB1> INFO: test: pretest no parameter change
[13:01:22.933] <TB1> INFO: running: pretest
[13:01:22.935] <TB1> INFO: ----------------------------------------------------------------------
[13:01:22.935] <TB1> INFO: PixTestPretest::findWorkingPixel()
[13:01:22.935] <TB1> INFO: ----------------------------------------------------------------------
[13:01:23.028] <TB1> INFO: Expecting 231680 events.


[13:01:32.287] <TB1> INFO: 231680 events read in total (8702ms).
[13:01:32.294] <TB1> INFO: Test took 9353ms.
[13:01:32.497] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[13:01:32.552] <TB1> INFO: enter test to run
[13:01:32.552] <TB1> INFO: test: pretest no parameter change
[13:01:32.552] <TB1> INFO: running: pretest
[13:01:32.554] <TB1> INFO: ----------------------------------------------------------------------
[13:01:32.554] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[13:01:32.554] <TB1> INFO: ----------------------------------------------------------------------
[13:01:32.647] <TB1> INFO: Expecting 231680 events.
[13:01:41.917] <TB1> INFO: 231680 events read in total (8713ms).
[13:01:41.923] <TB1> INFO: Test took 9364ms.
[13:01:42.151] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[13:01:42.151] <TB1> INFO: CalDel: 170 144 144 139 144 143 140 135 126 138 124 146 136 126 122 153
[13:01:42.151] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[13:01:42.185] <TB1> INFO: enter test to run
[13:01:42.185] <TB1> INFO: test: pretest no parameter change
[13:01:42.185] <TB1> INFO: running: pretest
[13:01:42.185] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C0.dat
[13:01:42.185] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C1.dat
[13:01:42.185] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C2.dat
[13:01:42.185] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C3.dat
[13:01:42.185] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C4.dat
[13:01:42.185] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C5.dat
[13:01:42.185] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C6.dat
[13:01:42.186] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C7.dat
[13:01:42.186] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C8.dat
[13:01:42.186] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C9.dat
[13:01:42.186] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C10.dat
[13:01:42.186] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C11.dat
[13:01:42.186] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C12.dat
[13:01:42.186] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C13.dat
[13:01:42.186] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C14.dat
[13:01:42.186] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters_C15.dat
[13:01:42.187] <TB1> INFO: enter test to run
[13:01:42.187] <TB1> INFO: test: fulltest no parameter change
[13:01:42.187] <TB1> INFO: running: fulltest
[13:01:42.187] <TB1> INFO: ######################################################################
[13:01:42.187] <TB1> INFO: PixTestFullTest::doTest()
[13:01:42.187] <TB1> INFO: ######################################################################
[13:01:42.188] <TB1> INFO: ######################################################################
[13:01:42.188] <TB1> INFO: PixTestAlive::doTest()
[13:01:42.188] <TB1> INFO: ######################################################################
[13:01:42.189] <TB1> INFO: ----------------------------------------------------------------------
[13:01:42.189] <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)
[13:01:42.189] <TB1> INFO: ----------------------------------------------------------------------
[13:01:42.461] <TB1> INFO: Expecting 41600 events.
[13:01:45.868] <TB1> INFO: 41600 events read in total (2851ms).
[13:01:45.868] <TB1> INFO: Test took 3678ms.
[13:01:46.088] <TB1> INFO: PixTestAlive::aliveTest() done
[13:01:46.088] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:01:46.089] <TB1> INFO: ----------------------------------------------------------------------
[13:01:46.089] <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)
[13:01:46.089] <TB1> INFO: ----------------------------------------------------------------------
[13:01:46.356] <TB1> INFO: Expecting 41600 events.
[13:01:49.123] <TB1> INFO: 41600 events read in total (2210ms).
[13:01:49.123] <TB1> INFO: Test took 3033ms.
[13:01:49.124] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[13:01:49.358] <TB1> INFO: PixTestAlive::maskTest() done
[13:01:49.358] <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
[13:01:49.359] <TB1> INFO: ----------------------------------------------------------------------
[13:01:49.359] <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)
[13:01:49.359] <TB1> INFO: ----------------------------------------------------------------------
[13:01:49.647] <TB1> INFO: Expecting 41600 events.
[13:01:53.110] <TB1> INFO: 41600 events read in total (2907ms).
[13:01:53.111] <TB1> INFO: Test took 3751ms.
[13:01:53.335] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[13:01:53.336] <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
[13:01:53.336] <TB1> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[13:01:53.336] <TB1> INFO: Decoding statistics:
[13:01:53.336] <TB1> INFO: General information:
[13:01:53.336] <TB1> INFO: 16bit words read: 0
[13:01:53.336] <TB1> INFO: valid events total: 0
[13:01:53.336] <TB1> INFO: empty events: 0
[13:01:53.336] <TB1> INFO: valid events with pixels: 0
[13:01:53.336] <TB1> INFO: valid pixel hits: 0
[13:01:53.336] <TB1> INFO: Event errors: 0
[13:01:53.336] <TB1> INFO: start marker: 0
[13:01:53.336] <TB1> INFO: stop marker: 0
[13:01:53.336] <TB1> INFO: overflow: 0
[13:01:53.336] <TB1> INFO: invalid 5bit words: 0
[13:01:53.336] <TB1> INFO: invalid XOR eye diagram: 0
[13:01:53.336] <TB1> INFO: frame (failed synchr.): 0
[13:01:53.336] <TB1> INFO: idle data (no TBM trl): 0
[13:01:53.336] <TB1> INFO: no data (only TBM hdr): 0
[13:01:53.336] <TB1> INFO: TBM errors: 0
[13:01:53.336] <TB1> INFO: flawed TBM headers: 0
[13:01:53.336] <TB1> INFO: flawed TBM trailers: 0
[13:01:53.336] <TB1> INFO: event ID mismatches: 0
[13:01:53.336] <TB1> INFO: ROC errors: 0
[13:01:53.336] <TB1> INFO: missing ROC header(s): 0
[13:01:53.336] <TB1> INFO: misplaced readback start: 0
[13:01:53.336] <TB1> INFO: Pixel decoding errors: 0
[13:01:53.336] <TB1> INFO: pixel data incomplete: 0
[13:01:53.336] <TB1> INFO: pixel address: 0
[13:01:53.336] <TB1> INFO: pulse height fill bit: 0
[13:01:53.336] <TB1> INFO: buffer corruption: 0
[13:01:53.345] <TB1> INFO: ######################################################################
[13:01:53.345] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[13:01:53.345] <TB1> INFO: ######################################################################
[13:01:53.349] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[13:01:53.362] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:01:53.362] <TB1> INFO: run 1 of 1
[13:01:53.663] <TB1> INFO: Expecting 3120000 events.
[13:02:31.498] <TB1> INFO: 880125 events read in total (37279ms).
[13:03:08.170] <TB1> INFO: 1746495 events read in total (73951ms).
[13:03:45.144] <TB1> INFO: 2624690 events read in total (110925ms).
[13:04:05.793] <TB1> INFO: 3120000 events read in total (131575ms).
[13:04:05.862] <TB1> INFO: Test took 132500ms.
[13:04:31.017] <TB1> INFO: PixTestBBMap::doTest() done, duration: 157 seconds
[13:04:31.017] <TB1> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 1 0 0 0 0 0 0 0 0
[13:04:31.017] <TB1> INFO: separation cut (per ROC): 88 88 90 91 84 93 87 89 85 86 92 93 96 96 88 89
[13:04:31.017] <TB1> INFO: Decoding statistics:
[13:04:31.017] <TB1> INFO: General information:
[13:04:31.017] <TB1> INFO: 16bit words read: 0
[13:04:31.017] <TB1> INFO: valid events total: 0
[13:04:31.017] <TB1> INFO: empty events: 0
[13:04:31.017] <TB1> INFO: valid events with pixels: 0
[13:04:31.017] <TB1> INFO: valid pixel hits: 0
[13:04:31.017] <TB1> INFO: Event errors: 0
[13:04:31.017] <TB1> INFO: start marker: 0
[13:04:31.017] <TB1> INFO: stop marker: 0
[13:04:31.017] <TB1> INFO: overflow: 0
[13:04:31.017] <TB1> INFO: invalid 5bit words: 0
[13:04:31.017] <TB1> INFO: invalid XOR eye diagram: 0
[13:04:31.017] <TB1> INFO: frame (failed synchr.): 0
[13:04:31.017] <TB1> INFO: idle data (no TBM trl): 0
[13:04:31.017] <TB1> INFO: no data (only TBM hdr): 0
[13:04:31.017] <TB1> INFO: TBM errors: 0
[13:04:31.017] <TB1> INFO: flawed TBM headers: 0
[13:04:31.017] <TB1> INFO: flawed TBM trailers: 0
[13:04:31.017] <TB1> INFO: event ID mismatches: 0
[13:04:31.017] <TB1> INFO: ROC errors: 0
[13:04:31.017] <TB1> INFO: missing ROC header(s): 0
[13:04:31.017] <TB1> INFO: misplaced readback start: 0
[13:04:31.017] <TB1> INFO: Pixel decoding errors: 0
[13:04:31.017] <TB1> INFO: pixel data incomplete: 0
[13:04:31.017] <TB1> INFO: pixel address: 0
[13:04:31.017] <TB1> INFO: pulse height fill bit: 0
[13:04:31.017] <TB1> INFO: buffer corruption: 0
[13:04:31.092] <TB1> INFO: ######################################################################
[13:04:31.092] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[13:04:31.092] <TB1> INFO: ######################################################################
[13:04:31.092] <TB1> INFO: ----------------------------------------------------------------------
[13:04:31.092] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[13:04:31.092] <TB1> INFO: ----------------------------------------------------------------------
[13:04:31.092] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[13:04:31.101] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:04:31.101] <TB1> INFO: run 1 of 1
[13:04:31.363] <TB1> INFO: Expecting 36608000 events.
[13:05:01.336] <TB1> INFO: 841350 events read in total (29416ms).
[13:05:29.886] <TB1> INFO: 1667100 events read in total (57966ms).
[13:05:58.514] <TB1> INFO: 2493400 events read in total (86594ms).
[13:06:27.430] <TB1> INFO: 3316600 events read in total (115510ms).
[13:06:56.298] <TB1> INFO: 4142650 events read in total (144378ms).
[13:07:24.745] <TB1> INFO: 4968700 events read in total (172825ms).
[13:07:53.461] <TB1> INFO: 5789650 events read in total (201541ms).
[13:08:22.021] <TB1> INFO: 6613100 events read in total (230101ms).
[13:08:50.286] <TB1> INFO: 7434950 events read in total (258366ms).
[13:09:19.076] <TB1> INFO: 8258300 events read in total (287156ms).
[13:09:47.656] <TB1> INFO: 9080000 events read in total (315736ms).
[13:10:18.079] <TB1> INFO: 9901400 events read in total (346159ms).
[13:10:48.128] <TB1> INFO: 10722350 events read in total (376208ms).
[13:11:17.940] <TB1> INFO: 11541550 events read in total (406020ms).
[13:11:46.292] <TB1> INFO: 12361600 events read in total (434372ms).
[13:12:15.046] <TB1> INFO: 13182050 events read in total (463126ms).
[13:12:43.782] <TB1> INFO: 13999900 events read in total (491862ms).
[13:13:12.203] <TB1> INFO: 14819450 events read in total (520283ms).
[13:13:40.954] <TB1> INFO: 15636300 events read in total (549034ms).
[13:14:09.573] <TB1> INFO: 16455050 events read in total (577653ms).
[13:14:38.209] <TB1> INFO: 17272800 events read in total (606289ms).
[13:15:06.805] <TB1> INFO: 18088300 events read in total (634885ms).
[13:15:35.312] <TB1> INFO: 18899150 events read in total (663392ms).
[13:16:04.098] <TB1> INFO: 19711100 events read in total (692178ms).
[13:16:32.640] <TB1> INFO: 20522550 events read in total (720720ms).
[13:17:00.533] <TB1> INFO: 21334250 events read in total (748613ms).
[13:17:29.157] <TB1> INFO: 22143250 events read in total (777237ms).
[13:17:57.658] <TB1> INFO: 22952250 events read in total (805738ms).
[13:18:26.207] <TB1> INFO: 23760700 events read in total (834287ms).
[13:18:54.757] <TB1> INFO: 24570800 events read in total (862837ms).
[13:19:23.130] <TB1> INFO: 25379100 events read in total (891210ms).
[13:19:50.995] <TB1> INFO: 26188000 events read in total (919075ms).
[13:20:19.629] <TB1> INFO: 26995150 events read in total (947709ms).
[13:20:48.135] <TB1> INFO: 27803200 events read in total (976215ms).
[13:21:16.662] <TB1> INFO: 28610450 events read in total (1004742ms).
[13:21:44.970] <TB1> INFO: 29416450 events read in total (1033050ms).
[13:22:13.581] <TB1> INFO: 30224700 events read in total (1061661ms).
[13:22:41.899] <TB1> INFO: 31028150 events read in total (1089979ms).
[13:23:10.032] <TB1> INFO: 31834000 events read in total (1118112ms).
[13:23:38.654] <TB1> INFO: 32639450 events read in total (1146734ms).
[13:24:07.473] <TB1> INFO: 33446000 events read in total (1175553ms).
[13:24:35.772] <TB1> INFO: 34253950 events read in total (1203852ms).
[13:25:04.329] <TB1> INFO: 35059150 events read in total (1232409ms).
[13:25:32.966] <TB1> INFO: 35867100 events read in total (1261046ms).
[13:25:58.993] <TB1> INFO: 36608000 events read in total (1287073ms).
[13:25:59.047] <TB1> INFO: Test took 1287946ms.
[13:25:59.481] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:01.290] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:03.198] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:05.006] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:06.791] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:08.573] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:10.370] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:12.333] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:14.493] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:16.251] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:18.197] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:20.242] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:22.299] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:24.496] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:26.564] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:28.217] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:26:30.372] <TB1> INFO: PixTestScurves::scurves() done
[13:26:30.372] <TB1> INFO: Vcal mean: 88.27 92.98 91.76 88.47 88.74 95.68 86.70 90.49 91.61 84.42 87.67 93.40 93.74 90.21 86.54 93.87
[13:26:30.372] <TB1> INFO: Vcal RMS: 4.70 5.53 4.66 4.95 5.09 4.76 4.95 4.93 4.94 4.46 5.10 5.38 5.33 5.01 4.72 4.83
[13:26:30.372] <TB1> INFO: PixTestScurves::fullTest() done, duration: 1319 seconds
[13:26:30.372] <TB1> INFO: Decoding statistics:
[13:26:30.372] <TB1> INFO: General information:
[13:26:30.372] <TB1> INFO: 16bit words read: 0
[13:26:30.372] <TB1> INFO: valid events total: 0
[13:26:30.372] <TB1> INFO: empty events: 0
[13:26:30.372] <TB1> INFO: valid events with pixels: 0
[13:26:30.372] <TB1> INFO: valid pixel hits: 0
[13:26:30.372] <TB1> INFO: Event errors: 0
[13:26:30.372] <TB1> INFO: start marker: 0
[13:26:30.372] <TB1> INFO: stop marker: 0
[13:26:30.372] <TB1> INFO: overflow: 0
[13:26:30.372] <TB1> INFO: invalid 5bit words: 0
[13:26:30.372] <TB1> INFO: invalid XOR eye diagram: 0
[13:26:30.372] <TB1> INFO: frame (failed synchr.): 0
[13:26:30.372] <TB1> INFO: idle data (no TBM trl): 0
[13:26:30.372] <TB1> INFO: no data (only TBM hdr): 0
[13:26:30.372] <TB1> INFO: TBM errors: 0
[13:26:30.372] <TB1> INFO: flawed TBM headers: 0
[13:26:30.372] <TB1> INFO: flawed TBM trailers: 0
[13:26:30.372] <TB1> INFO: event ID mismatches: 0
[13:26:30.372] <TB1> INFO: ROC errors: 0
[13:26:30.372] <TB1> INFO: missing ROC header(s): 0
[13:26:30.372] <TB1> INFO: misplaced readback start: 0
[13:26:30.372] <TB1> INFO: Pixel decoding errors: 0
[13:26:30.372] <TB1> INFO: pixel data incomplete: 0
[13:26:30.372] <TB1> INFO: pixel address: 0
[13:26:30.372] <TB1> INFO: pulse height fill bit: 0
[13:26:30.372] <TB1> INFO: buffer corruption: 0
[13:26:30.447] <TB1> INFO: ######################################################################
[13:26:30.447] <TB1> INFO: PixTestTrim::doTest()
[13:26:30.447] <TB1> INFO: ######################################################################
[13:26:30.448] <TB1> INFO: ----------------------------------------------------------------------
[13:26:30.448] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[13:26:30.448] <TB1> INFO: ----------------------------------------------------------------------
[13:26:30.545] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[13:26:30.545] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:26:30.556] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:26:30.556] <TB1> INFO: run 1 of 1
[13:26:30.857] <TB1> INFO: Expecting 6281600 events.
[13:27:07.143] <TB1> INFO: 1103830 events read in total (35722ms).
[13:27:42.683] <TB1> INFO: 2201440 events read in total (71263ms).
[13:28:18.680] <TB1> INFO: 3293140 events read in total (107259ms).
[13:28:55.123] <TB1> INFO: 4392390 events read in total (143702ms).
[13:29:31.413] <TB1> INFO: 5496700 events read in total (179992ms).
[13:29:56.833] <TB1> INFO: 6281600 events read in total (205412ms).
[13:29:56.884] <TB1> INFO: Test took 206328ms.
[13:30:18.308] <TB1> INFO: ROC 0 VthrComp = 93
[13:30:18.309] <TB1> INFO: ROC 1 VthrComp = 96
[13:30:18.311] <TB1> INFO: ROC 2 VthrComp = 98
[13:30:18.311] <TB1> INFO: ROC 3 VthrComp = 94
[13:30:18.312] <TB1> INFO: ROC 4 VthrComp = 91
[13:30:18.312] <TB1> INFO: ROC 5 VthrComp = 99
[13:30:18.312] <TB1> INFO: ROC 6 VthrComp = 91
[13:30:18.312] <TB1> INFO: ROC 7 VthrComp = 94
[13:30:18.312] <TB1> INFO: ROC 8 VthrComp = 93
[13:30:18.312] <TB1> INFO: ROC 9 VthrComp = 88
[13:30:18.312] <TB1> INFO: ROC 10 VthrComp = 94
[13:30:18.312] <TB1> INFO: ROC 11 VthrComp = 95
[13:30:18.312] <TB1> INFO: ROC 12 VthrComp = 95
[13:30:18.313] <TB1> INFO: ROC 13 VthrComp = 97
[13:30:18.313] <TB1> INFO: ROC 14 VthrComp = 92
[13:30:18.313] <TB1> INFO: ROC 15 VthrComp = 98
[13:30:18.313] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[13:30:18.313] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:30:18.322] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:30:18.322] <TB1> INFO: run 1 of 1
[13:30:18.587] <TB1> INFO: Expecting 6281600 events.
[13:30:50.230] <TB1> INFO: 758640 events read in total (31087ms).
[13:31:20.751] <TB1> INFO: 1514920 events read in total (61608ms).
[13:31:51.559] <TB1> INFO: 2271620 events read in total (92416ms).
[13:32:22.453] <TB1> INFO: 3028160 events read in total (123310ms).
[13:32:53.438] <TB1> INFO: 3779090 events read in total (154295ms).
[13:33:24.125] <TB1> INFO: 4526510 events read in total (184982ms).
[13:33:54.822] <TB1> INFO: 5271680 events read in total (215679ms).
[13:34:26.157] <TB1> INFO: 6015890 events read in total (247014ms).
[13:34:37.485] <TB1> INFO: 6281600 events read in total (258342ms).
[13:34:37.535] <TB1> INFO: Test took 259214ms.
[13:35:04.135] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 55.4867 for pixel 7/9 mean/min/max = 44.3172/33.0807/55.5538
[13:35:04.135] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 57.3603 for pixel 16/57 mean/min/max = 44.8832/32.0746/57.6917
[13:35:04.135] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 55.2569 for pixel 2/30 mean/min/max = 43.2714/31.2598/55.2831
[13:35:04.135] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 56.4185 for pixel 18/79 mean/min/max = 44.4678/32.4499/56.4857
[13:35:04.136] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 58.1249 for pixel 0/2 mean/min/max = 45.9484/33.588/58.3088
[13:35:04.136] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 56.2872 for pixel 18/10 mean/min/max = 44.1868/32.0757/56.298
[13:35:04.136] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 57.5785 for pixel 12/18 mean/min/max = 45.4992/33.3299/57.6684
[13:35:04.137] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 56.9103 for pixel 9/0 mean/min/max = 45.551/34.1819/56.9201
[13:35:04.137] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 57.7482 for pixel 9/31 mean/min/max = 45.8627/33.964/57.7614
[13:35:04.138] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 55.4697 for pixel 3/18 mean/min/max = 45.1458/34.8187/55.4729
[13:35:04.138] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 56.9187 for pixel 10/8 mean/min/max = 45.1382/33.0837/57.1927
[13:35:04.138] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 58.8802 for pixel 0/76 mean/min/max = 45.5289/31.9854/59.0725
[13:35:04.139] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 59.3379 for pixel 7/6 mean/min/max = 45.7338/31.5544/59.9133
[13:35:04.139] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 56.8677 for pixel 0/18 mean/min/max = 44.5436/31.9705/57.1167
[13:35:04.140] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 55.2629 for pixel 16/2 mean/min/max = 44.6283/33.8749/55.3817
[13:35:04.140] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 56.0415 for pixel 0/0 mean/min/max = 44.0827/32.0129/56.1524
[13:35:04.140] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:35:04.229] <TB1> INFO: Expecting 514560 events.
[13:35:15.025] <TB1> INFO: 514560 events read in total (10240ms).
[13:35:15.031] <TB1> INFO: Expecting 514560 events.
[13:35:25.737] <TB1> INFO: 514560 events read in total (10304ms).
[13:35:25.747] <TB1> INFO: Expecting 514560 events.
[13:35:36.571] <TB1> INFO: 514560 events read in total (10422ms).
[13:35:36.584] <TB1> INFO: Expecting 514560 events.
[13:35:47.275] <TB1> INFO: 514560 events read in total (10289ms).
[13:35:47.289] <TB1> INFO: Expecting 514560 events.
[13:35:57.944] <TB1> INFO: 514560 events read in total (10254ms).
[13:35:57.960] <TB1> INFO: Expecting 514560 events.
[13:36:08.652] <TB1> INFO: 514560 events read in total (10290ms).
[13:36:08.672] <TB1> INFO: Expecting 514560 events.
[13:36:19.493] <TB1> INFO: 514560 events read in total (10419ms).
[13:36:19.518] <TB1> INFO: Expecting 514560 events.
[13:36:30.265] <TB1> INFO: 514560 events read in total (10345ms).
[13:36:30.290] <TB1> INFO: Expecting 514560 events.
[13:36:41.099] <TB1> INFO: 514560 events read in total (10407ms).
[13:36:41.131] <TB1> INFO: Expecting 514560 events.
[13:36:51.891] <TB1> INFO: 514560 events read in total (10358ms).
[13:36:51.920] <TB1> INFO: Expecting 514560 events.
[13:37:02.654] <TB1> INFO: 514560 events read in total (10332ms).
[13:37:02.692] <TB1> INFO: Expecting 514560 events.
[13:37:13.337] <TB1> INFO: 514560 events read in total (10243ms).
[13:37:13.372] <TB1> INFO: Expecting 514560 events.
[13:37:24.130] <TB1> INFO: 514560 events read in total (10356ms).
[13:37:24.173] <TB1> INFO: Expecting 514560 events.
[13:37:34.976] <TB1> INFO: 514560 events read in total (10401ms).
[13:37:35.025] <TB1> INFO: Expecting 514560 events.
[13:37:45.719] <TB1> INFO: 514560 events read in total (10292ms).
[13:37:45.765] <TB1> INFO: Expecting 514560 events.
[13:37:56.481] <TB1> INFO: 514560 events read in total (10314ms).
[13:37:56.537] <TB1> INFO: Test took 172397ms.
[13:37:57.635] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[13:37:57.644] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:37:57.644] <TB1> INFO: run 1 of 1
[13:37:57.915] <TB1> INFO: Expecting 6281600 events.
[13:38:28.887] <TB1> INFO: 747420 events read in total (30415ms).
[13:38:59.825] <TB1> INFO: 1492980 events read in total (61353ms).
[13:39:30.510] <TB1> INFO: 2238770 events read in total (92038ms).
[13:40:02.420] <TB1> INFO: 2984610 events read in total (123948ms).
[13:40:34.472] <TB1> INFO: 3724850 events read in total (156000ms).
[13:41:05.759] <TB1> INFO: 4461440 events read in total (187287ms).
[13:41:36.551] <TB1> INFO: 5195380 events read in total (218079ms).
[13:42:06.948] <TB1> INFO: 5928150 events read in total (248476ms).
[13:42:21.727] <TB1> INFO: 6281600 events read in total (263255ms).
[13:42:21.792] <TB1> INFO: Test took 264149ms.
[13:42:45.509] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 11.500000 .. 49.379476
[13:42:45.770] <TB1> INFO: Expecting 208000 events.
[13:42:55.566] <TB1> INFO: 208000 events read in total (9240ms).
[13:42:55.567] <TB1> INFO: Test took 10057ms.
[13:42:55.659] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 1 .. 59 (-1/-1) hits flags = 528 (plus default)
[13:42:55.670] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:42:55.670] <TB1> INFO: run 1 of 1
[13:42:55.979] <TB1> INFO: Expecting 2454400 events.
[13:43:30.642] <TB1> INFO: 957510 events read in total (34107ms).
[13:44:04.662] <TB1> INFO: 1904460 events read in total (68127ms).
[13:44:24.702] <TB1> INFO: 2454400 events read in total (88167ms).
[13:44:24.729] <TB1> INFO: Test took 89059ms.
[13:44:40.479] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 21.442786 .. 45.974131
[13:44:40.833] <TB1> INFO: Expecting 208000 events.
[13:44:50.740] <TB1> INFO: 208000 events read in total (9351ms).
[13:44:50.741] <TB1> INFO: Test took 10260ms.
[13:44:50.861] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 11 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:44:50.872] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:44:50.872] <TB1> INFO: run 1 of 1
[13:44:51.151] <TB1> INFO: Expecting 1872000 events.
[13:45:25.381] <TB1> INFO: 921930 events read in total (33674ms).
[13:45:58.936] <TB1> INFO: 1840850 events read in total (67229ms).
[13:46:00.416] <TB1> INFO: 1872000 events read in total (68709ms).
[13:46:00.435] <TB1> INFO: Test took 69563ms.
[13:46:15.270] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 22.643780 .. 41.827169
[13:46:15.547] <TB1> INFO: Expecting 208000 events.
[13:46:25.514] <TB1> INFO: 208000 events read in total (9411ms).
[13:46:25.515] <TB1> INFO: Test took 10242ms.
[13:46:25.605] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 12 .. 51 (-1/-1) hits flags = 528 (plus default)
[13:46:25.613] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:46:25.613] <TB1> INFO: run 1 of 1
[13:46:25.926] <TB1> INFO: Expecting 1664000 events.
[13:47:00.593] <TB1> INFO: 951550 events read in total (34110ms).
[13:47:26.044] <TB1> INFO: 1664000 events read in total (59561ms).
[13:47:26.063] <TB1> INFO: Test took 60450ms.
[13:47:40.071] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 24.657234 .. 41.827169
[13:47:40.348] <TB1> INFO: Expecting 208000 events.
[13:47:50.373] <TB1> INFO: 208000 events read in total (9469ms).
[13:47:50.374] <TB1> INFO: Test took 10302ms.
[13:47:50.460] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 14 .. 51 (-1/-1) hits flags = 528 (plus default)
[13:47:50.469] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:47:50.469] <TB1> INFO: run 1 of 1
[13:47:50.734] <TB1> INFO: Expecting 1580800 events.
[13:48:25.707] <TB1> INFO: 938890 events read in total (34416ms).
[13:48:49.341] <TB1> INFO: 1580800 events read in total (58051ms).
[13:48:49.360] <TB1> INFO: Test took 58891ms.
[13:49:02.470] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[13:49:02.470] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:49:02.479] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:49:02.479] <TB1> INFO: run 1 of 1
[13:49:02.746] <TB1> INFO: Expecting 1705600 events.
[13:49:36.599] <TB1> INFO: 894870 events read in total (33297ms).
[13:50:06.940] <TB1> INFO: 1705600 events read in total (63639ms).
[13:50:06.961] <TB1> INFO: Test took 64482ms.
[13:50:21.431] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C0.dat
[13:50:21.431] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C1.dat
[13:50:21.431] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C2.dat
[13:50:21.431] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C3.dat
[13:50:21.431] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C4.dat
[13:50:21.431] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C5.dat
[13:50:21.432] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C6.dat
[13:50:21.432] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C7.dat
[13:50:21.432] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C8.dat
[13:50:21.432] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C9.dat
[13:50:21.432] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C10.dat
[13:50:21.432] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C11.dat
[13:50:21.432] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C12.dat
[13:50:21.432] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C13.dat
[13:50:21.432] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C14.dat
[13:50:21.433] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C15.dat
[13:50:21.433] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C0.dat
[13:50:21.440] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C1.dat
[13:50:21.446] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C2.dat
[13:50:21.453] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C3.dat
[13:50:21.459] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C4.dat
[13:50:21.467] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C5.dat
[13:50:21.474] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C6.dat
[13:50:21.480] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C7.dat
[13:50:21.487] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C8.dat
[13:50:21.493] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C9.dat
[13:50:21.500] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C10.dat
[13:50:21.506] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C11.dat
[13:50:21.513] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C12.dat
[13:50:21.519] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C13.dat
[13:50:21.526] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C14.dat
[13:50:21.533] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//trimParameters35_C15.dat
[13:50:21.539] <TB1> INFO: PixTestTrim::trimTest() done
[13:50:21.539] <TB1> INFO: vtrim: 96 104 93 83 94 104 102 107 95 89 98 102 122 95 90 98
[13:50:21.539] <TB1> INFO: vthrcomp: 93 96 98 94 91 99 91 94 93 88 94 95 95 97 92 98
[13:50:21.539] <TB1> INFO: vcal mean: 34.99 34.95 34.94 34.94 34.93 34.96 34.95 35.00 34.97 35.00 34.97 34.97 34.90 34.93 34.99 34.94
[13:50:21.539] <TB1> INFO: vcal RMS: 0.80 0.85 0.86 0.79 0.82 0.84 0.80 0.79 0.88 0.78 0.80 0.85 0.90 0.79 0.79 0.79
[13:50:21.539] <TB1> INFO: bits mean: 10.02 9.79 10.51 9.70 9.17 10.02 9.49 9.36 9.73 9.39 9.57 9.15 9.96 9.58 9.76 9.50
[13:50:21.539] <TB1> INFO: bits RMS: 2.38 2.64 2.42 2.66 2.63 2.55 2.57 2.52 2.33 2.38 2.60 2.93 2.58 2.80 2.38 2.80
[13:50:21.546] <TB1> INFO: ----------------------------------------------------------------------
[13:50:21.546] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[13:50:21.546] <TB1> INFO: ----------------------------------------------------------------------
[13:50:21.549] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[13:50:21.560] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:50:21.560] <TB1> INFO: run 1 of 1
[13:50:21.870] <TB1> INFO: Expecting 4160000 events.
[13:51:00.574] <TB1> INFO: 949845 events read in total (38148ms).
[13:51:38.286] <TB1> INFO: 1890415 events read in total (75860ms).
[13:52:15.933] <TB1> INFO: 2820850 events read in total (113507ms).
[13:52:53.440] <TB1> INFO: 3747660 events read in total (151014ms).
[13:53:10.615] <TB1> INFO: 4160000 events read in total (168189ms).
[13:53:10.689] <TB1> INFO: Test took 169129ms.
[13:53:40.997] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 158 (-1/-1) hits flags = 528 (plus default)
[13:53:41.005] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:53:41.005] <TB1> INFO: run 1 of 1
[13:53:41.267] <TB1> INFO: Expecting 3307200 events.
[13:54:20.699] <TB1> INFO: 1015060 events read in total (38876ms).
[13:54:59.295] <TB1> INFO: 2013710 events read in total (77472ms).
[13:55:38.106] <TB1> INFO: 3000490 events read in total (116283ms).
[13:55:50.297] <TB1> INFO: 3307200 events read in total (128474ms).
[13:55:50.344] <TB1> INFO: Test took 129337ms.
[13:56:16.611] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 151 (-1/-1) hits flags = 528 (plus default)
[13:56:16.619] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:56:16.619] <TB1> INFO: run 1 of 1
[13:56:16.882] <TB1> INFO: Expecting 3161600 events.
[13:56:57.670] <TB1> INFO: 1039610 events read in total (40232ms).
[13:57:36.950] <TB1> INFO: 2058915 events read in total (79512ms).
[13:58:15.980] <TB1> INFO: 3067175 events read in total (118542ms).
[13:58:20.015] <TB1> INFO: 3161600 events read in total (122577ms).
[13:58:20.055] <TB1> INFO: Test took 123436ms.
[13:58:45.699] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 528 (plus default)
[13:58:45.707] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:58:45.707] <TB1> INFO: run 1 of 1
[13:58:45.971] <TB1> INFO: Expecting 3120000 events.
[13:59:26.580] <TB1> INFO: 1046145 events read in total (40053ms).
[14:00:06.250] <TB1> INFO: 2071635 events read in total (79723ms).
[14:00:45.769] <TB1> INFO: 3086855 events read in total (119242ms).
[14:00:47.451] <TB1> INFO: 3120000 events read in total (120924ms).
[14:00:47.493] <TB1> INFO: Test took 121786ms.
[14:01:12.073] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 148 (-1/-1) hits flags = 528 (plus default)
[14:01:12.082] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[14:01:12.082] <TB1> INFO: run 1 of 1
[14:01:12.389] <TB1> INFO: Expecting 3099200 events.
[14:01:52.896] <TB1> INFO: 1049770 events read in total (39951ms).
[14:02:32.462] <TB1> INFO: 2078045 events read in total (79517ms).
[14:03:11.836] <TB1> INFO: 3096985 events read in total (118891ms).
[14:03:12.325] <TB1> INFO: 3099200 events read in total (119380ms).
[14:03:12.363] <TB1> INFO: Test took 120282ms.
[14:03:36.328] <TB1> INFO: PixTestTrim::trimBitTest() done
[14:03:36.329] <TB1> INFO: PixTestTrim::doTest() done, duration: 2225 seconds
[14:03:36.329] <TB1> INFO: Decoding statistics:
[14:03:36.329] <TB1> INFO: General information:
[14:03:36.329] <TB1> INFO: 16bit words read: 0
[14:03:36.329] <TB1> INFO: valid events total: 0
[14:03:36.329] <TB1> INFO: empty events: 0
[14:03:36.329] <TB1> INFO: valid events with pixels: 0
[14:03:36.329] <TB1> INFO: valid pixel hits: 0
[14:03:36.329] <TB1> INFO: Event errors: 0
[14:03:36.329] <TB1> INFO: start marker: 0
[14:03:36.329] <TB1> INFO: stop marker: 0
[14:03:36.329] <TB1> INFO: overflow: 0
[14:03:36.329] <TB1> INFO: invalid 5bit words: 0
[14:03:36.329] <TB1> INFO: invalid XOR eye diagram: 0
[14:03:36.329] <TB1> INFO: frame (failed synchr.): 0
[14:03:36.329] <TB1> INFO: idle data (no TBM trl): 0
[14:03:36.329] <TB1> INFO: no data (only TBM hdr): 0
[14:03:36.329] <TB1> INFO: TBM errors: 0
[14:03:36.329] <TB1> INFO: flawed TBM headers: 0
[14:03:36.329] <TB1> INFO: flawed TBM trailers: 0
[14:03:36.329] <TB1> INFO: event ID mismatches: 0
[14:03:36.329] <TB1> INFO: ROC errors: 0
[14:03:36.329] <TB1> INFO: missing ROC header(s): 0
[14:03:36.329] <TB1> INFO: misplaced readback start: 0
[14:03:36.329] <TB1> INFO: Pixel decoding errors: 0
[14:03:36.329] <TB1> INFO: pixel data incomplete: 0
[14:03:36.329] <TB1> INFO: pixel address: 0
[14:03:36.329] <TB1> INFO: pulse height fill bit: 0
[14:03:36.329] <TB1> INFO: buffer corruption: 0
[14:03:37.010] <TB1> INFO: ######################################################################
[14:03:37.010] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[14:03:37.010] <TB1> INFO: ######################################################################
[14:03:37.273] <TB1> INFO: Expecting 41600 events.
[14:03:40.803] <TB1> INFO: 41600 events read in total (2974ms).
[14:03:40.804] <TB1> INFO: Test took 3793ms.
[14:03:41.321] <TB1> INFO: Expecting 41600 events.
[14:03:44.908] <TB1> INFO: 41600 events read in total (3031ms).
[14:03:44.909] <TB1> INFO: Test took 3912ms.
[14:03:45.229] <TB1> INFO: Expecting 41600 events.
[14:03:48.790] <TB1> INFO: 41600 events read in total (3005ms).
[14:03:48.790] <TB1> INFO: Test took 3855ms.
[14:03:49.045] <TB1> INFO: Expecting 2560 events.
[14:03:49.898] <TB1> INFO: 2560 events read in total (297ms).
[14:03:49.898] <TB1> INFO: Test took 1095ms.
[14:03:50.203] <TB1> INFO: Expecting 2560 events.
[14:03:51.057] <TB1> INFO: 2560 events read in total (297ms).
[14:03:51.057] <TB1> INFO: Test took 1158ms.
[14:03:51.361] <TB1> INFO: Expecting 2560 events.
[14:03:52.213] <TB1> INFO: 2560 events read in total (296ms).
[14:03:52.214] <TB1> INFO: Test took 1157ms.
[14:03:52.519] <TB1> INFO: Expecting 2560 events.
[14:03:53.373] <TB1> INFO: 2560 events read in total (298ms).
[14:03:53.373] <TB1> INFO: Test took 1159ms.
[14:03:53.678] <TB1> INFO: Expecting 2560 events.
[14:03:54.530] <TB1> INFO: 2560 events read in total (296ms).
[14:03:54.530] <TB1> INFO: Test took 1157ms.
[14:03:54.835] <TB1> INFO: Expecting 2560 events.
[14:03:55.687] <TB1> INFO: 2560 events read in total (296ms).
[14:03:55.687] <TB1> INFO: Test took 1157ms.
[14:03:55.992] <TB1> INFO: Expecting 2560 events.
[14:03:56.848] <TB1> INFO: 2560 events read in total (300ms).
[14:03:56.848] <TB1> INFO: Test took 1161ms.
[14:03:57.153] <TB1> INFO: Expecting 2560 events.
[14:03:58.005] <TB1> INFO: 2560 events read in total (296ms).
[14:03:58.005] <TB1> INFO: Test took 1156ms.
[14:03:58.310] <TB1> INFO: Expecting 2560 events.
[14:03:59.163] <TB1> INFO: 2560 events read in total (296ms).
[14:03:59.163] <TB1> INFO: Test took 1158ms.
[14:03:59.467] <TB1> INFO: Expecting 2560 events.
[14:04:00.319] <TB1> INFO: 2560 events read in total (295ms).
[14:04:00.319] <TB1> INFO: Test took 1156ms.
[14:04:00.624] <TB1> INFO: Expecting 2560 events.
[14:04:01.477] <TB1> INFO: 2560 events read in total (297ms).
[14:04:01.477] <TB1> INFO: Test took 1158ms.
[14:04:01.782] <TB1> INFO: Expecting 2560 events.
[14:04:02.635] <TB1> INFO: 2560 events read in total (297ms).
[14:04:02.635] <TB1> INFO: Test took 1156ms.
[14:04:02.940] <TB1> INFO: Expecting 2560 events.
[14:04:03.796] <TB1> INFO: 2560 events read in total (300ms).
[14:04:03.796] <TB1> INFO: Test took 1161ms.
[14:04:04.100] <TB1> INFO: Expecting 2560 events.
[14:04:04.956] <TB1> INFO: 2560 events read in total (299ms).
[14:04:04.956] <TB1> INFO: Test took 1160ms.
[14:04:05.261] <TB1> INFO: Expecting 2560 events.
[14:04:06.114] <TB1> INFO: 2560 events read in total (297ms).
[14:04:06.114] <TB1> INFO: Test took 1157ms.
[14:04:06.418] <TB1> INFO: Expecting 2560 events.
[14:04:07.273] <TB1> INFO: 2560 events read in total (299ms).
[14:04:07.273] <TB1> INFO: Test took 1158ms.
[14:04:07.276] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:04:07.579] <TB1> INFO: Expecting 655360 events.
[14:04:21.493] <TB1> INFO: 655360 events read in total (13359ms).
[14:04:21.504] <TB1> INFO: Expecting 655360 events.
[14:04:35.235] <TB1> INFO: 655360 events read in total (13330ms).
[14:04:35.250] <TB1> INFO: Expecting 655360 events.
[14:04:49.050] <TB1> INFO: 655360 events read in total (13398ms).
[14:04:49.069] <TB1> INFO: Expecting 655360 events.
[14:05:02.703] <TB1> INFO: 655360 events read in total (13232ms).
[14:05:02.726] <TB1> INFO: Expecting 655360 events.
[14:05:16.489] <TB1> INFO: 655360 events read in total (13361ms).
[14:05:16.518] <TB1> INFO: Expecting 655360 events.
[14:05:30.295] <TB1> INFO: 655360 events read in total (13375ms).
[14:05:30.327] <TB1> INFO: Expecting 655360 events.
[14:05:44.168] <TB1> INFO: 655360 events read in total (13439ms).
[14:05:44.203] <TB1> INFO: Expecting 655360 events.
[14:05:58.076] <TB1> INFO: 655360 events read in total (13471ms).
[14:05:58.128] <TB1> INFO: Expecting 655360 events.
[14:06:12.020] <TB1> INFO: 655360 events read in total (13491ms).
[14:06:12.076] <TB1> INFO: Expecting 655360 events.
[14:06:25.957] <TB1> INFO: 655360 events read in total (13479ms).
[14:06:26.004] <TB1> INFO: Expecting 655360 events.
[14:06:39.856] <TB1> INFO: 655360 events read in total (13450ms).
[14:06:39.912] <TB1> INFO: Expecting 655360 events.
[14:06:53.649] <TB1> INFO: 655360 events read in total (13335ms).
[14:06:53.708] <TB1> INFO: Expecting 655360 events.
[14:07:07.579] <TB1> INFO: 655360 events read in total (13469ms).
[14:07:07.654] <TB1> INFO: Expecting 655360 events.
[14:07:21.457] <TB1> INFO: 655360 events read in total (13401ms).
[14:07:21.550] <TB1> INFO: Expecting 655360 events.
[14:07:35.515] <TB1> INFO: 655360 events read in total (13563ms).
[14:07:35.588] <TB1> INFO: Expecting 655360 events.
[14:07:49.445] <TB1> INFO: 655360 events read in total (13455ms).
[14:07:49.524] <TB1> INFO: Test took 222248ms.
[14:07:49.608] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:07:49.839] <TB1> INFO: Expecting 655360 events.
[14:08:03.746] <TB1> INFO: 655360 events read in total (13351ms).
[14:08:03.756] <TB1> INFO: Expecting 655360 events.
[14:08:17.521] <TB1> INFO: 655360 events read in total (13363ms).
[14:08:17.546] <TB1> INFO: Expecting 655360 events.
[14:08:31.437] <TB1> INFO: 655360 events read in total (13489ms).
[14:08:31.456] <TB1> INFO: Expecting 655360 events.
[14:08:45.197] <TB1> INFO: 655360 events read in total (13339ms).
[14:08:45.221] <TB1> INFO: Expecting 655360 events.
[14:08:59.008] <TB1> INFO: 655360 events read in total (13386ms).
[14:08:59.036] <TB1> INFO: Expecting 655360 events.
[14:09:12.738] <TB1> INFO: 655360 events read in total (13301ms).
[14:09:12.782] <TB1> INFO: Expecting 655360 events.
[14:09:26.661] <TB1> INFO: 655360 events read in total (13478ms).
[14:09:26.698] <TB1> INFO: Expecting 655360 events.
[14:09:40.503] <TB1> INFO: 655360 events read in total (13403ms).
[14:09:40.555] <TB1> INFO: Expecting 655360 events.
[14:09:54.237] <TB1> INFO: 655360 events read in total (13281ms).
[14:09:54.281] <TB1> INFO: Expecting 655360 events.
[14:10:08.121] <TB1> INFO: 655360 events read in total (13438ms).
[14:10:08.172] <TB1> INFO: Expecting 655360 events.
[14:10:22.059] <TB1> INFO: 655360 events read in total (13486ms).
[14:10:22.118] <TB1> INFO: Expecting 655360 events.
[14:10:36.047] <TB1> INFO: 655360 events read in total (13528ms).
[14:10:36.109] <TB1> INFO: Expecting 655360 events.
[14:10:50.079] <TB1> INFO: 655360 events read in total (13568ms).
[14:10:50.145] <TB1> INFO: Expecting 655360 events.
[14:11:03.913] <TB1> INFO: 655360 events read in total (13367ms).
[14:11:03.987] <TB1> INFO: Expecting 655360 events.
[14:11:17.810] <TB1> INFO: 655360 events read in total (13419ms).
[14:11:17.888] <TB1> INFO: Expecting 655360 events.
[14:11:31.766] <TB1> INFO: 655360 events read in total (13476ms).
[14:11:31.849] <TB1> INFO: Test took 222241ms.
[14:11:32.057] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:11:32.063] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:11:32.070] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:11:32.077] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:11:32.084] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:11:32.091] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:11:32.098] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:11:32.105] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:11:32.112] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:11:32.119] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[14:11:32.126] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:11:32.133] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:11:32.140] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:11:32.147] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:11:32.154] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:11:32.161] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[14:11:32.168] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[14:11:32.175] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[14:11:32.182] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:11:32.189] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:11:32.196] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[14:11:32.203] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[14:11:32.242] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C0.dat
[14:11:32.242] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C1.dat
[14:11:32.242] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C2.dat
[14:11:32.242] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C3.dat
[14:11:32.242] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C4.dat
[14:11:32.242] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C5.dat
[14:11:32.242] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C6.dat
[14:11:32.243] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C7.dat
[14:11:32.243] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C8.dat
[14:11:32.243] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C9.dat
[14:11:32.243] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C10.dat
[14:11:32.243] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C11.dat
[14:11:32.243] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C12.dat
[14:11:32.244] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C13.dat
[14:11:32.244] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C14.dat
[14:11:32.244] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//dacParameters35_C15.dat
[14:11:32.527] <TB1> INFO: Expecting 41600 events.
[14:11:35.661] <TB1> INFO: 41600 events read in total (2577ms).
[14:11:35.662] <TB1> INFO: Test took 3415ms.
[14:11:36.107] <TB1> INFO: Expecting 41600 events.
[14:11:39.171] <TB1> INFO: 41600 events read in total (2508ms).
[14:11:39.171] <TB1> INFO: Test took 3329ms.
[14:11:39.605] <TB1> INFO: Expecting 41600 events.
[14:11:42.722] <TB1> INFO: 41600 events read in total (2560ms).
[14:11:42.723] <TB1> INFO: Test took 3377ms.
[14:11:42.903] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:42.991] <TB1> INFO: Expecting 2560 events.
[14:11:43.847] <TB1> INFO: 2560 events read in total (300ms).
[14:11:43.848] <TB1> INFO: Test took 945ms.
[14:11:43.851] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:44.153] <TB1> INFO: Expecting 2560 events.
[14:11:45.014] <TB1> INFO: 2560 events read in total (305ms).
[14:11:45.014] <TB1> INFO: Test took 1164ms.
[14:11:45.016] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:45.319] <TB1> INFO: Expecting 2560 events.
[14:11:46.172] <TB1> INFO: 2560 events read in total (297ms).
[14:11:46.172] <TB1> INFO: Test took 1156ms.
[14:11:46.174] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:46.477] <TB1> INFO: Expecting 2560 events.
[14:11:47.329] <TB1> INFO: 2560 events read in total (296ms).
[14:11:47.329] <TB1> INFO: Test took 1155ms.
[14:11:47.331] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:47.635] <TB1> INFO: Expecting 2560 events.
[14:11:48.487] <TB1> INFO: 2560 events read in total (296ms).
[14:11:48.487] <TB1> INFO: Test took 1156ms.
[14:11:48.489] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:48.793] <TB1> INFO: Expecting 2560 events.
[14:11:49.646] <TB1> INFO: 2560 events read in total (297ms).
[14:11:49.647] <TB1> INFO: Test took 1158ms.
[14:11:49.649] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:49.952] <TB1> INFO: Expecting 2560 events.
[14:11:50.804] <TB1> INFO: 2560 events read in total (296ms).
[14:11:50.805] <TB1> INFO: Test took 1156ms.
[14:11:50.807] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:51.110] <TB1> INFO: Expecting 2560 events.
[14:11:51.967] <TB1> INFO: 2560 events read in total (301ms).
[14:11:51.968] <TB1> INFO: Test took 1161ms.
[14:11:51.971] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:52.272] <TB1> INFO: Expecting 2560 events.
[14:11:53.126] <TB1> INFO: 2560 events read in total (298ms).
[14:11:53.126] <TB1> INFO: Test took 1155ms.
[14:11:53.129] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:53.432] <TB1> INFO: Expecting 2560 events.
[14:11:54.285] <TB1> INFO: 2560 events read in total (297ms).
[14:11:54.285] <TB1> INFO: Test took 1156ms.
[14:11:54.288] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:54.590] <TB1> INFO: Expecting 2560 events.
[14:11:55.444] <TB1> INFO: 2560 events read in total (297ms).
[14:11:55.444] <TB1> INFO: Test took 1156ms.
[14:11:55.446] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:55.750] <TB1> INFO: Expecting 2560 events.
[14:11:56.602] <TB1> INFO: 2560 events read in total (296ms).
[14:11:56.603] <TB1> INFO: Test took 1157ms.
[14:11:56.604] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:56.907] <TB1> INFO: Expecting 2560 events.
[14:11:57.758] <TB1> INFO: 2560 events read in total (296ms).
[14:11:57.759] <TB1> INFO: Test took 1155ms.
[14:11:57.761] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:58.064] <TB1> INFO: Expecting 2560 events.
[14:11:58.920] <TB1> INFO: 2560 events read in total (300ms).
[14:11:58.920] <TB1> INFO: Test took 1159ms.
[14:11:58.923] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:11:59.225] <TB1> INFO: Expecting 2560 events.
[14:12:00.079] <TB1> INFO: 2560 events read in total (297ms).
[14:12:00.079] <TB1> INFO: Test took 1157ms.
[14:12:00.082] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:00.384] <TB1> INFO: Expecting 2560 events.
[14:12:01.238] <TB1> INFO: 2560 events read in total (298ms).
[14:12:01.238] <TB1> INFO: Test took 1156ms.
[14:12:01.240] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:01.543] <TB1> INFO: Expecting 2560 events.
[14:12:02.396] <TB1> INFO: 2560 events read in total (297ms).
[14:12:02.396] <TB1> INFO: Test took 1156ms.
[14:12:02.398] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:02.701] <TB1> INFO: Expecting 2560 events.
[14:12:03.558] <TB1> INFO: 2560 events read in total (301ms).
[14:12:03.558] <TB1> INFO: Test took 1160ms.
[14:12:03.560] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:03.863] <TB1> INFO: Expecting 2560 events.
[14:12:04.716] <TB1> INFO: 2560 events read in total (297ms).
[14:12:04.717] <TB1> INFO: Test took 1157ms.
[14:12:04.720] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:05.021] <TB1> INFO: Expecting 2560 events.
[14:12:05.875] <TB1> INFO: 2560 events read in total (298ms).
[14:12:05.875] <TB1> INFO: Test took 1156ms.
[14:12:05.877] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:06.179] <TB1> INFO: Expecting 2560 events.
[14:12:07.037] <TB1> INFO: 2560 events read in total (301ms).
[14:12:07.037] <TB1> INFO: Test took 1160ms.
[14:12:07.040] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:07.342] <TB1> INFO: Expecting 2560 events.
[14:12:08.202] <TB1> INFO: 2560 events read in total (303ms).
[14:12:08.203] <TB1> INFO: Test took 1163ms.
[14:12:08.205] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:08.508] <TB1> INFO: Expecting 2560 events.
[14:12:09.363] <TB1> INFO: 2560 events read in total (299ms).
[14:12:09.363] <TB1> INFO: Test took 1159ms.
[14:12:09.366] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:09.668] <TB1> INFO: Expecting 2560 events.
[14:12:10.525] <TB1> INFO: 2560 events read in total (301ms).
[14:12:10.526] <TB1> INFO: Test took 1160ms.
[14:12:10.529] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:10.830] <TB1> INFO: Expecting 2560 events.
[14:12:11.682] <TB1> INFO: 2560 events read in total (296ms).
[14:12:11.682] <TB1> INFO: Test took 1154ms.
[14:12:11.684] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:11.988] <TB1> INFO: Expecting 2560 events.
[14:12:12.840] <TB1> INFO: 2560 events read in total (296ms).
[14:12:12.840] <TB1> INFO: Test took 1156ms.
[14:12:12.842] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:13.146] <TB1> INFO: Expecting 2560 events.
[14:12:13.998] <TB1> INFO: 2560 events read in total (296ms).
[14:12:13.998] <TB1> INFO: Test took 1156ms.
[14:12:14.000] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:14.304] <TB1> INFO: Expecting 2560 events.
[14:12:15.163] <TB1> INFO: 2560 events read in total (303ms).
[14:12:15.163] <TB1> INFO: Test took 1163ms.
[14:12:15.165] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:15.469] <TB1> INFO: Expecting 2560 events.
[14:12:16.321] <TB1> INFO: 2560 events read in total (296ms).
[14:12:16.322] <TB1> INFO: Test took 1157ms.
[14:12:16.323] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:16.627] <TB1> INFO: Expecting 2560 events.
[14:12:17.479] <TB1> INFO: 2560 events read in total (296ms).
[14:12:17.479] <TB1> INFO: Test took 1156ms.
[14:12:17.481] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:17.785] <TB1> INFO: Expecting 2560 events.
[14:12:18.643] <TB1> INFO: 2560 events read in total (302ms).
[14:12:18.643] <TB1> INFO: Test took 1162ms.
[14:12:18.646] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:12:18.950] <TB1> INFO: Expecting 2560 events.
[14:12:19.806] <TB1> INFO: 2560 events read in total (300ms).
[14:12:19.807] <TB1> INFO: Test took 1161ms.
[14:12:20.290] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 523 seconds
[14:12:20.290] <TB1> INFO: PH scale (per ROC): 78 76 76 85 70 74 81 80 80 80 85 78 71 80 77 77
[14:12:20.290] <TB1> INFO: PH offset (per ROC): 157 161 163 159 187 184 175 157 159 162 171 161 179 155 174 176
[14:12:20.296] <TB1> INFO: Decoding statistics:
[14:12:20.296] <TB1> INFO: General information:
[14:12:20.296] <TB1> INFO: 16bit words read: 86936
[14:12:20.296] <TB1> INFO: valid events total: 10240
[14:12:20.296] <TB1> INFO: empty events: 7732
[14:12:20.296] <TB1> INFO: valid events with pixels: 2508
[14:12:20.296] <TB1> INFO: valid pixel hits: 2508
[14:12:20.296] <TB1> INFO: Event errors: 0
[14:12:20.296] <TB1> INFO: start marker: 0
[14:12:20.296] <TB1> INFO: stop marker: 0
[14:12:20.296] <TB1> INFO: overflow: 0
[14:12:20.296] <TB1> INFO: invalid 5bit words: 0
[14:12:20.296] <TB1> INFO: invalid XOR eye diagram: 0
[14:12:20.296] <TB1> INFO: frame (failed synchr.): 0
[14:12:20.296] <TB1> INFO: idle data (no TBM trl): 0
[14:12:20.296] <TB1> INFO: no data (only TBM hdr): 0
[14:12:20.296] <TB1> INFO: TBM errors: 0
[14:12:20.296] <TB1> INFO: flawed TBM headers: 0
[14:12:20.296] <TB1> INFO: flawed TBM trailers: 0
[14:12:20.296] <TB1> INFO: event ID mismatches: 0
[14:12:20.296] <TB1> INFO: ROC errors: 0
[14:12:20.296] <TB1> INFO: missing ROC header(s): 0
[14:12:20.296] <TB1> INFO: misplaced readback start: 0
[14:12:20.296] <TB1> INFO: Pixel decoding errors: 0
[14:12:20.296] <TB1> INFO: pixel data incomplete: 0
[14:12:20.296] <TB1> INFO: pixel address: 0
[14:12:20.296] <TB1> INFO: pulse height fill bit: 0
[14:12:20.296] <TB1> INFO: buffer corruption: 0
[14:12:20.476] <TB1> INFO: ######################################################################
[14:12:20.476] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[14:12:20.476] <TB1> INFO: ######################################################################
[14:12:20.486] <TB1> INFO: scanning low vcal = 10
[14:12:20.753] <TB1> INFO: Expecting 41600 events.
[14:12:24.130] <TB1> INFO: 41600 events read in total (2821ms).
[14:12:24.130] <TB1> INFO: Test took 3644ms.
[14:12:24.131] <TB1> INFO: scanning low vcal = 20
[14:12:24.429] <TB1> INFO: Expecting 41600 events.
[14:12:27.827] <TB1> INFO: 41600 events read in total (2841ms).
[14:12:27.828] <TB1> INFO: Test took 3697ms.
[14:12:27.829] <TB1> INFO: scanning low vcal = 30
[14:12:28.126] <TB1> INFO: Expecting 41600 events.
[14:12:31.523] <TB1> INFO: 41600 events read in total (2841ms).
[14:12:31.523] <TB1> INFO: Test took 3694ms.
[14:12:31.525] <TB1> INFO: scanning low vcal = 40
[14:12:31.817] <TB1> INFO: Expecting 41600 events.
[14:12:35.692] <TB1> INFO: 41600 events read in total (3319ms).
[14:12:35.693] <TB1> INFO: Test took 4168ms.
[14:12:35.696] <TB1> INFO: scanning low vcal = 50
[14:12:35.983] <TB1> INFO: Expecting 41600 events.
[14:12:39.928] <TB1> INFO: 41600 events read in total (3389ms).
[14:12:39.929] <TB1> INFO: Test took 4233ms.
[14:12:39.932] <TB1> INFO: scanning low vcal = 60
[14:12:40.208] <TB1> INFO: Expecting 41600 events.
[14:12:44.157] <TB1> INFO: 41600 events read in total (3392ms).
[14:12:44.158] <TB1> INFO: Test took 4226ms.
[14:12:44.161] <TB1> INFO: scanning low vcal = 70
[14:12:44.441] <TB1> INFO: Expecting 41600 events.
[14:12:48.399] <TB1> INFO: 41600 events read in total (3401ms).
[14:12:48.400] <TB1> INFO: Test took 4239ms.
[14:12:48.403] <TB1> INFO: scanning low vcal = 80
[14:12:48.664] <TB1> INFO: Expecting 41600 events.
[14:12:52.611] <TB1> INFO: 41600 events read in total (3390ms).
[14:12:52.612] <TB1> INFO: Test took 4209ms.
[14:12:52.615] <TB1> INFO: scanning low vcal = 90
[14:12:52.888] <TB1> INFO: Expecting 41600 events.
[14:12:56.871] <TB1> INFO: 41600 events read in total (3427ms).
[14:12:56.873] <TB1> INFO: Test took 4258ms.
[14:12:56.878] <TB1> INFO: scanning low vcal = 100
[14:12:57.149] <TB1> INFO: Expecting 41600 events.
[14:13:01.132] <TB1> INFO: 41600 events read in total (3427ms).
[14:13:01.133] <TB1> INFO: Test took 4255ms.
[14:13:01.136] <TB1> INFO: scanning low vcal = 110
[14:13:01.406] <TB1> INFO: Expecting 41600 events.
[14:13:05.361] <TB1> INFO: 41600 events read in total (3399ms).
[14:13:05.363] <TB1> INFO: Test took 4227ms.
[14:13:05.366] <TB1> INFO: scanning low vcal = 120
[14:13:05.635] <TB1> INFO: Expecting 41600 events.
[14:13:09.584] <TB1> INFO: 41600 events read in total (3392ms).
[14:13:09.584] <TB1> INFO: Test took 4217ms.
[14:13:09.587] <TB1> INFO: scanning low vcal = 130
[14:13:09.850] <TB1> INFO: Expecting 41600 events.
[14:13:13.802] <TB1> INFO: 41600 events read in total (3396ms).
[14:13:13.803] <TB1> INFO: Test took 4216ms.
[14:13:13.805] <TB1> INFO: scanning low vcal = 140
[14:13:14.071] <TB1> INFO: Expecting 41600 events.
[14:13:18.016] <TB1> INFO: 41600 events read in total (3389ms).
[14:13:18.016] <TB1> INFO: Test took 4211ms.
[14:13:18.019] <TB1> INFO: scanning low vcal = 150
[14:13:18.281] <TB1> INFO: Expecting 41600 events.
[14:13:22.214] <TB1> INFO: 41600 events read in total (3376ms).
[14:13:22.214] <TB1> INFO: Test took 4195ms.
[14:13:22.217] <TB1> INFO: scanning low vcal = 160
[14:13:22.477] <TB1> INFO: Expecting 41600 events.
[14:13:26.495] <TB1> INFO: 41600 events read in total (3462ms).
[14:13:26.495] <TB1> INFO: Test took 4278ms.
[14:13:26.498] <TB1> INFO: scanning low vcal = 170
[14:13:26.829] <TB1> INFO: Expecting 41600 events.
[14:13:30.788] <TB1> INFO: 41600 events read in total (3402ms).
[14:13:30.788] <TB1> INFO: Test took 4289ms.
[14:13:30.793] <TB1> INFO: scanning low vcal = 180
[14:13:31.053] <TB1> INFO: Expecting 41600 events.
[14:13:35.003] <TB1> INFO: 41600 events read in total (3393ms).
[14:13:35.003] <TB1> INFO: Test took 4210ms.
[14:13:35.006] <TB1> INFO: scanning low vcal = 190
[14:13:35.285] <TB1> INFO: Expecting 41600 events.
[14:13:39.240] <TB1> INFO: 41600 events read in total (3398ms).
[14:13:39.240] <TB1> INFO: Test took 4234ms.
[14:13:39.243] <TB1> INFO: scanning low vcal = 200
[14:13:39.510] <TB1> INFO: Expecting 41600 events.
[14:13:43.451] <TB1> INFO: 41600 events read in total (3385ms).
[14:13:43.451] <TB1> INFO: Test took 4208ms.
[14:13:43.453] <TB1> INFO: scanning low vcal = 210
[14:13:43.719] <TB1> INFO: Expecting 41600 events.
[14:13:47.671] <TB1> INFO: 41600 events read in total (3396ms).
[14:13:47.671] <TB1> INFO: Test took 4217ms.
[14:13:47.674] <TB1> INFO: scanning low vcal = 220
[14:13:47.943] <TB1> INFO: Expecting 41600 events.
[14:13:51.869] <TB1> INFO: 41600 events read in total (3370ms).
[14:13:51.869] <TB1> INFO: Test took 4195ms.
[14:13:51.872] <TB1> INFO: scanning low vcal = 230
[14:13:52.133] <TB1> INFO: Expecting 41600 events.
[14:13:56.113] <TB1> INFO: 41600 events read in total (3424ms).
[14:13:56.114] <TB1> INFO: Test took 4242ms.
[14:13:56.117] <TB1> INFO: scanning low vcal = 240
[14:13:56.436] <TB1> INFO: Expecting 41600 events.
[14:14:00.388] <TB1> INFO: 41600 events read in total (3395ms).
[14:14:00.389] <TB1> INFO: Test took 4272ms.
[14:14:00.392] <TB1> INFO: scanning low vcal = 250
[14:14:00.676] <TB1> INFO: Expecting 41600 events.
[14:14:04.618] <TB1> INFO: 41600 events read in total (3385ms).
[14:14:04.619] <TB1> INFO: Test took 4227ms.
[14:14:04.623] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[14:14:04.891] <TB1> INFO: Expecting 41600 events.
[14:14:08.855] <TB1> INFO: 41600 events read in total (3408ms).
[14:14:08.856] <TB1> INFO: Test took 4233ms.
[14:14:08.858] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[14:14:09.143] <TB1> INFO: Expecting 41600 events.
[14:14:13.088] <TB1> INFO: 41600 events read in total (3389ms).
[14:14:13.089] <TB1> INFO: Test took 4230ms.
[14:14:13.092] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[14:14:13.356] <TB1> INFO: Expecting 41600 events.
[14:14:17.294] <TB1> INFO: 41600 events read in total (3382ms).
[14:14:17.294] <TB1> INFO: Test took 4202ms.
[14:14:17.297] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[14:14:17.578] <TB1> INFO: Expecting 41600 events.
[14:14:21.515] <TB1> INFO: 41600 events read in total (3380ms).
[14:14:21.515] <TB1> INFO: Test took 4218ms.
[14:14:21.518] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[14:14:21.778] <TB1> INFO: Expecting 41600 events.
[14:14:25.758] <TB1> INFO: 41600 events read in total (3423ms).
[14:14:25.760] <TB1> INFO: Test took 4242ms.
[14:14:26.285] <TB1> INFO: PixTestGainPedestal::measure() done
[14:15:02.423] <TB1> INFO: PixTestGainPedestal::fit() done
[14:15:02.423] <TB1> INFO: non-linearity mean: 0.949 0.954 0.958 0.956 0.956 0.953 0.956 0.958 0.954 0.949 0.960 0.963 0.947 0.959 0.956 0.959
[14:15:02.423] <TB1> INFO: non-linearity RMS: 0.007 0.006 0.007 0.006 0.007 0.008 0.005 0.007 0.007 0.007 0.006 0.005 0.008 0.005 0.006 0.005
[14:15:02.423] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[14:15:02.450] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[14:15:02.476] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[14:15:02.501] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[14:15:02.527] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[14:15:02.553] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[14:15:02.580] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[14:15:02.606] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[14:15:02.632] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[14:15:02.658] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[14:15:02.684] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[14:15:02.710] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[14:15:02.736] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[14:15:02.758] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[14:15:02.785] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[14:15:02.810] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[14:15:02.836] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 162 seconds
[14:15:02.836] <TB1> INFO: Decoding statistics:
[14:15:02.836] <TB1> INFO: General information:
[14:15:02.836] <TB1> INFO: 16bit words read: 2662400
[14:15:02.836] <TB1> INFO: valid events total: 166400
[14:15:02.836] <TB1> INFO: empty events: 0
[14:15:02.836] <TB1> INFO: valid events with pixels: 166400
[14:15:02.836] <TB1> INFO: valid pixel hits: 665600
[14:15:02.836] <TB1> INFO: Event errors: 0
[14:15:02.836] <TB1> INFO: start marker: 0
[14:15:02.836] <TB1> INFO: stop marker: 0
[14:15:02.836] <TB1> INFO: overflow: 0
[14:15:02.836] <TB1> INFO: invalid 5bit words: 0
[14:15:02.837] <TB1> INFO: invalid XOR eye diagram: 0
[14:15:02.837] <TB1> INFO: frame (failed synchr.): 0
[14:15:02.837] <TB1> INFO: idle data (no TBM trl): 0
[14:15:02.837] <TB1> INFO: no data (only TBM hdr): 0
[14:15:02.837] <TB1> INFO: TBM errors: 0
[14:15:02.837] <TB1> INFO: flawed TBM headers: 0
[14:15:02.837] <TB1> INFO: flawed TBM trailers: 0
[14:15:02.837] <TB1> INFO: event ID mismatches: 0
[14:15:02.837] <TB1> INFO: ROC errors: 0
[14:15:02.837] <TB1> INFO: missing ROC header(s): 0
[14:15:02.837] <TB1> INFO: misplaced readback start: 0
[14:15:02.837] <TB1> INFO: Pixel decoding errors: 0
[14:15:02.837] <TB1> INFO: pixel data incomplete: 0
[14:15:02.837] <TB1> INFO: pixel address: 0
[14:15:02.837] <TB1> INFO: pulse height fill bit: 0
[14:15:02.837] <TB1> INFO: buffer corruption: 0
[14:15:02.846] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C15.dat
[14:15:02.848] <TB1> INFO: ######################################################################
[14:15:02.848] <TB1> INFO: PixTestReadback::doTest()
[14:15:02.848] <TB1> INFO: ######################################################################
[14:15:02.848] <TB1> INFO: ----------------------------------------------------------------------
[14:15:02.848] <TB1> INFO: PixTestReadback::CalibrateVd()
[14:15:02.848] <TB1> INFO: ----------------------------------------------------------------------
[14:15:12.329] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C0.dat
[14:15:12.330] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C1.dat
[14:15:12.330] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C2.dat
[14:15:12.330] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C3.dat
[14:15:12.330] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C4.dat
[14:15:12.330] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C5.dat
[14:15:12.330] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C6.dat
[14:15:12.330] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C7.dat
[14:15:12.330] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C8.dat
[14:15:12.330] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C9.dat
[14:15:12.330] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C10.dat
[14:15:12.330] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C11.dat
[14:15:12.330] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C12.dat
[14:15:12.330] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C13.dat
[14:15:12.331] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C14.dat
[14:15:12.331] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C15.dat
[14:15:12.361] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:15:12.361] <TB1> INFO: ----------------------------------------------------------------------
[14:15:12.361] <TB1> INFO: PixTestReadback::CalibrateVa()
[14:15:12.361] <TB1> INFO: ----------------------------------------------------------------------
[14:15:21.828] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C0.dat
[14:15:21.829] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C1.dat
[14:15:21.829] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C2.dat
[14:15:21.829] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C3.dat
[14:15:21.829] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C4.dat
[14:15:21.829] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C5.dat
[14:15:21.829] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C6.dat
[14:15:21.829] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C7.dat
[14:15:21.829] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C8.dat
[14:15:21.829] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C9.dat
[14:15:21.829] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C10.dat
[14:15:21.829] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C11.dat
[14:15:21.829] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C12.dat
[14:15:21.830] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C13.dat
[14:15:21.830] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C14.dat
[14:15:21.830] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C15.dat
[14:15:21.854] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:15:21.854] <TB1> INFO: ----------------------------------------------------------------------
[14:15:21.854] <TB1> INFO: PixTestReadback::readbackVbg()
[14:15:21.854] <TB1> INFO: ----------------------------------------------------------------------
[14:15:29.148] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:15:29.148] <TB1> INFO: ----------------------------------------------------------------------
[14:15:29.148] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[14:15:29.148] <TB1> INFO: ----------------------------------------------------------------------
[14:15:29.148] <TB1> INFO: Vbg will be calibrated using Vd calibration
[14:15:29.148] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 153.1calibrated Vbg = 1.19209 :::*/*/*/*/
[14:15:29.148] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 149.5calibrated Vbg = 1.19357 :::*/*/*/*/
[14:15:29.148] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 150.9calibrated Vbg = 1.19208 :::*/*/*/*/
[14:15:29.148] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 161.1calibrated Vbg = 1.19383 :::*/*/*/*/
[14:15:29.148] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 157.9calibrated Vbg = 1.20508 :::*/*/*/*/
[14:15:29.148] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 150calibrated Vbg = 1.20111 :::*/*/*/*/
[14:15:29.148] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 154.6calibrated Vbg = 1.20608 :::*/*/*/*/
[14:15:29.148] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 147.9calibrated Vbg = 1.19973 :::*/*/*/*/
[14:15:29.148] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 153.6calibrated Vbg = 1.20689 :::*/*/*/*/
[14:15:29.148] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 146.6calibrated Vbg = 1.20123 :::*/*/*/*/
[14:15:29.148] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 146.2calibrated Vbg = 1.20037 :::*/*/*/*/
[14:15:29.148] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 142.9calibrated Vbg = 1.20088 :::*/*/*/*/
[14:15:29.148] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 154.8calibrated Vbg = 1.19076 :::*/*/*/*/
[14:15:29.148] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 151.6calibrated Vbg = 1.19348 :::*/*/*/*/
[14:15:29.148] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 145.9calibrated Vbg = 1.19364 :::*/*/*/*/
[14:15:29.148] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 157.2calibrated Vbg = 1.19582 :::*/*/*/*/
[14:15:29.150] <TB1> INFO: ----------------------------------------------------------------------
[14:15:29.150] <TB1> INFO: PixTestReadback::CalibrateIa()
[14:15:29.150] <TB1> INFO: ----------------------------------------------------------------------
[14:18:04.336] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C0.dat
[14:18:04.337] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C1.dat
[14:18:04.338] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C2.dat
[14:18:04.338] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C3.dat
[14:18:04.338] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C4.dat
[14:18:04.338] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C5.dat
[14:18:04.338] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C6.dat
[14:18:04.338] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C7.dat
[14:18:04.338] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C8.dat
[14:18:04.338] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C9.dat
[14:18:04.339] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C10.dat
[14:18:04.339] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C11.dat
[14:18:04.339] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C12.dat
[14:18:04.339] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C13.dat
[14:18:04.339] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C14.dat
[14:18:04.339] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2357_FullQualification_2016-10-10_09h35m_1476084938//004_FulltestPxar_p17//readbackCal_C15.dat
[14:18:04.365] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:18:04.367] <TB1> INFO: PixTestReadback::doTest() done
[14:18:04.367] <TB1> INFO: Decoding statistics:
[14:18:04.367] <TB1> INFO: General information:
[14:18:04.368] <TB1> INFO: 16bit words read: 1024
[14:18:04.368] <TB1> INFO: valid events total: 128
[14:18:04.368] <TB1> INFO: empty events: 128
[14:18:04.368] <TB1> INFO: valid events with pixels: 0
[14:18:04.368] <TB1> INFO: valid pixel hits: 0
[14:18:04.368] <TB1> INFO: Event errors: 0
[14:18:04.368] <TB1> INFO: start marker: 0
[14:18:04.368] <TB1> INFO: stop marker: 0
[14:18:04.368] <TB1> INFO: overflow: 0
[14:18:04.368] <TB1> INFO: invalid 5bit words: 0
[14:18:04.368] <TB1> INFO: invalid XOR eye diagram: 0
[14:18:04.368] <TB1> INFO: frame (failed synchr.): 0
[14:18:04.368] <TB1> INFO: idle data (no TBM trl): 0
[14:18:04.368] <TB1> INFO: no data (only TBM hdr): 0
[14:18:04.368] <TB1> INFO: TBM errors: 0
[14:18:04.368] <TB1> INFO: flawed TBM headers: 0
[14:18:04.368] <TB1> INFO: flawed TBM trailers: 0
[14:18:04.368] <TB1> INFO: event ID mismatches: 0
[14:18:04.368] <TB1> INFO: ROC errors: 0
[14:18:04.368] <TB1> INFO: missing ROC header(s): 0
[14:18:04.368] <TB1> INFO: misplaced readback start: 0
[14:18:04.368] <TB1> INFO: Pixel decoding errors: 0
[14:18:04.368] <TB1> INFO: pixel data incomplete: 0
[14:18:04.368] <TB1> INFO: pixel address: 0
[14:18:04.368] <TB1> INFO: pulse height fill bit: 0
[14:18:04.368] <TB1> INFO: buffer corruption: 0
[14:18:04.392] <TB1> INFO: Decoding statistics:
[14:18:04.392] <TB1> INFO: General information:
[14:18:04.392] <TB1> INFO: 16bit words read: 2750360
[14:18:04.392] <TB1> INFO: valid events total: 176768
[14:18:04.392] <TB1> INFO: empty events: 7860
[14:18:04.392] <TB1> INFO: valid events with pixels: 168908
[14:18:04.392] <TB1> INFO: valid pixel hits: 668108
[14:18:04.392] <TB1> INFO: Event errors: 0
[14:18:04.392] <TB1> INFO: start marker: 0
[14:18:04.392] <TB1> INFO: stop marker: 0
[14:18:04.392] <TB1> INFO: overflow: 0
[14:18:04.392] <TB1> INFO: invalid 5bit words: 0
[14:18:04.392] <TB1> INFO: invalid XOR eye diagram: 0
[14:18:04.392] <TB1> INFO: frame (failed synchr.): 0
[14:18:04.392] <TB1> INFO: idle data (no TBM trl): 0
[14:18:04.392] <TB1> INFO: no data (only TBM hdr): 0
[14:18:04.392] <TB1> INFO: TBM errors: 0
[14:18:04.392] <TB1> INFO: flawed TBM headers: 0
[14:18:04.392] <TB1> INFO: flawed TBM trailers: 0
[14:18:04.392] <TB1> INFO: event ID mismatches: 0
[14:18:04.392] <TB1> INFO: ROC errors: 0
[14:18:04.392] <TB1> INFO: missing ROC header(s): 0
[14:18:04.392] <TB1> INFO: misplaced readback start: 0
[14:18:04.392] <TB1> INFO: Pixel decoding errors: 0
[14:18:04.392] <TB1> INFO: pixel data incomplete: 0
[14:18:04.392] <TB1> INFO: pixel address: 0
[14:18:04.392] <TB1> INFO: pulse height fill bit: 0
[14:18:04.392] <TB1> INFO: buffer corruption: 0
[14:18:04.392] <TB1> INFO: enter test to run
[14:18:04.392] <TB1> INFO: test: exit no parameter change
[14:18:04.572] <TB1> QUIET: Connection to board 153 closed.
[14:18:04.652] <TB1> INFO: pXar: this is the end, my friend