[08:14:20.448] <TB2> INFO: *** Welcome to pxar ***
[08:14:20.448] <TB2> INFO: *** Today: 2016/10/03
[08:14:20.545] <TB2> INFO: *** Version: 0b00-dirty
[08:14:20.545] <TB2> INFO: readRocDacs: /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C15.dat
[08:14:20.546] <TB2> INFO: readTbmDacs: /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//tbmParameters_C0b.dat
[08:14:20.546] <TB2> INFO: readMaskFile: /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//defaultMaskFile.dat
[08:14:20.546] <TB2> INFO: readTrimFile: /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters_C15.dat
[08:14:20.628] <TB2> INFO: clk: 4
[08:14:20.628] <TB2> INFO: ctr: 4
[08:14:20.628] <TB2> INFO: sda: 19
[08:14:20.628] <TB2> INFO: tin: 9
[08:14:20.628] <TB2> INFO: level: 15
[08:14:20.628] <TB2> INFO: triggerdelay: 0
[08:14:20.628] <TB2> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[08:14:20.628] <TB2> INFO: Log level: INFO
[08:14:20.637] <TB2> INFO: Found DTB DTB_WXC55Z
[08:14:20.645] <TB2> QUIET: Connection to board DTB_WXC55Z opened.
[08:14:20.648] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 156
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WXC55Z
MAC address: 40D85511809C
Hostname: pixelDTB156
Comment:
------------------------------------------------------
[08:14:20.650] <TB2> INFO: RPC call hashes of host and DTB match: 486171790
[08:14:22.183] <TB2> INFO: DUT info:
[08:14:22.183] <TB2> INFO: The DUT currently contains the following objects:
[08:14:22.183] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[08:14:22.183] <TB2> INFO: TBM Core alpha (0): 7 registers set
[08:14:22.183] <TB2> INFO: TBM Core beta (1): 7 registers set
[08:14:22.183] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:14:22.183] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:22.183] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:22.183] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:22.183] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:22.183] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:22.183] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:22.183] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:22.183] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:22.183] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:22.183] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:22.183] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:22.183] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:22.183] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:22.183] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:22.183] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:22.183] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:14:22.584] <TB2> INFO: enter 'restricted' command line mode
[08:14:22.584] <TB2> INFO: enter test to run
[08:14:22.584] <TB2> INFO: test: pretest no parameter change
[08:14:22.584] <TB2> INFO: running: pretest
[08:14:22.589] <TB2> INFO: ----------------------------------------------------------------------
[08:14:22.589] <TB2> INFO: PixTestPretest::programROC()
[08:14:22.589] <TB2> INFO: ----------------------------------------------------------------------
[08:14:40.602] <TB2> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[08:14:40.602] <TB2> INFO: IA differences per ROC: 16.9 21.7 20.1 18.5 17.7 19.3 19.3 19.3 16.9 16.1 18.5 19.3 18.5 18.5 19.3 18.5
[08:14:40.694] <TB2> INFO: enter test to run
[08:14:40.694] <TB2> INFO: test: pretest no parameter change
[08:14:40.694] <TB2> INFO: running: pretest
[08:14:40.695] <TB2> INFO: ----------------------------------------------------------------------
[08:14:40.695] <TB2> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[08:14:40.695] <TB2> INFO: ----------------------------------------------------------------------
[08:15:01.947] <TB2> INFO: PixTestPretest::setVana() done, Module Ia 384.3 mA = 24.0187 mA/ROC
[08:15:01.947] <TB2> INFO: i(loss) [mA/ROC]: 19.3 18.5 19.3 19.3 19.3 19.3 20.1 19.3 19.3 19.3 19.3 18.5 19.3 18.5 18.5 19.3
[08:15:01.973] <TB2> INFO: enter test to run
[08:15:01.973] <TB2> INFO: test: pretest no parameter change
[08:15:01.973] <TB2> INFO: running: pretest
[08:15:01.973] <TB2> INFO: ----------------------------------------------------------------------
[08:15:01.973] <TB2> INFO: PixTestPretest::findTiming()
[08:15:01.973] <TB2> INFO: ----------------------------------------------------------------------
[08:15:01.973] <TB2> INFO: PixTestCmd::init()

[08:16:31.235] <TB2> INFO: TBM phases: 160MHz: 7, 400MHz: 6, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[08:16:31.235] <TB2> INFO: (success/tries = 100/100), width = 5
[08:16:31.235] <TB2> INFO: enter test to run
[08:16:31.235] <TB2> INFO: test: pretest no parameter change
[08:16:31.235] <TB2> INFO: running: pretest
[08:16:31.237] <TB2> INFO: ----------------------------------------------------------------------
[08:16:31.237] <TB2> INFO: PixTestPretest::findWorkingPixel()
[08:16:31.237] <TB2> INFO: ----------------------------------------------------------------------
[08:16:31.328] <TB2> INFO: Expecting 231680 events.


[08:16:40.863] <TB2> INFO: 231680 events read in total (8979ms).
[08:16:40.869] <TB2> INFO: Test took 9629ms.
[08:16:41.071] <TB2> INFO: Found working pixel in all ROCs: col/row = 12/22
[08:16:41.130] <TB2> INFO: enter test to run
[08:16:41.130] <TB2> INFO: test: pretest no parameter change
[08:16:41.130] <TB2> INFO: running: pretest
[08:16:41.132] <TB2> INFO: ----------------------------------------------------------------------
[08:16:41.132] <TB2> INFO: PixTestPretest::setVthrCompCalDel()
[08:16:41.132] <TB2> INFO: ----------------------------------------------------------------------
[08:16:41.224] <TB2> INFO: Expecting 231680 events.
[08:16:50.456] <TB2> INFO: 231680 events read in total (8676ms).
[08:16:50.461] <TB2> INFO: Test took 9326ms.
[08:16:50.670] <TB2> INFO: PixTestPretest::setVthrCompCalDel() done
[08:16:50.670] <TB2> INFO: CalDel: 142 139 126 148 143 148 124 143 131 119 124 145 129 143 158 160
[08:16:50.670] <TB2> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[08:16:50.704] <TB2> INFO: enter test to run
[08:16:50.704] <TB2> INFO: test: pretest no parameter change
[08:16:50.704] <TB2> INFO: running: pretest
[08:16:50.704] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C0.dat
[08:16:50.704] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C1.dat
[08:16:50.704] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C2.dat
[08:16:50.704] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C3.dat
[08:16:50.704] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C4.dat
[08:16:50.704] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C5.dat
[08:16:50.705] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C6.dat
[08:16:50.705] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C7.dat
[08:16:50.705] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C8.dat
[08:16:50.705] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C9.dat
[08:16:50.705] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C10.dat
[08:16:50.705] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C11.dat
[08:16:50.705] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C12.dat
[08:16:50.705] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C13.dat
[08:16:50.705] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C14.dat
[08:16:50.705] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters_C15.dat
[08:16:50.706] <TB2> INFO: enter test to run
[08:16:50.706] <TB2> INFO: test: fulltest no parameter change
[08:16:50.706] <TB2> INFO: running: fulltest
[08:16:50.706] <TB2> INFO: ######################################################################
[08:16:50.706] <TB2> INFO: PixTestFullTest::doTest()
[08:16:50.706] <TB2> INFO: ######################################################################
[08:16:50.707] <TB2> INFO: ######################################################################
[08:16:50.707] <TB2> INFO: PixTestAlive::doTest()
[08:16:50.707] <TB2> INFO: ######################################################################
[08:16:50.708] <TB2> INFO: ----------------------------------------------------------------------
[08:16:50.708] <TB2> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:16:50.708] <TB2> INFO: ----------------------------------------------------------------------
[08:16:50.974] <TB2> INFO: Expecting 41600 events.
[08:16:54.383] <TB2> INFO: 41600 events read in total (2853ms).
[08:16:54.384] <TB2> INFO: Test took 3675ms.
[08:16:54.605] <TB2> INFO: PixTestAlive::aliveTest() done
[08:16:54.605] <TB2> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:16:54.606] <TB2> INFO: ----------------------------------------------------------------------
[08:16:54.606] <TB2> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:16:54.606] <TB2> INFO: ----------------------------------------------------------------------
[08:16:54.900] <TB2> INFO: Expecting 41600 events.
[08:16:57.702] <TB2> INFO: 41600 events read in total (2246ms).
[08:16:57.703] <TB2> INFO: Test took 3095ms.
[08:16:57.703] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:16:57.939] <TB2> INFO: PixTestAlive::maskTest() done
[08:16:57.939] <TB2> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:16:57.940] <TB2> INFO: ----------------------------------------------------------------------
[08:16:57.940] <TB2> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:16:57.940] <TB2> INFO: ----------------------------------------------------------------------
[08:16:58.244] <TB2> INFO: Expecting 41600 events.
[08:17:01.670] <TB2> INFO: 41600 events read in total (2870ms).
[08:17:01.670] <TB2> INFO: Test took 3729ms.
[08:17:01.877] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[08:17:01.877] <TB2> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:17:01.877] <TB2> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[08:17:01.877] <TB2> INFO: Decoding statistics:
[08:17:01.877] <TB2> INFO: General information:
[08:17:01.877] <TB2> INFO: 16bit words read: 0
[08:17:01.877] <TB2> INFO: valid events total: 0
[08:17:01.877] <TB2> INFO: empty events: 0
[08:17:01.877] <TB2> INFO: valid events with pixels: 0
[08:17:01.877] <TB2> INFO: valid pixel hits: 0
[08:17:01.877] <TB2> INFO: Event errors: 0
[08:17:01.877] <TB2> INFO: start marker: 0
[08:17:01.877] <TB2> INFO: stop marker: 0
[08:17:01.877] <TB2> INFO: overflow: 0
[08:17:01.877] <TB2> INFO: invalid 5bit words: 0
[08:17:01.877] <TB2> INFO: invalid XOR eye diagram: 0
[08:17:01.877] <TB2> INFO: frame (failed synchr.): 0
[08:17:01.877] <TB2> INFO: idle data (no TBM trl): 0
[08:17:01.877] <TB2> INFO: no data (only TBM hdr): 0
[08:17:01.877] <TB2> INFO: TBM errors: 0
[08:17:01.877] <TB2> INFO: flawed TBM headers: 0
[08:17:01.877] <TB2> INFO: flawed TBM trailers: 0
[08:17:01.877] <TB2> INFO: event ID mismatches: 0
[08:17:01.877] <TB2> INFO: ROC errors: 0
[08:17:01.877] <TB2> INFO: missing ROC header(s): 0
[08:17:01.877] <TB2> INFO: misplaced readback start: 0
[08:17:01.877] <TB2> INFO: Pixel decoding errors: 0
[08:17:01.877] <TB2> INFO: pixel data incomplete: 0
[08:17:01.877] <TB2> INFO: pixel address: 0
[08:17:01.877] <TB2> INFO: pulse height fill bit: 0
[08:17:01.877] <TB2> INFO: buffer corruption: 0
[08:17:01.888] <TB2> INFO: ######################################################################
[08:17:01.888] <TB2> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[08:17:01.889] <TB2> INFO: ######################################################################
[08:17:01.891] <TB2> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[08:17:02.068] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[08:17:02.068] <TB2> INFO: run 1 of 1
[08:17:02.367] <TB2> INFO: Expecting 3120000 events.
[08:17:40.018] <TB2> INFO: 845140 events read in total (37095ms).
[08:18:17.226] <TB2> INFO: 1676560 events read in total (74303ms).
[08:18:55.047] <TB2> INFO: 2517740 events read in total (112124ms).
[08:19:25.295] <TB2> INFO: 3120000 events read in total (142372ms).
[08:19:25.372] <TB2> INFO: Test took 143303ms.
[08:19:52.046] <TB2> INFO: PixTestBBMap::doTest() done, duration: 170 seconds
[08:19:52.046] <TB2> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 1 2 2 0 0 0 0 0 1
[08:19:52.046] <TB2> INFO: separation cut (per ROC): 72 82 87 70 74 71 78 80 84 94 88 72 89 71 75 76
[08:19:52.046] <TB2> INFO: Decoding statistics:
[08:19:52.046] <TB2> INFO: General information:
[08:19:52.046] <TB2> INFO: 16bit words read: 0
[08:19:52.046] <TB2> INFO: valid events total: 0
[08:19:52.046] <TB2> INFO: empty events: 0
[08:19:52.046] <TB2> INFO: valid events with pixels: 0
[08:19:52.046] <TB2> INFO: valid pixel hits: 0
[08:19:52.046] <TB2> INFO: Event errors: 0
[08:19:52.046] <TB2> INFO: start marker: 0
[08:19:52.046] <TB2> INFO: stop marker: 0
[08:19:52.046] <TB2> INFO: overflow: 0
[08:19:52.046] <TB2> INFO: invalid 5bit words: 0
[08:19:52.046] <TB2> INFO: invalid XOR eye diagram: 0
[08:19:52.046] <TB2> INFO: frame (failed synchr.): 0
[08:19:52.046] <TB2> INFO: idle data (no TBM trl): 0
[08:19:52.046] <TB2> INFO: no data (only TBM hdr): 0
[08:19:52.046] <TB2> INFO: TBM errors: 0
[08:19:52.046] <TB2> INFO: flawed TBM headers: 0
[08:19:52.046] <TB2> INFO: flawed TBM trailers: 0
[08:19:52.046] <TB2> INFO: event ID mismatches: 0
[08:19:52.046] <TB2> INFO: ROC errors: 0
[08:19:52.046] <TB2> INFO: missing ROC header(s): 0
[08:19:52.046] <TB2> INFO: misplaced readback start: 0
[08:19:52.046] <TB2> INFO: Pixel decoding errors: 0
[08:19:52.046] <TB2> INFO: pixel data incomplete: 0
[08:19:52.046] <TB2> INFO: pixel address: 0
[08:19:52.046] <TB2> INFO: pulse height fill bit: 0
[08:19:52.046] <TB2> INFO: buffer corruption: 0
[08:19:52.123] <TB2> INFO: ######################################################################
[08:19:52.123] <TB2> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:19:52.123] <TB2> INFO: ######################################################################
[08:19:52.123] <TB2> INFO: ----------------------------------------------------------------------
[08:19:52.123] <TB2> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[08:19:52.123] <TB2> INFO: ----------------------------------------------------------------------
[08:19:52.123] <TB2> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[08:19:52.131] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[08:19:52.131] <TB2> INFO: run 1 of 1
[08:19:52.402] <TB2> INFO: Expecting 36608000 events.
[08:20:22.381] <TB2> INFO: 814750 events read in total (29423ms).
[08:20:52.574] <TB2> INFO: 1613800 events read in total (59616ms).
[08:21:21.789] <TB2> INFO: 2412300 events read in total (88831ms).
[08:21:51.241] <TB2> INFO: 3210050 events read in total (118283ms).
[08:22:20.591] <TB2> INFO: 4008750 events read in total (147633ms).
[08:22:50.168] <TB2> INFO: 4808800 events read in total (177210ms).
[08:23:19.550] <TB2> INFO: 5605950 events read in total (206592ms).
[08:23:49.199] <TB2> INFO: 6406150 events read in total (236241ms).
[08:24:18.718] <TB2> INFO: 7203800 events read in total (265760ms).
[08:24:48.382] <TB2> INFO: 8001200 events read in total (295424ms).
[08:25:18.765] <TB2> INFO: 8797350 events read in total (325807ms).
[08:25:49.921] <TB2> INFO: 9593850 events read in total (356963ms).
[08:26:20.242] <TB2> INFO: 10390850 events read in total (387284ms).
[08:26:50.351] <TB2> INFO: 11188250 events read in total (417393ms).
[08:27:19.932] <TB2> INFO: 11984700 events read in total (446974ms).
[08:27:50.074] <TB2> INFO: 12782500 events read in total (477116ms).
[08:28:20.243] <TB2> INFO: 13579250 events read in total (507285ms).
[08:28:50.374] <TB2> INFO: 14373750 events read in total (537416ms).
[08:29:20.072] <TB2> INFO: 15168500 events read in total (567114ms).
[08:29:49.629] <TB2> INFO: 15965650 events read in total (596671ms).
[08:30:18.876] <TB2> INFO: 16760550 events read in total (625918ms).
[08:30:48.515] <TB2> INFO: 17555450 events read in total (655557ms).
[08:31:17.754] <TB2> INFO: 18348450 events read in total (684796ms).
[08:31:47.768] <TB2> INFO: 19139350 events read in total (714810ms).
[08:32:17.117] <TB2> INFO: 19930250 events read in total (744159ms).
[08:32:47.274] <TB2> INFO: 20720650 events read in total (774316ms).
[08:33:16.796] <TB2> INFO: 21511550 events read in total (803838ms).
[08:33:46.084] <TB2> INFO: 22300950 events read in total (833126ms).
[08:34:16.068] <TB2> INFO: 23089600 events read in total (863110ms).
[08:34:45.234] <TB2> INFO: 23878450 events read in total (892276ms).
[08:35:15.214] <TB2> INFO: 24666650 events read in total (922256ms).
[08:35:44.377] <TB2> INFO: 25454850 events read in total (951419ms).
[08:36:13.152] <TB2> INFO: 26243550 events read in total (980194ms).
[08:36:42.551] <TB2> INFO: 27030100 events read in total (1009593ms).
[08:37:12.176] <TB2> INFO: 27817650 events read in total (1039218ms).
[08:37:41.817] <TB2> INFO: 28605800 events read in total (1068859ms).
[08:38:10.732] <TB2> INFO: 29392000 events read in total (1097774ms).
[08:38:41.644] <TB2> INFO: 30180650 events read in total (1128686ms).
[08:39:12.729] <TB2> INFO: 30966550 events read in total (1159771ms).
[08:39:43.476] <TB2> INFO: 31754750 events read in total (1190518ms).
[08:40:13.985] <TB2> INFO: 32541350 events read in total (1221027ms).
[08:40:43.632] <TB2> INFO: 33330250 events read in total (1250674ms).
[08:41:13.241] <TB2> INFO: 34116650 events read in total (1280283ms).
[08:41:42.690] <TB2> INFO: 34905950 events read in total (1309732ms).
[08:42:11.702] <TB2> INFO: 35694700 events read in total (1338744ms).
[08:42:43.342] <TB2> INFO: 36494100 events read in total (1370384ms).
[08:42:48.378] <TB2> INFO: 36608000 events read in total (1375420ms).
[08:42:48.477] <TB2> INFO: Test took 1376346ms.
[08:42:49.039] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:42:51.591] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:42:54.018] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:42:56.791] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:42:59.560] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:43:02.251] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:43:04.149] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:43:06.183] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:43:07.977] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:43:09.978] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:43:11.810] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:43:13.601] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:43:15.351] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:43:17.074] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:43:18.809] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:43:20.641] <TB2> INFO: dumping ASCII scurve output file: SCurveData
[08:43:22.635] <TB2> INFO: PixTestScurves::scurves() done
[08:43:22.635] <TB2> INFO: Vcal mean: 83.18 91.19 87.56 74.59 80.62 78.30 81.78 84.73 88.55 93.26 91.99 78.43 89.54 74.38 82.69 82.55
[08:43:22.635] <TB2> INFO: Vcal RMS: 4.41 5.33 4.82 4.22 4.37 3.96 3.99 4.58 5.37 5.93 6.05 3.78 5.17 4.85 4.36 4.90
[08:43:22.635] <TB2> INFO: PixTestScurves::fullTest() done, duration: 1410 seconds
[08:43:22.635] <TB2> INFO: Decoding statistics:
[08:43:22.635] <TB2> INFO: General information:
[08:43:22.635] <TB2> INFO: 16bit words read: 0
[08:43:22.635] <TB2> INFO: valid events total: 0
[08:43:22.635] <TB2> INFO: empty events: 0
[08:43:22.635] <TB2> INFO: valid events with pixels: 0
[08:43:22.635] <TB2> INFO: valid pixel hits: 0
[08:43:22.635] <TB2> INFO: Event errors: 0
[08:43:22.635] <TB2> INFO: start marker: 0
[08:43:22.635] <TB2> INFO: stop marker: 0
[08:43:22.635] <TB2> INFO: overflow: 0
[08:43:22.635] <TB2> INFO: invalid 5bit words: 0
[08:43:22.635] <TB2> INFO: invalid XOR eye diagram: 0
[08:43:22.635] <TB2> INFO: frame (failed synchr.): 0
[08:43:22.635] <TB2> INFO: idle data (no TBM trl): 0
[08:43:22.635] <TB2> INFO: no data (only TBM hdr): 0
[08:43:22.635] <TB2> INFO: TBM errors: 0
[08:43:22.635] <TB2> INFO: flawed TBM headers: 0
[08:43:22.635] <TB2> INFO: flawed TBM trailers: 0
[08:43:22.635] <TB2> INFO: event ID mismatches: 0
[08:43:22.635] <TB2> INFO: ROC errors: 0
[08:43:22.635] <TB2> INFO: missing ROC header(s): 0
[08:43:22.635] <TB2> INFO: misplaced readback start: 0
[08:43:22.635] <TB2> INFO: Pixel decoding errors: 0
[08:43:22.635] <TB2> INFO: pixel data incomplete: 0
[08:43:22.635] <TB2> INFO: pixel address: 0
[08:43:22.635] <TB2> INFO: pulse height fill bit: 0
[08:43:22.635] <TB2> INFO: buffer corruption: 0
[08:43:22.720] <TB2> INFO: ######################################################################
[08:43:22.720] <TB2> INFO: PixTestTrim::doTest()
[08:43:22.720] <TB2> INFO: ######################################################################
[08:43:22.721] <TB2> INFO: ----------------------------------------------------------------------
[08:43:22.721] <TB2> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[08:43:22.721] <TB2> INFO: ----------------------------------------------------------------------
[08:43:22.856] <TB2> INFO: ---> VthrComp thr map (minimal VthrComp)
[08:43:22.856] <TB2> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[08:43:22.865] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:43:22.865] <TB2> INFO: run 1 of 1
[08:43:23.144] <TB2> INFO: Expecting 6281600 events.
[08:44:01.834] <TB2> INFO: 1084540 events read in total (38124ms).
[08:44:38.688] <TB2> INFO: 2163360 events read in total (74978ms).
[08:45:15.112] <TB2> INFO: 3235350 events read in total (111403ms).
[08:45:52.005] <TB2> INFO: 4315020 events read in total (148295ms).
[08:46:30.313] <TB2> INFO: 5399100 events read in total (186604ms).
[08:47:03.763] <TB2> INFO: 6281600 events read in total (220053ms).
[08:47:03.836] <TB2> INFO: Test took 220971ms.
[08:47:24.600] <TB2> INFO: ROC 0 VthrComp = 90
[08:47:24.600] <TB2> INFO: ROC 1 VthrComp = 99
[08:47:24.600] <TB2> INFO: ROC 2 VthrComp = 99
[08:47:24.600] <TB2> INFO: ROC 3 VthrComp = 83
[08:47:24.600] <TB2> INFO: ROC 4 VthrComp = 87
[08:47:24.600] <TB2> INFO: ROC 5 VthrComp = 84
[08:47:24.600] <TB2> INFO: ROC 6 VthrComp = 95
[08:47:24.600] <TB2> INFO: ROC 7 VthrComp = 94
[08:47:24.600] <TB2> INFO: ROC 8 VthrComp = 96
[08:47:24.601] <TB2> INFO: ROC 9 VthrComp = 98
[08:47:24.601] <TB2> INFO: ROC 10 VthrComp = 97
[08:47:24.601] <TB2> INFO: ROC 11 VthrComp = 87
[08:47:24.601] <TB2> INFO: ROC 12 VthrComp = 97
[08:47:24.601] <TB2> INFO: ROC 13 VthrComp = 80
[08:47:24.601] <TB2> INFO: ROC 14 VthrComp = 88
[08:47:24.601] <TB2> INFO: ROC 15 VthrComp = 88
[08:47:24.602] <TB2> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[08:47:24.602] <TB2> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[08:47:24.611] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:47:24.611] <TB2> INFO: run 1 of 1
[08:47:24.890] <TB2> INFO: Expecting 6281600 events.
[08:47:57.318] <TB2> INFO: 757370 events read in total (31872ms).
[08:48:30.184] <TB2> INFO: 1512530 events read in total (64738ms).
[08:49:02.345] <TB2> INFO: 2267450 events read in total (96899ms).
[08:49:34.466] <TB2> INFO: 3023270 events read in total (129020ms).
[08:50:07.404] <TB2> INFO: 3774500 events read in total (161958ms).
[08:50:39.688] <TB2> INFO: 4521930 events read in total (194242ms).
[08:51:11.927] <TB2> INFO: 5267510 events read in total (226481ms).
[08:51:45.440] <TB2> INFO: 6012120 events read in total (259994ms).
[08:51:58.380] <TB2> INFO: 6281600 events read in total (272934ms).
[08:51:58.453] <TB2> INFO: Test took 273842ms.
[08:52:25.685] <TB2> INFO: roc 0 with ID = 0 has maximal Vcal 57.1588 for pixel 51/73 mean/min/max = 45.2573/33.3467/57.1679
[08:52:25.685] <TB2> INFO: roc 1 with ID = 1 has maximal Vcal 58.8565 for pixel 15/23 mean/min/max = 45.1643/31.4599/58.8687
[08:52:25.685] <TB2> INFO: roc 2 with ID = 2 has maximal Vcal 55.0675 for pixel 51/13 mean/min/max = 43.2338/31.2471/55.2205
[08:52:25.686] <TB2> INFO: roc 3 with ID = 3 has maximal Vcal 55.2454 for pixel 5/75 mean/min/max = 43.6748/32.0788/55.2708
[08:52:25.686] <TB2> INFO: roc 4 with ID = 4 has maximal Vcal 57.6901 for pixel 6/74 mean/min/max = 44.7554/31.7113/57.7995
[08:52:25.686] <TB2> INFO: roc 5 with ID = 5 has maximal Vcal 56.2326 for pixel 0/79 mean/min/max = 44.4384/32.5375/56.3392
[08:52:25.687] <TB2> INFO: roc 6 with ID = 6 has maximal Vcal 54.4526 for pixel 22/20 mean/min/max = 43.3716/32.2736/54.4696
[08:52:25.687] <TB2> INFO: roc 7 with ID = 7 has maximal Vcal 56.5017 for pixel 0/2 mean/min/max = 44.2209/31.9129/56.529
[08:52:25.687] <TB2> INFO: roc 8 with ID = 8 has maximal Vcal 58.5445 for pixel 22/43 mean/min/max = 45.0849/31.6157/58.5542
[08:52:25.688] <TB2> INFO: roc 9 with ID = 9 has maximal Vcal 62.3961 for pixel 0/20 mean/min/max = 46.9804/31.1784/62.7823
[08:52:25.688] <TB2> INFO: roc 10 with ID = 10 has maximal Vcal 62.147 for pixel 4/12 mean/min/max = 47.1052/32.045/62.1653
[08:52:25.689] <TB2> INFO: roc 11 with ID = 11 has maximal Vcal 54.2653 for pixel 20/1 mean/min/max = 43.5193/31.86/55.1785
[08:52:25.689] <TB2> INFO: roc 12 with ID = 12 has maximal Vcal 58.7091 for pixel 5/67 mean/min/max = 45.1964/31.5036/58.8892
[08:52:25.689] <TB2> INFO: roc 13 with ID = 13 has maximal Vcal 58.5462 for pixel 10/77 mean/min/max = 45.373/32.1621/58.5838
[08:52:25.690] <TB2> INFO: roc 14 with ID = 14 has maximal Vcal 56.4728 for pixel 4/73 mean/min/max = 45.0503/33.5728/56.5277
[08:52:25.690] <TB2> INFO: roc 15 with ID = 15 has maximal Vcal 58.5725 for pixel 13/2 mean/min/max = 45.5413/32.2868/58.7958
[08:52:25.691] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[08:52:25.779] <TB2> INFO: Expecting 514560 events.
[08:52:36.739] <TB2> INFO: 514560 events read in total (10404ms).
[08:52:36.746] <TB2> INFO: Expecting 514560 events.
[08:52:47.684] <TB2> INFO: 514560 events read in total (10536ms).
[08:52:47.694] <TB2> INFO: Expecting 514560 events.
[08:52:58.487] <TB2> INFO: 514560 events read in total (10391ms).
[08:52:58.499] <TB2> INFO: Expecting 514560 events.
[08:53:09.373] <TB2> INFO: 514560 events read in total (10472ms).
[08:53:09.388] <TB2> INFO: Expecting 514560 events.
[08:53:20.314] <TB2> INFO: 514560 events read in total (10524ms).
[08:53:20.332] <TB2> INFO: Expecting 514560 events.
[08:53:31.086] <TB2> INFO: 514560 events read in total (10352ms).
[08:53:31.106] <TB2> INFO: Expecting 514560 events.
[08:53:42.202] <TB2> INFO: 514560 events read in total (10694ms).
[08:53:42.225] <TB2> INFO: Expecting 514560 events.
[08:53:53.109] <TB2> INFO: 514560 events read in total (10482ms).
[08:53:53.135] <TB2> INFO: Expecting 514560 events.
[08:54:04.208] <TB2> INFO: 514560 events read in total (10671ms).
[08:54:04.241] <TB2> INFO: Expecting 514560 events.
[08:54:15.065] <TB2> INFO: 514560 events read in total (10422ms).
[08:54:15.097] <TB2> INFO: Expecting 514560 events.
[08:54:25.991] <TB2> INFO: 514560 events read in total (10493ms).
[08:54:26.040] <TB2> INFO: Expecting 514560 events.
[08:54:36.747] <TB2> INFO: 514560 events read in total (10305ms).
[08:54:36.784] <TB2> INFO: Expecting 514560 events.
[08:54:47.571] <TB2> INFO: 514560 events read in total (10386ms).
[08:54:47.615] <TB2> INFO: Expecting 514560 events.
[08:54:58.578] <TB2> INFO: 514560 events read in total (10561ms).
[08:54:58.621] <TB2> INFO: Expecting 514560 events.
[08:55:09.519] <TB2> INFO: 514560 events read in total (10495ms).
[08:55:09.567] <TB2> INFO: Expecting 514560 events.
[08:55:20.736] <TB2> INFO: 514560 events read in total (10767ms).
[08:55:20.788] <TB2> INFO: Test took 175097ms.
[08:55:22.078] <TB2> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[08:55:22.087] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[08:55:22.087] <TB2> INFO: run 1 of 1
[08:55:22.348] <TB2> INFO: Expecting 6281600 events.
[08:55:55.822] <TB2> INFO: 747220 events read in total (32918ms).
[08:56:27.867] <TB2> INFO: 1492850 events read in total (64963ms).
[08:57:00.211] <TB2> INFO: 2237990 events read in total (97307ms).
[08:57:31.313] <TB2> INFO: 2984040 events read in total (128409ms).
[08:58:03.372] <TB2> INFO: 3723900 events read in total (160468ms).
[08:58:34.751] <TB2> INFO: 4458550 events read in total (191847ms).
[08:59:06.526] <TB2> INFO: 5190670 events read in total (223622ms).
[08:59:40.685] <TB2> INFO: 5921480 events read in total (257781ms).
[08:59:57.877] <TB2> INFO: 6281600 events read in total (274973ms).
[08:59:57.936] <TB2> INFO: Test took 275849ms.
[09:00:26.293] <TB2> INFO: ---> TrimStepCorr4 extremal thresholds: 7.940794 .. 50.556223
[09:00:26.590] <TB2> INFO: Expecting 208000 events.
[09:00:36.696] <TB2> INFO: 208000 events read in total (9550ms).
[09:00:36.697] <TB2> INFO: Test took 10403ms.
[09:00:36.785] <TB2> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 7 .. 60 (-1/-1) hits flags = 528 (plus default)
[09:00:36.794] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[09:00:36.794] <TB2> INFO: run 1 of 1
[09:00:37.059] <TB2> INFO: Expecting 2246400 events.
[09:01:11.938] <TB2> INFO: 914580 events read in total (34323ms).
[09:01:46.085] <TB2> INFO: 1822970 events read in total (68471ms).
[09:02:02.696] <TB2> INFO: 2246400 events read in total (85081ms).
[09:02:02.723] <TB2> INFO: Test took 85930ms.
[09:02:20.017] <TB2> INFO: ---> TrimStepCorr2 extremal thresholds: 20.589236 .. 43.697597
[09:02:20.281] <TB2> INFO: Expecting 208000 events.
[09:02:30.396] <TB2> INFO: 208000 events read in total (9558ms).
[09:02:30.396] <TB2> INFO: Test took 10377ms.
[09:02:30.541] <TB2> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 10 .. 53 (-1/-1) hits flags = 528 (plus default)
[09:02:30.553] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[09:02:30.553] <TB2> INFO: run 1 of 1
[09:02:30.838] <TB2> INFO: Expecting 1830400 events.
[09:03:07.998] <TB2> INFO: 944140 events read in total (36603ms).
[09:03:41.902] <TB2> INFO: 1830400 events read in total (70507ms).
[09:03:41.923] <TB2> INFO: Test took 71370ms.
[09:03:57.714] <TB2> INFO: ---> TrimStepCorr1a extremal thresholds: 22.149253 .. 39.535247
[09:03:58.013] <TB2> INFO: Expecting 208000 events.
[09:04:08.270] <TB2> INFO: 208000 events read in total (9701ms).
[09:04:08.271] <TB2> INFO: Test took 10554ms.
[09:04:08.356] <TB2> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 12 .. 49 (-1/-1) hits flags = 528 (plus default)
[09:04:08.364] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[09:04:08.364] <TB2> INFO: run 1 of 1
[09:04:08.626] <TB2> INFO: Expecting 1580800 events.
[09:04:45.766] <TB2> INFO: 972340 events read in total (36584ms).
[09:05:09.788] <TB2> INFO: 1580800 events read in total (60606ms).
[09:05:09.807] <TB2> INFO: Test took 61443ms.
[09:05:23.670] <TB2> INFO: ---> TrimStepCorr1b extremal thresholds: 24.452163 .. 38.982409
[09:05:23.983] <TB2> INFO: Expecting 208000 events.
[09:05:34.286] <TB2> INFO: 208000 events read in total (9746ms).
[09:05:34.287] <TB2> INFO: Test took 10615ms.
[09:05:34.385] <TB2> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 14 .. 48 (-1/-1) hits flags = 528 (plus default)
[09:05:34.394] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[09:05:34.394] <TB2> INFO: run 1 of 1
[09:05:34.656] <TB2> INFO: Expecting 1456000 events.
[09:06:12.547] <TB2> INFO: 971880 events read in total (37334ms).
[09:06:30.398] <TB2> INFO: 1456000 events read in total (55185ms).
[09:06:30.414] <TB2> INFO: Test took 56021ms.
[09:06:43.608] <TB2> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[09:06:43.608] <TB2> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:06:43.617] <TB2> INFO: dacScan split into 1 runs with ntrig = 10
[09:06:43.617] <TB2> INFO: run 1 of 1
[09:06:43.877] <TB2> INFO: Expecting 1705600 events.
[09:07:18.504] <TB2> INFO: 893790 events read in total (34070ms).
[09:07:50.096] <TB2> INFO: 1705600 events read in total (65662ms).
[09:07:50.117] <TB2> INFO: Test took 66500ms.
[09:08:05.995] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:08:05.995] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:08:05.995] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:08:05.996] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:08:05.996] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:08:05.996] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:08:05.997] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:08:05.997] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:08:05.997] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:08:05.997] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:08:05.997] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:08:05.997] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:08:05.998] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:08:05.998] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:08:05.998] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:08:05.998] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:08:05.998] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C0.dat
[09:08:06.011] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C1.dat
[09:08:06.024] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C2.dat
[09:08:06.036] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C3.dat
[09:08:06.049] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C4.dat
[09:08:06.062] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C5.dat
[09:08:06.074] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C6.dat
[09:08:06.087] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C7.dat
[09:08:06.100] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C8.dat
[09:08:06.112] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C9.dat
[09:08:06.125] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C10.dat
[09:08:06.137] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C11.dat
[09:08:06.150] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C12.dat
[09:08:06.162] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C13.dat
[09:08:06.175] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C14.dat
[09:08:06.188] <TB2> INFO: write trim parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//trimParameters35_C15.dat
[09:08:06.200] <TB2> INFO: PixTestTrim::trimTest() done
[09:08:06.200] <TB2> INFO: vtrim: 97 122 99 91 100 101 99 97 117 117 118 101 107 108 94 109
[09:08:06.200] <TB2> INFO: vthrcomp: 90 99 99 83 87 84 95 94 96 98 97 87 97 80 88 88
[09:08:06.200] <TB2> INFO: vcal mean: 34.97 34.92 34.99 34.94 34.95 34.97 34.96 34.91 34.96 34.94 34.94 34.94 34.95 34.91 34.95 34.97
[09:08:06.200] <TB2> INFO: vcal RMS: 0.77 0.85 0.82 0.80 0.82 0.79 0.78 0.82 0.85 0.92 0.88 0.86 0.87 0.86 0.77 0.80
[09:08:06.200] <TB2> INFO: bits mean: 9.04 9.67 10.17 9.84 9.66 9.50 10.13 9.75 9.73 8.95 9.09 10.31 9.62 9.43 9.27 9.47
[09:08:06.200] <TB2> INFO: bits RMS: 2.69 2.71 2.60 2.59 2.71 2.74 2.45 2.66 2.67 2.98 2.82 2.43 2.74 2.72 2.55 2.64
[09:08:06.208] <TB2> INFO: ----------------------------------------------------------------------
[09:08:06.208] <TB2> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[09:08:06.208] <TB2> INFO: ----------------------------------------------------------------------
[09:08:06.211] <TB2> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[09:08:06.221] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[09:08:06.221] <TB2> INFO: run 1 of 1
[09:08:06.553] <TB2> INFO: Expecting 4160000 events.
[09:08:47.080] <TB2> INFO: 912705 events read in total (39971ms).
[09:09:26.904] <TB2> INFO: 1819860 events read in total (79795ms).
[09:10:05.728] <TB2> INFO: 2719950 events read in total (118619ms).
[09:10:44.158] <TB2> INFO: 3615990 events read in total (157049ms).
[09:11:10.433] <TB2> INFO: 4160000 events read in total (183324ms).
[09:11:10.521] <TB2> INFO: Test took 184301ms.
[09:11:40.581] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 173 (-1/-1) hits flags = 528 (plus default)
[09:11:40.592] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[09:11:40.592] <TB2> INFO: run 1 of 1
[09:11:40.883] <TB2> INFO: Expecting 3619200 events.
[09:12:20.204] <TB2> INFO: 930910 events read in total (38765ms).
[09:12:58.276] <TB2> INFO: 1854550 events read in total (76837ms).
[09:13:37.344] <TB2> INFO: 2768045 events read in total (115905ms).
[09:14:14.387] <TB2> INFO: 3619200 events read in total (152948ms).
[09:14:14.452] <TB2> INFO: Test took 153858ms.
[09:14:45.643] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 161 (-1/-1) hits flags = 528 (plus default)
[09:14:45.654] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[09:14:45.654] <TB2> INFO: run 1 of 1
[09:14:45.986] <TB2> INFO: Expecting 3369600 events.
[09:15:26.326] <TB2> INFO: 958930 events read in total (39784ms).
[09:16:06.435] <TB2> INFO: 1907995 events read in total (79893ms).
[09:16:46.654] <TB2> INFO: 2846365 events read in total (120112ms).
[09:17:08.770] <TB2> INFO: 3369600 events read in total (142228ms).
[09:17:08.838] <TB2> INFO: Test took 143185ms.
[09:17:43.211] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 528 (plus default)
[09:17:43.220] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[09:17:43.220] <TB2> INFO: run 1 of 1
[09:17:43.489] <TB2> INFO: Expecting 3120000 events.
[09:18:24.836] <TB2> INFO: 992780 events read in total (40791ms).
[09:19:05.670] <TB2> INFO: 1971990 events read in total (81625ms).
[09:19:46.172] <TB2> INFO: 2941250 events read in total (122127ms).
[09:19:54.407] <TB2> INFO: 3120000 events read in total (130362ms).
[09:19:54.469] <TB2> INFO: Test took 131249ms.
[09:20:24.543] <TB2> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 528 (plus default)
[09:20:24.552] <TB2> INFO: dacScan split into 1 runs with ntrig = 5
[09:20:24.552] <TB2> INFO: run 1 of 1
[09:20:24.812] <TB2> INFO: Expecting 3120000 events.
[09:21:04.940] <TB2> INFO: 992015 events read in total (39572ms).
[09:21:45.683] <TB2> INFO: 1970180 events read in total (80315ms).
[09:22:26.371] <TB2> INFO: 2938495 events read in total (121003ms).
[09:22:34.275] <TB2> INFO: 3120000 events read in total (128907ms).
[09:22:34.320] <TB2> INFO: Test took 129769ms.
[09:23:01.238] <TB2> INFO: PixTestTrim::trimBitTest() done
[09:23:01.239] <TB2> INFO: PixTestTrim::doTest() done, duration: 2378 seconds
[09:23:01.239] <TB2> INFO: Decoding statistics:
[09:23:01.239] <TB2> INFO: General information:
[09:23:01.239] <TB2> INFO: 16bit words read: 0
[09:23:01.239] <TB2> INFO: valid events total: 0
[09:23:01.239] <TB2> INFO: empty events: 0
[09:23:01.239] <TB2> INFO: valid events with pixels: 0
[09:23:01.239] <TB2> INFO: valid pixel hits: 0
[09:23:01.239] <TB2> INFO: Event errors: 0
[09:23:01.239] <TB2> INFO: start marker: 0
[09:23:01.239] <TB2> INFO: stop marker: 0
[09:23:01.239] <TB2> INFO: overflow: 0
[09:23:01.239] <TB2> INFO: invalid 5bit words: 0
[09:23:01.239] <TB2> INFO: invalid XOR eye diagram: 0
[09:23:01.239] <TB2> INFO: frame (failed synchr.): 0
[09:23:01.239] <TB2> INFO: idle data (no TBM trl): 0
[09:23:01.239] <TB2> INFO: no data (only TBM hdr): 0
[09:23:01.239] <TB2> INFO: TBM errors: 0
[09:23:01.239] <TB2> INFO: flawed TBM headers: 0
[09:23:01.239] <TB2> INFO: flawed TBM trailers: 0
[09:23:01.239] <TB2> INFO: event ID mismatches: 0
[09:23:01.239] <TB2> INFO: ROC errors: 0
[09:23:01.239] <TB2> INFO: missing ROC header(s): 0
[09:23:01.239] <TB2> INFO: misplaced readback start: 0
[09:23:01.239] <TB2> INFO: Pixel decoding errors: 0
[09:23:01.239] <TB2> INFO: pixel data incomplete: 0
[09:23:01.239] <TB2> INFO: pixel address: 0
[09:23:01.239] <TB2> INFO: pulse height fill bit: 0
[09:23:01.239] <TB2> INFO: buffer corruption: 0
[09:23:02.056] <TB2> INFO: ######################################################################
[09:23:02.056] <TB2> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[09:23:02.056] <TB2> INFO: ######################################################################
[09:23:02.330] <TB2> INFO: Expecting 41600 events.
[09:23:05.768] <TB2> INFO: 41600 events read in total (2882ms).
[09:23:05.769] <TB2> INFO: Test took 3712ms.
[09:23:06.236] <TB2> INFO: Expecting 41600 events.
[09:23:09.722] <TB2> INFO: 41600 events read in total (2930ms).
[09:23:09.723] <TB2> INFO: Test took 3759ms.
[09:23:10.025] <TB2> INFO: Expecting 41600 events.
[09:23:13.482] <TB2> INFO: 41600 events read in total (2901ms).
[09:23:13.482] <TB2> INFO: Test took 3737ms.
[09:23:13.737] <TB2> INFO: Expecting 2560 events.
[09:23:14.588] <TB2> INFO: 2560 events read in total (295ms).
[09:23:14.588] <TB2> INFO: Test took 1093ms.
[09:23:14.894] <TB2> INFO: Expecting 2560 events.
[09:23:15.746] <TB2> INFO: 2560 events read in total (296ms).
[09:23:15.747] <TB2> INFO: Test took 1158ms.
[09:23:16.051] <TB2> INFO: Expecting 2560 events.
[09:23:16.902] <TB2> INFO: 2560 events read in total (295ms).
[09:23:16.903] <TB2> INFO: Test took 1156ms.
[09:23:17.207] <TB2> INFO: Expecting 2560 events.
[09:23:18.059] <TB2> INFO: 2560 events read in total (295ms).
[09:23:18.059] <TB2> INFO: Test took 1156ms.
[09:23:18.364] <TB2> INFO: Expecting 2560 events.
[09:23:19.216] <TB2> INFO: 2560 events read in total (296ms).
[09:23:19.216] <TB2> INFO: Test took 1156ms.
[09:23:19.521] <TB2> INFO: Expecting 2560 events.
[09:23:20.372] <TB2> INFO: 2560 events read in total (295ms).
[09:23:20.372] <TB2> INFO: Test took 1155ms.
[09:23:20.677] <TB2> INFO: Expecting 2560 events.
[09:23:21.529] <TB2> INFO: 2560 events read in total (295ms).
[09:23:21.529] <TB2> INFO: Test took 1156ms.
[09:23:21.834] <TB2> INFO: Expecting 2560 events.
[09:23:22.687] <TB2> INFO: 2560 events read in total (296ms).
[09:23:22.687] <TB2> INFO: Test took 1157ms.
[09:23:22.992] <TB2> INFO: Expecting 2560 events.
[09:23:23.849] <TB2> INFO: 2560 events read in total (300ms).
[09:23:23.849] <TB2> INFO: Test took 1161ms.
[09:23:24.154] <TB2> INFO: Expecting 2560 events.
[09:23:25.009] <TB2> INFO: 2560 events read in total (299ms).
[09:23:25.010] <TB2> INFO: Test took 1161ms.
[09:23:25.314] <TB2> INFO: Expecting 2560 events.
[09:23:26.166] <TB2> INFO: 2560 events read in total (296ms).
[09:23:26.166] <TB2> INFO: Test took 1156ms.
[09:23:26.471] <TB2> INFO: Expecting 2560 events.
[09:23:27.323] <TB2> INFO: 2560 events read in total (296ms).
[09:23:27.323] <TB2> INFO: Test took 1156ms.
[09:23:27.628] <TB2> INFO: Expecting 2560 events.
[09:23:28.481] <TB2> INFO: 2560 events read in total (297ms).
[09:23:28.481] <TB2> INFO: Test took 1157ms.
[09:23:28.786] <TB2> INFO: Expecting 2560 events.
[09:23:29.643] <TB2> INFO: 2560 events read in total (301ms).
[09:23:29.643] <TB2> INFO: Test took 1162ms.
[09:23:29.948] <TB2> INFO: Expecting 2560 events.
[09:23:30.804] <TB2> INFO: 2560 events read in total (300ms).
[09:23:30.804] <TB2> INFO: Test took 1161ms.
[09:23:31.108] <TB2> INFO: Expecting 2560 events.
[09:23:31.959] <TB2> INFO: 2560 events read in total (295ms).
[09:23:31.959] <TB2> INFO: Test took 1155ms.
[09:23:31.962] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:23:32.265] <TB2> INFO: Expecting 655360 events.
[09:23:46.151] <TB2> INFO: 655360 events read in total (13329ms).
[09:23:46.161] <TB2> INFO: Expecting 655360 events.
[09:24:00.096] <TB2> INFO: 655360 events read in total (13531ms).
[09:24:00.110] <TB2> INFO: Expecting 655360 events.
[09:24:14.318] <TB2> INFO: 655360 events read in total (13806ms).
[09:24:14.337] <TB2> INFO: Expecting 655360 events.
[09:24:28.463] <TB2> INFO: 655360 events read in total (13724ms).
[09:24:28.486] <TB2> INFO: Expecting 655360 events.
[09:24:42.465] <TB2> INFO: 655360 events read in total (13577ms).
[09:24:42.491] <TB2> INFO: Expecting 655360 events.
[09:24:56.480] <TB2> INFO: 655360 events read in total (13587ms).
[09:24:56.532] <TB2> INFO: Expecting 655360 events.
[09:25:10.765] <TB2> INFO: 655360 events read in total (13831ms).
[09:25:10.801] <TB2> INFO: Expecting 655360 events.
[09:25:25.086] <TB2> INFO: 655360 events read in total (13884ms).
[09:25:25.128] <TB2> INFO: Expecting 655360 events.
[09:25:39.309] <TB2> INFO: 655360 events read in total (13779ms).
[09:25:39.354] <TB2> INFO: Expecting 655360 events.
[09:25:53.106] <TB2> INFO: 655360 events read in total (13350ms).
[09:25:53.158] <TB2> INFO: Expecting 655360 events.
[09:26:07.070] <TB2> INFO: 655360 events read in total (13511ms).
[09:26:07.123] <TB2> INFO: Expecting 655360 events.
[09:26:21.325] <TB2> INFO: 655360 events read in total (13800ms).
[09:26:21.384] <TB2> INFO: Expecting 655360 events.
[09:26:35.359] <TB2> INFO: 655360 events read in total (13574ms).
[09:26:35.434] <TB2> INFO: Expecting 655360 events.
[09:26:49.132] <TB2> INFO: 655360 events read in total (13296ms).
[09:26:49.202] <TB2> INFO: Expecting 655360 events.
[09:27:03.043] <TB2> INFO: 655360 events read in total (13439ms).
[09:27:03.119] <TB2> INFO: Expecting 655360 events.
[09:27:17.140] <TB2> INFO: 655360 events read in total (13619ms).
[09:27:17.220] <TB2> INFO: Test took 225258ms.
[09:27:17.322] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:27:17.549] <TB2> INFO: Expecting 655360 events.
[09:27:31.564] <TB2> INFO: 655360 events read in total (13459ms).
[09:27:31.576] <TB2> INFO: Expecting 655360 events.
[09:27:45.463] <TB2> INFO: 655360 events read in total (13485ms).
[09:27:45.486] <TB2> INFO: Expecting 655360 events.
[09:27:59.403] <TB2> INFO: 655360 events read in total (13515ms).
[09:27:59.424] <TB2> INFO: Expecting 655360 events.
[09:28:13.215] <TB2> INFO: 655360 events read in total (13390ms).
[09:28:13.240] <TB2> INFO: Expecting 655360 events.
[09:28:27.078] <TB2> INFO: 655360 events read in total (13437ms).
[09:28:27.107] <TB2> INFO: Expecting 655360 events.
[09:28:40.917] <TB2> INFO: 655360 events read in total (13408ms).
[09:28:40.952] <TB2> INFO: Expecting 655360 events.
[09:28:54.882] <TB2> INFO: 655360 events read in total (13528ms).
[09:28:54.917] <TB2> INFO: Expecting 655360 events.
[09:29:08.681] <TB2> INFO: 655360 events read in total (13362ms).
[09:29:08.731] <TB2> INFO: Expecting 655360 events.
[09:29:22.479] <TB2> INFO: 655360 events read in total (13346ms).
[09:29:22.524] <TB2> INFO: Expecting 655360 events.
[09:29:36.323] <TB2> INFO: 655360 events read in total (13397ms).
[09:29:36.377] <TB2> INFO: Expecting 655360 events.
[09:29:50.243] <TB2> INFO: 655360 events read in total (13464ms).
[09:29:50.295] <TB2> INFO: Expecting 655360 events.
[09:30:04.186] <TB2> INFO: 655360 events read in total (13489ms).
[09:30:04.245] <TB2> INFO: Expecting 655360 events.
[09:30:18.050] <TB2> INFO: 655360 events read in total (13403ms).
[09:30:18.267] <TB2> INFO: Expecting 655360 events.
[09:30:31.837] <TB2> INFO: 655360 events read in total (13168ms).
[09:30:31.905] <TB2> INFO: Expecting 655360 events.
[09:30:45.639] <TB2> INFO: 655360 events read in total (13332ms).
[09:30:45.719] <TB2> INFO: Expecting 655360 events.
[09:30:59.457] <TB2> INFO: 655360 events read in total (13337ms).
[09:30:59.553] <TB2> INFO: Test took 222231ms.
[09:30:59.765] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:59.772] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[09:30:59.780] <TB2> INFO: safety margin for low PH: adding 2, margin is now 22
[09:30:59.787] <TB2> INFO: safety margin for low PH: adding 3, margin is now 23
[09:30:59.794] <TB2> INFO: safety margin for low PH: adding 4, margin is now 24
[09:30:59.801] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:59.808] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:59.815] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:59.822] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:59.830] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:59.837] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:59.844] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:59.854] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:59.865] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:59.872] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:59.879] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:59.886] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:59.893] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:59.900] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:59.908] <TB2> INFO: safety margin for low PH: adding 1, margin is now 21
[09:30:59.919] <TB2> INFO: safety margin for low PH: adding 2, margin is now 22
[09:30:59.930] <TB2> INFO: safety margin for low PH: adding 0, margin is now 20
[09:30:59.970] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:30:59.970] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:30:59.970] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:30:59.970] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:30:59.970] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:30:59.970] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:30:59.970] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:30:59.971] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:30:59.971] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:30:59.971] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:30:59.971] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:30:59.971] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:31:00.008] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:31:00.008] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:31:00.008] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:31:00.008] <TB2> INFO: write dac parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:31:00.289] <TB2> INFO: Expecting 41600 events.
[09:31:03.399] <TB2> INFO: 41600 events read in total (2553ms).
[09:31:03.400] <TB2> INFO: Test took 3390ms.
[09:31:03.907] <TB2> INFO: Expecting 41600 events.
[09:31:06.950] <TB2> INFO: 41600 events read in total (2487ms).
[09:31:06.950] <TB2> INFO: Test took 3376ms.
[09:31:07.393] <TB2> INFO: Expecting 41600 events.
[09:31:10.535] <TB2> INFO: 41600 events read in total (2586ms).
[09:31:10.535] <TB2> INFO: Test took 3407ms.
[09:31:10.717] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:10.808] <TB2> INFO: Expecting 2560 events.
[09:31:11.661] <TB2> INFO: 2560 events read in total (297ms).
[09:31:11.662] <TB2> INFO: Test took 945ms.
[09:31:11.664] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:11.967] <TB2> INFO: Expecting 2560 events.
[09:31:12.818] <TB2> INFO: 2560 events read in total (295ms).
[09:31:12.818] <TB2> INFO: Test took 1155ms.
[09:31:12.821] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:13.124] <TB2> INFO: Expecting 2560 events.
[09:31:13.975] <TB2> INFO: 2560 events read in total (295ms).
[09:31:13.975] <TB2> INFO: Test took 1154ms.
[09:31:13.977] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:14.280] <TB2> INFO: Expecting 2560 events.
[09:31:15.133] <TB2> INFO: 2560 events read in total (296ms).
[09:31:15.133] <TB2> INFO: Test took 1156ms.
[09:31:15.135] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:15.438] <TB2> INFO: Expecting 2560 events.
[09:31:16.290] <TB2> INFO: 2560 events read in total (296ms).
[09:31:16.290] <TB2> INFO: Test took 1155ms.
[09:31:16.292] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:16.595] <TB2> INFO: Expecting 2560 events.
[09:31:17.449] <TB2> INFO: 2560 events read in total (298ms).
[09:31:17.449] <TB2> INFO: Test took 1157ms.
[09:31:17.451] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:17.753] <TB2> INFO: Expecting 2560 events.
[09:31:18.605] <TB2> INFO: 2560 events read in total (295ms).
[09:31:18.605] <TB2> INFO: Test took 1154ms.
[09:31:18.607] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:18.911] <TB2> INFO: Expecting 2560 events.
[09:31:19.764] <TB2> INFO: 2560 events read in total (297ms).
[09:31:19.764] <TB2> INFO: Test took 1157ms.
[09:31:19.766] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:20.069] <TB2> INFO: Expecting 2560 events.
[09:31:20.921] <TB2> INFO: 2560 events read in total (296ms).
[09:31:20.921] <TB2> INFO: Test took 1155ms.
[09:31:20.923] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:21.227] <TB2> INFO: Expecting 2560 events.
[09:31:22.078] <TB2> INFO: 2560 events read in total (295ms).
[09:31:22.079] <TB2> INFO: Test took 1156ms.
[09:31:22.081] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:22.384] <TB2> INFO: Expecting 2560 events.
[09:31:23.237] <TB2> INFO: 2560 events read in total (297ms).
[09:31:23.237] <TB2> INFO: Test took 1156ms.
[09:31:23.240] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:23.542] <TB2> INFO: Expecting 2560 events.
[09:31:24.394] <TB2> INFO: 2560 events read in total (296ms).
[09:31:24.394] <TB2> INFO: Test took 1155ms.
[09:31:24.396] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:24.699] <TB2> INFO: Expecting 2560 events.
[09:31:25.552] <TB2> INFO: 2560 events read in total (297ms).
[09:31:25.552] <TB2> INFO: Test took 1156ms.
[09:31:25.554] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:25.857] <TB2> INFO: Expecting 2560 events.
[09:31:26.709] <TB2> INFO: 2560 events read in total (296ms).
[09:31:26.709] <TB2> INFO: Test took 1155ms.
[09:31:26.711] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:27.014] <TB2> INFO: Expecting 2560 events.
[09:31:27.867] <TB2> INFO: 2560 events read in total (296ms).
[09:31:27.867] <TB2> INFO: Test took 1156ms.
[09:31:27.878] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:28.172] <TB2> INFO: Expecting 2560 events.
[09:31:29.023] <TB2> INFO: 2560 events read in total (295ms).
[09:31:29.025] <TB2> INFO: Test took 1147ms.
[09:31:29.026] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:29.329] <TB2> INFO: Expecting 2560 events.
[09:31:30.181] <TB2> INFO: 2560 events read in total (296ms).
[09:31:30.181] <TB2> INFO: Test took 1155ms.
[09:31:30.184] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:30.486] <TB2> INFO: Expecting 2560 events.
[09:31:31.337] <TB2> INFO: 2560 events read in total (295ms).
[09:31:31.337] <TB2> INFO: Test took 1153ms.
[09:31:31.340] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:31.643] <TB2> INFO: Expecting 2560 events.
[09:31:32.494] <TB2> INFO: 2560 events read in total (295ms).
[09:31:32.495] <TB2> INFO: Test took 1155ms.
[09:31:32.496] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:32.800] <TB2> INFO: Expecting 2560 events.
[09:31:33.657] <TB2> INFO: 2560 events read in total (301ms).
[09:31:33.657] <TB2> INFO: Test took 1161ms.
[09:31:33.660] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:33.963] <TB2> INFO: Expecting 2560 events.
[09:31:34.818] <TB2> INFO: 2560 events read in total (299ms).
[09:31:34.819] <TB2> INFO: Test took 1159ms.
[09:31:34.820] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:35.124] <TB2> INFO: Expecting 2560 events.
[09:31:35.980] <TB2> INFO: 2560 events read in total (300ms).
[09:31:35.980] <TB2> INFO: Test took 1160ms.
[09:31:35.982] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:36.286] <TB2> INFO: Expecting 2560 events.
[09:31:37.141] <TB2> INFO: 2560 events read in total (299ms).
[09:31:37.142] <TB2> INFO: Test took 1160ms.
[09:31:37.144] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:37.447] <TB2> INFO: Expecting 2560 events.
[09:31:38.299] <TB2> INFO: 2560 events read in total (296ms).
[09:31:38.300] <TB2> INFO: Test took 1156ms.
[09:31:38.302] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:38.605] <TB2> INFO: Expecting 2560 events.
[09:31:39.457] <TB2> INFO: 2560 events read in total (296ms).
[09:31:39.458] <TB2> INFO: Test took 1156ms.
[09:31:39.459] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:39.763] <TB2> INFO: Expecting 2560 events.
[09:31:40.620] <TB2> INFO: 2560 events read in total (301ms).
[09:31:40.620] <TB2> INFO: Test took 1161ms.
[09:31:40.622] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:40.925] <TB2> INFO: Expecting 2560 events.
[09:31:41.777] <TB2> INFO: 2560 events read in total (296ms).
[09:31:41.777] <TB2> INFO: Test took 1155ms.
[09:31:41.780] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:42.082] <TB2> INFO: Expecting 2560 events.
[09:31:42.939] <TB2> INFO: 2560 events read in total (300ms).
[09:31:42.939] <TB2> INFO: Test took 1159ms.
[09:31:42.941] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:43.244] <TB2> INFO: Expecting 2560 events.
[09:31:44.096] <TB2> INFO: 2560 events read in total (296ms).
[09:31:44.096] <TB2> INFO: Test took 1155ms.
[09:31:44.097] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:44.401] <TB2> INFO: Expecting 2560 events.
[09:31:45.254] <TB2> INFO: 2560 events read in total (296ms).
[09:31:45.254] <TB2> INFO: Test took 1157ms.
[09:31:45.256] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:45.559] <TB2> INFO: Expecting 2560 events.
[09:31:46.412] <TB2> INFO: 2560 events read in total (296ms).
[09:31:46.412] <TB2> INFO: Test took 1156ms.
[09:31:46.414] <TB2> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:31:46.717] <TB2> INFO: Expecting 2560 events.
[09:31:47.571] <TB2> INFO: 2560 events read in total (297ms).
[09:31:47.571] <TB2> INFO: Test took 1157ms.
[09:31:48.045] <TB2> INFO: PixTestPhOptimization::doTest() done, duration: 525 seconds
[09:31:48.046] <TB2> INFO: PH scale (per ROC): 87 82 94 86 85 97 97 92 80 80 84 90 80 87 80 80
[09:31:48.046] <TB2> INFO: PH offset (per ROC): 144 152 149 143 164 143 135 144 165 172 150 143 158 143 149 153
[09:31:48.052] <TB2> INFO: Decoding statistics:
[09:31:48.052] <TB2> INFO: General information:
[09:31:48.052] <TB2> INFO: 16bit words read: 86922
[09:31:48.052] <TB2> INFO: valid events total: 10240
[09:31:48.052] <TB2> INFO: empty events: 7739
[09:31:48.052] <TB2> INFO: valid events with pixels: 2501
[09:31:48.052] <TB2> INFO: valid pixel hits: 2501
[09:31:48.052] <TB2> INFO: Event errors: 0
[09:31:48.052] <TB2> INFO: start marker: 0
[09:31:48.052] <TB2> INFO: stop marker: 0
[09:31:48.052] <TB2> INFO: overflow: 0
[09:31:48.052] <TB2> INFO: invalid 5bit words: 0
[09:31:48.052] <TB2> INFO: invalid XOR eye diagram: 0
[09:31:48.052] <TB2> INFO: frame (failed synchr.): 0
[09:31:48.052] <TB2> INFO: idle data (no TBM trl): 0
[09:31:48.052] <TB2> INFO: no data (only TBM hdr): 0
[09:31:48.052] <TB2> INFO: TBM errors: 0
[09:31:48.052] <TB2> INFO: flawed TBM headers: 0
[09:31:48.052] <TB2> INFO: flawed TBM trailers: 0
[09:31:48.052] <TB2> INFO: event ID mismatches: 0
[09:31:48.052] <TB2> INFO: ROC errors: 0
[09:31:48.052] <TB2> INFO: missing ROC header(s): 0
[09:31:48.052] <TB2> INFO: misplaced readback start: 0
[09:31:48.052] <TB2> INFO: Pixel decoding errors: 0
[09:31:48.052] <TB2> INFO: pixel data incomplete: 0
[09:31:48.052] <TB2> INFO: pixel address: 0
[09:31:48.052] <TB2> INFO: pulse height fill bit: 0
[09:31:48.052] <TB2> INFO: buffer corruption: 0
[09:31:48.231] <TB2> INFO: ######################################################################
[09:31:48.231] <TB2> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[09:31:48.231] <TB2> INFO: ######################################################################
[09:31:48.242] <TB2> INFO: scanning low vcal = 10
[09:31:48.506] <TB2> INFO: Expecting 41600 events.
[09:31:51.882] <TB2> INFO: 41600 events read in total (2820ms).
[09:31:51.882] <TB2> INFO: Test took 3640ms.
[09:31:51.883] <TB2> INFO: scanning low vcal = 20
[09:31:52.180] <TB2> INFO: Expecting 41600 events.
[09:31:55.588] <TB2> INFO: 41600 events read in total (2851ms).
[09:31:55.589] <TB2> INFO: Test took 3706ms.
[09:31:55.590] <TB2> INFO: scanning low vcal = 30
[09:31:55.887] <TB2> INFO: Expecting 41600 events.
[09:31:59.301] <TB2> INFO: 41600 events read in total (2858ms).
[09:31:59.301] <TB2> INFO: Test took 3711ms.
[09:31:59.303] <TB2> INFO: scanning low vcal = 40
[09:31:59.594] <TB2> INFO: Expecting 41600 events.
[09:32:03.475] <TB2> INFO: 41600 events read in total (3325ms).
[09:32:03.476] <TB2> INFO: Test took 4173ms.
[09:32:03.480] <TB2> INFO: scanning low vcal = 50
[09:32:03.762] <TB2> INFO: Expecting 41600 events.
[09:32:07.690] <TB2> INFO: 41600 events read in total (3372ms).
[09:32:07.690] <TB2> INFO: Test took 4210ms.
[09:32:07.694] <TB2> INFO: scanning low vcal = 60
[09:32:07.959] <TB2> INFO: Expecting 41600 events.
[09:32:11.881] <TB2> INFO: 41600 events read in total (3366ms).
[09:32:11.881] <TB2> INFO: Test took 4187ms.
[09:32:11.884] <TB2> INFO: scanning low vcal = 70
[09:32:12.149] <TB2> INFO: Expecting 41600 events.
[09:32:16.069] <TB2> INFO: 41600 events read in total (3364ms).
[09:32:16.070] <TB2> INFO: Test took 4186ms.
[09:32:16.073] <TB2> INFO: scanning low vcal = 80
[09:32:16.356] <TB2> INFO: Expecting 41600 events.
[09:32:20.323] <TB2> INFO: 41600 events read in total (3411ms).
[09:32:20.325] <TB2> INFO: Test took 4252ms.
[09:32:20.328] <TB2> INFO: scanning low vcal = 90
[09:32:20.627] <TB2> INFO: Expecting 41600 events.
[09:32:24.557] <TB2> INFO: 41600 events read in total (3374ms).
[09:32:24.558] <TB2> INFO: Test took 4230ms.
[09:32:24.561] <TB2> INFO: scanning low vcal = 100
[09:32:24.825] <TB2> INFO: Expecting 41600 events.
[09:32:28.758] <TB2> INFO: 41600 events read in total (3377ms).
[09:32:28.759] <TB2> INFO: Test took 4198ms.
[09:32:28.761] <TB2> INFO: scanning low vcal = 110
[09:32:29.025] <TB2> INFO: Expecting 41600 events.
[09:32:32.958] <TB2> INFO: 41600 events read in total (3377ms).
[09:32:32.959] <TB2> INFO: Test took 4198ms.
[09:32:32.961] <TB2> INFO: scanning low vcal = 120
[09:32:33.225] <TB2> INFO: Expecting 41600 events.
[09:32:37.143] <TB2> INFO: 41600 events read in total (3362ms).
[09:32:37.144] <TB2> INFO: Test took 4183ms.
[09:32:37.146] <TB2> INFO: scanning low vcal = 130
[09:32:37.412] <TB2> INFO: Expecting 41600 events.
[09:32:41.348] <TB2> INFO: 41600 events read in total (3380ms).
[09:32:41.349] <TB2> INFO: Test took 4203ms.
[09:32:41.352] <TB2> INFO: scanning low vcal = 140
[09:32:41.616] <TB2> INFO: Expecting 41600 events.
[09:32:45.589] <TB2> INFO: 41600 events read in total (3417ms).
[09:32:45.590] <TB2> INFO: Test took 4238ms.
[09:32:45.593] <TB2> INFO: scanning low vcal = 150
[09:32:45.857] <TB2> INFO: Expecting 41600 events.
[09:32:49.779] <TB2> INFO: 41600 events read in total (3366ms).
[09:32:49.780] <TB2> INFO: Test took 4187ms.
[09:32:49.783] <TB2> INFO: scanning low vcal = 160
[09:32:50.043] <TB2> INFO: Expecting 41600 events.
[09:32:53.967] <TB2> INFO: 41600 events read in total (3369ms).
[09:32:53.968] <TB2> INFO: Test took 4185ms.
[09:32:53.971] <TB2> INFO: scanning low vcal = 170
[09:32:54.232] <TB2> INFO: Expecting 41600 events.
[09:32:58.252] <TB2> INFO: 41600 events read in total (3464ms).
[09:32:58.252] <TB2> INFO: Test took 4281ms.
[09:32:58.256] <TB2> INFO: scanning low vcal = 180
[09:32:58.540] <TB2> INFO: Expecting 41600 events.
[09:33:02.544] <TB2> INFO: 41600 events read in total (3448ms).
[09:33:02.545] <TB2> INFO: Test took 4289ms.
[09:33:02.548] <TB2> INFO: scanning low vcal = 190
[09:33:02.839] <TB2> INFO: Expecting 41600 events.
[09:33:06.834] <TB2> INFO: 41600 events read in total (3439ms).
[09:33:06.834] <TB2> INFO: Test took 4286ms.
[09:33:06.837] <TB2> INFO: scanning low vcal = 200
[09:33:07.100] <TB2> INFO: Expecting 41600 events.
[09:33:11.029] <TB2> INFO: 41600 events read in total (3373ms).
[09:33:11.029] <TB2> INFO: Test took 4192ms.
[09:33:11.032] <TB2> INFO: scanning low vcal = 210
[09:33:11.296] <TB2> INFO: Expecting 41600 events.
[09:33:15.321] <TB2> INFO: 41600 events read in total (3469ms).
[09:33:15.322] <TB2> INFO: Test took 4290ms.
[09:33:15.325] <TB2> INFO: scanning low vcal = 220
[09:33:15.590] <TB2> INFO: Expecting 41600 events.
[09:33:19.588] <TB2> INFO: 41600 events read in total (3442ms).
[09:33:19.589] <TB2> INFO: Test took 4264ms.
[09:33:19.592] <TB2> INFO: scanning low vcal = 230
[09:33:19.909] <TB2> INFO: Expecting 41600 events.
[09:33:23.858] <TB2> INFO: 41600 events read in total (3393ms).
[09:33:23.859] <TB2> INFO: Test took 4267ms.
[09:33:23.861] <TB2> INFO: scanning low vcal = 240
[09:33:24.134] <TB2> INFO: Expecting 41600 events.
[09:33:28.097] <TB2> INFO: 41600 events read in total (3407ms).
[09:33:28.098] <TB2> INFO: Test took 4236ms.
[09:33:28.101] <TB2> INFO: scanning low vcal = 250
[09:33:28.362] <TB2> INFO: Expecting 41600 events.
[09:33:32.278] <TB2> INFO: 41600 events read in total (3360ms).
[09:33:32.279] <TB2> INFO: Test took 4178ms.
[09:33:32.283] <TB2> INFO: scanning high vcal = 30 (= 210 in low range)
[09:33:32.549] <TB2> INFO: Expecting 41600 events.
[09:33:36.494] <TB2> INFO: 41600 events read in total (3389ms).
[09:33:36.494] <TB2> INFO: Test took 4211ms.
[09:33:36.497] <TB2> INFO: scanning high vcal = 50 (= 350 in low range)
[09:33:36.758] <TB2> INFO: Expecting 41600 events.
[09:33:40.690] <TB2> INFO: 41600 events read in total (3376ms).
[09:33:40.691] <TB2> INFO: Test took 4194ms.
[09:33:40.693] <TB2> INFO: scanning high vcal = 70 (= 490 in low range)
[09:33:40.958] <TB2> INFO: Expecting 41600 events.
[09:33:44.981] <TB2> INFO: 41600 events read in total (3467ms).
[09:33:44.982] <TB2> INFO: Test took 4288ms.
[09:33:44.985] <TB2> INFO: scanning high vcal = 90 (= 630 in low range)
[09:33:45.297] <TB2> INFO: Expecting 41600 events.
[09:33:49.342] <TB2> INFO: 41600 events read in total (3488ms).
[09:33:49.343] <TB2> INFO: Test took 4358ms.
[09:33:49.345] <TB2> INFO: scanning high vcal = 200 (= 1400 in low range)
[09:33:49.606] <TB2> INFO: Expecting 41600 events.
[09:33:53.564] <TB2> INFO: 41600 events read in total (3402ms).
[09:33:53.564] <TB2> INFO: Test took 4218ms.
[09:33:53.994] <TB2> INFO: PixTestGainPedestal::measure() done
[09:34:27.018] <TB2> INFO: PixTestGainPedestal::fit() done
[09:34:27.018] <TB2> INFO: non-linearity mean: 0.962 0.955 0.951 0.952 0.951 0.956 0.950 0.958 0.953 0.949 0.949 0.956 0.951 0.955 0.957 0.952
[09:34:27.018] <TB2> INFO: non-linearity RMS: 0.004 0.006 0.005 0.006 0.007 0.005 0.005 0.006 0.007 0.008 0.006 0.007 0.006 0.005 0.005 0.006
[09:34:27.018] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[09:34:27.038] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[09:34:27.058] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[09:34:27.078] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[09:34:27.100] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[09:34:27.119] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[09:34:27.137] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[09:34:27.155] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[09:34:27.173] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[09:34:27.191] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[09:34:27.209] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[09:34:27.227] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[09:34:27.244] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[09:34:27.262] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[09:34:27.280] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[09:34:27.298] <TB2> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[09:34:27.316] <TB2> INFO: PixTestGainPedestal::fullTest() done, duration: 159 seconds
[09:34:27.316] <TB2> INFO: Decoding statistics:
[09:34:27.316] <TB2> INFO: General information:
[09:34:27.316] <TB2> INFO: 16bit words read: 2662400
[09:34:27.316] <TB2> INFO: valid events total: 166400
[09:34:27.316] <TB2> INFO: empty events: 0
[09:34:27.316] <TB2> INFO: valid events with pixels: 166400
[09:34:27.316] <TB2> INFO: valid pixel hits: 665600
[09:34:27.316] <TB2> INFO: Event errors: 0
[09:34:27.316] <TB2> INFO: start marker: 0
[09:34:27.316] <TB2> INFO: stop marker: 0
[09:34:27.316] <TB2> INFO: overflow: 0
[09:34:27.316] <TB2> INFO: invalid 5bit words: 0
[09:34:27.316] <TB2> INFO: invalid XOR eye diagram: 0
[09:34:27.316] <TB2> INFO: frame (failed synchr.): 0
[09:34:27.316] <TB2> INFO: idle data (no TBM trl): 0
[09:34:27.316] <TB2> INFO: no data (only TBM hdr): 0
[09:34:27.316] <TB2> INFO: TBM errors: 0
[09:34:27.316] <TB2> INFO: flawed TBM headers: 0
[09:34:27.316] <TB2> INFO: flawed TBM trailers: 0
[09:34:27.316] <TB2> INFO: event ID mismatches: 0
[09:34:27.316] <TB2> INFO: ROC errors: 0
[09:34:27.316] <TB2> INFO: missing ROC header(s): 0
[09:34:27.316] <TB2> INFO: misplaced readback start: 0
[09:34:27.316] <TB2> INFO: Pixel decoding errors: 0
[09:34:27.316] <TB2> INFO: pixel data incomplete: 0
[09:34:27.316] <TB2> INFO: pixel address: 0
[09:34:27.316] <TB2> INFO: pulse height fill bit: 0
[09:34:27.316] <TB2> INFO: buffer corruption: 0
[09:34:27.324] <TB2> INFO: readReadbackCal: /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C15.dat
[09:34:27.361] <TB2> INFO: ######################################################################
[09:34:27.361] <TB2> INFO: PixTestReadback::doTest()
[09:34:27.361] <TB2> INFO: ######################################################################
[09:34:27.361] <TB2> INFO: ----------------------------------------------------------------------
[09:34:27.361] <TB2> INFO: PixTestReadback::CalibrateVd()
[09:34:27.361] <TB2> INFO: ----------------------------------------------------------------------
[09:34:36.800] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C0.dat
[09:34:36.800] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C1.dat
[09:34:36.800] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C2.dat
[09:34:36.801] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C3.dat
[09:34:36.801] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C4.dat
[09:34:36.801] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C5.dat
[09:34:36.801] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C6.dat
[09:34:36.801] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C7.dat
[09:34:36.801] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C8.dat
[09:34:36.801] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C9.dat
[09:34:36.801] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C10.dat
[09:34:36.802] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C11.dat
[09:34:36.802] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C12.dat
[09:34:36.802] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C13.dat
[09:34:36.802] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C14.dat
[09:34:36.802] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C15.dat
[09:34:36.830] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[09:34:36.830] <TB2> INFO: ----------------------------------------------------------------------
[09:34:36.830] <TB2> INFO: PixTestReadback::CalibrateVa()
[09:34:36.830] <TB2> INFO: ----------------------------------------------------------------------
[09:34:46.354] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C0.dat
[09:34:46.354] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C1.dat
[09:34:46.354] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C2.dat
[09:34:46.354] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C3.dat
[09:34:46.355] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C4.dat
[09:34:46.355] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C5.dat
[09:34:46.355] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C6.dat
[09:34:46.355] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C7.dat
[09:34:46.355] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C8.dat
[09:34:46.355] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C9.dat
[09:34:46.355] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C10.dat
[09:34:46.355] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C11.dat
[09:34:46.355] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C12.dat
[09:34:46.355] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C13.dat
[09:34:46.356] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C14.dat
[09:34:46.356] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C15.dat
[09:34:46.383] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[09:34:46.383] <TB2> INFO: ----------------------------------------------------------------------
[09:34:46.383] <TB2> INFO: PixTestReadback::readbackVbg()
[09:34:46.383] <TB2> INFO: ----------------------------------------------------------------------
[09:34:53.679] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[09:34:53.679] <TB2> INFO: ----------------------------------------------------------------------
[09:34:53.679] <TB2> INFO: PixTestReadback::getCalibratedVbg()
[09:34:53.679] <TB2> INFO: ----------------------------------------------------------------------
[09:34:53.679] <TB2> INFO: Vbg will be calibrated using Vd calibration
[09:34:53.679] <TB2> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 158.6calibrated Vbg = 1.19863 :::*/*/*/*/
[09:34:53.679] <TB2> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 150.5calibrated Vbg = 1.19308 :::*/*/*/*/
[09:34:53.679] <TB2> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 153.5calibrated Vbg = 1.19336 :::*/*/*/*/
[09:34:53.679] <TB2> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 146.3calibrated Vbg = 1.20335 :::*/*/*/*/
[09:34:53.679] <TB2> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 153.2calibrated Vbg = 1.21234 :::*/*/*/*/
[09:34:53.679] <TB2> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 145.3calibrated Vbg = 1.20514 :::*/*/*/*/
[09:34:53.679] <TB2> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 145.9calibrated Vbg = 1.20554 :::*/*/*/*/
[09:34:53.679] <TB2> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 144.6calibrated Vbg = 1.20365 :::*/*/*/*/
[09:34:53.679] <TB2> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 153.1calibrated Vbg = 1.21558 :::*/*/*/*/
[09:34:53.679] <TB2> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 156.3calibrated Vbg = 1.2082 :::*/*/*/*/
[09:34:53.679] <TB2> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 152.7calibrated Vbg = 1.20646 :::*/*/*/*/
[09:34:53.679] <TB2> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 143.1calibrated Vbg = 1.21544 :::*/*/*/*/
[09:34:53.679] <TB2> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 161.7calibrated Vbg = 1.19603 :::*/*/*/*/
[09:34:53.679] <TB2> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 148calibrated Vbg = 1.19368 :::*/*/*/*/
[09:34:53.679] <TB2> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 141.7calibrated Vbg = 1.20526 :::*/*/*/*/
[09:34:53.679] <TB2> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 145.1calibrated Vbg = 1.19483 :::*/*/*/*/
[09:34:53.681] <TB2> INFO: ----------------------------------------------------------------------
[09:34:53.681] <TB2> INFO: PixTestReadback::CalibrateIa()
[09:34:53.681] <TB2> INFO: ----------------------------------------------------------------------
[09:37:28.744] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C0.dat
[09:37:28.744] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C1.dat
[09:37:28.744] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C2.dat
[09:37:28.744] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C3.dat
[09:37:28.744] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C4.dat
[09:37:28.745] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C5.dat
[09:37:28.745] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C6.dat
[09:37:28.746] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C7.dat
[09:37:28.746] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C8.dat
[09:37:28.746] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C9.dat
[09:37:28.746] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C10.dat
[09:37:28.746] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C11.dat
[09:37:28.746] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C12.dat
[09:37:28.747] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C13.dat
[09:37:28.747] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C14.dat
[09:37:28.747] <TB2> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2350_FullQualification_2016-10-03_10h07m_1475482076//000_FulltestPxar_m20//readbackCal_C15.dat
[09:37:28.774] <TB2> INFO: PixTestPattern:: pg_setup set to default.
[09:37:28.775] <TB2> INFO: PixTestReadback::doTest() done
[09:37:28.776] <TB2> INFO: Decoding statistics:
[09:37:28.776] <TB2> INFO: General information:
[09:37:28.776] <TB2> INFO: 16bit words read: 1024
[09:37:28.776] <TB2> INFO: valid events total: 128
[09:37:28.776] <TB2> INFO: empty events: 128
[09:37:28.776] <TB2> INFO: valid events with pixels: 0
[09:37:28.776] <TB2> INFO: valid pixel hits: 0
[09:37:28.776] <TB2> INFO: Event errors: 0
[09:37:28.776] <TB2> INFO: start marker: 0
[09:37:28.776] <TB2> INFO: stop marker: 0
[09:37:28.776] <TB2> INFO: overflow: 0
[09:37:28.776] <TB2> INFO: invalid 5bit words: 0
[09:37:28.776] <TB2> INFO: invalid XOR eye diagram: 0
[09:37:28.776] <TB2> INFO: frame (failed synchr.): 0
[09:37:28.776] <TB2> INFO: idle data (no TBM trl): 0
[09:37:28.776] <TB2> INFO: no data (only TBM hdr): 0
[09:37:28.776] <TB2> INFO: TBM errors: 0
[09:37:28.776] <TB2> INFO: flawed TBM headers: 0
[09:37:28.776] <TB2> INFO: flawed TBM trailers: 0
[09:37:28.776] <TB2> INFO: event ID mismatches: 0
[09:37:28.776] <TB2> INFO: ROC errors: 0
[09:37:28.776] <TB2> INFO: missing ROC header(s): 0
[09:37:28.776] <TB2> INFO: misplaced readback start: 0
[09:37:28.776] <TB2> INFO: Pixel decoding errors: 0
[09:37:28.776] <TB2> INFO: pixel data incomplete: 0
[09:37:28.776] <TB2> INFO: pixel address: 0
[09:37:28.776] <TB2> INFO: pulse height fill bit: 0
[09:37:28.776] <TB2> INFO: buffer corruption: 0
[09:37:28.795] <TB2> INFO: Decoding statistics:
[09:37:28.795] <TB2> INFO: General information:
[09:37:28.795] <TB2> INFO: 16bit words read: 2750346
[09:37:28.795] <TB2> INFO: valid events total: 176768
[09:37:28.795] <TB2> INFO: empty events: 7867
[09:37:28.795] <TB2> INFO: valid events with pixels: 168901
[09:37:28.795] <TB2> INFO: valid pixel hits: 668101
[09:37:28.795] <TB2> INFO: Event errors: 0
[09:37:28.795] <TB2> INFO: start marker: 0
[09:37:28.795] <TB2> INFO: stop marker: 0
[09:37:28.795] <TB2> INFO: overflow: 0
[09:37:28.795] <TB2> INFO: invalid 5bit words: 0
[09:37:28.795] <TB2> INFO: invalid XOR eye diagram: 0
[09:37:28.795] <TB2> INFO: frame (failed synchr.): 0
[09:37:28.795] <TB2> INFO: idle data (no TBM trl): 0
[09:37:28.795] <TB2> INFO: no data (only TBM hdr): 0
[09:37:28.795] <TB2> INFO: TBM errors: 0
[09:37:28.795] <TB2> INFO: flawed TBM headers: 0
[09:37:28.795] <TB2> INFO: flawed TBM trailers: 0
[09:37:28.795] <TB2> INFO: event ID mismatches: 0
[09:37:28.795] <TB2> INFO: ROC errors: 0
[09:37:28.795] <TB2> INFO: missing ROC header(s): 0
[09:37:28.795] <TB2> INFO: misplaced readback start: 0
[09:37:28.795] <TB2> INFO: Pixel decoding errors: 0
[09:37:28.795] <TB2> INFO: pixel data incomplete: 0
[09:37:28.795] <TB2> INFO: pixel address: 0
[09:37:28.795] <TB2> INFO: pulse height fill bit: 0
[09:37:28.795] <TB2> INFO: buffer corruption: 0
[09:37:28.795] <TB2> INFO: enter test to run
[09:37:28.795] <TB2> INFO: test: exit no parameter change
[09:37:28.968] <TB2> QUIET: Connection to board 156 closed.
[09:37:29.048] <TB2> INFO: pXar: this is the end, my friend