[09:00:07.653] <TB0> INFO: *** Welcome to pxar ***
[09:00:07.653] <TB0> INFO: *** Today: 2016/09/23
[09:00:08.163] <TB0> INFO: *** Version: fe94
[09:00:08.163] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C15.dat
[09:00:08.164] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//tbmParameters_C0b.dat
[09:00:08.164] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//defaultMaskFile.dat
[09:00:08.164] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters_C15.dat
[09:00:08.230] <TB0> INFO: clk: 4
[09:00:08.230] <TB0> INFO: ctr: 4
[09:00:08.230] <TB0> INFO: sda: 19
[09:00:08.230] <TB0> INFO: tin: 9
[09:00:08.230] <TB0> INFO: level: 15
[09:00:08.230] <TB0> INFO: triggerdelay: 0
[09:00:08.230] <TB0> QUIET: Instanciating API for pxar v2.7.6+55~gafdbfd9
[09:00:08.230] <TB0> INFO: Log level: INFO
[09:00:08.237] <TB0> INFO: Found DTB DTB_WS6AYH
[09:00:08.248] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[09:00:08.250] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.6
SW version: 4.7
Options:
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[09:00:08.252] <TB0> INFO: RPC call hashes of host and DTB match: 486171790
[09:00:09.751] <TB0> INFO: DUT info:
[09:00:09.751] <TB0> INFO: The DUT currently contains the following objects:
[09:00:09.751] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[09:00:09.751] <TB0> INFO: TBM Core alpha (0): 7 registers set
[09:00:09.751] <TB0> INFO: TBM Core beta (1): 7 registers set
[09:00:09.751] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:00:09.751] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:09.751] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:09.751] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:09.751] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:09.751] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:09.751] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:09.751] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:09.751] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:09.751] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:09.751] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:09.751] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:09.751] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:09.751] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:09.751] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:09.751] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:09.751] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:00:10.152] <TB0> INFO: enter 'restricted' command line mode
[09:00:10.152] <TB0> INFO: enter test to run
[09:00:10.152] <TB0> INFO: test: pretest no parameter change
[09:00:10.152] <TB0> INFO: running: pretest
[09:00:10.156] <TB0> INFO: ----------------------------------------------------------------------
[09:00:10.156] <TB0> INFO: PixTestPretest::programROC()
[09:00:10.156] <TB0> INFO: ----------------------------------------------------------------------
[09:00:28.170] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[09:00:28.170] <TB0> INFO: IA differences per ROC: 18.5 18.5 20.1 18.5 17.7 20.9 18.5 18.5 18.5 18.5 19.3 18.5 18.5 20.9 20.9 20.9
[09:00:28.278] <TB0> INFO: enter test to run
[09:00:28.278] <TB0> INFO: test: pretest no parameter change
[09:00:28.278] <TB0> INFO: running: pretest
[09:00:28.280] <TB0> INFO: ----------------------------------------------------------------------
[09:00:28.280] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[09:00:28.280] <TB0> INFO: ----------------------------------------------------------------------
[09:00:34.767] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 379.4 mA = 23.7125 mA/ROC
[09:00:34.767] <TB0> INFO: i(loss) [mA/ROC]: 19.3 18.5 18.5 19.3 19.3 18.5 19.3 19.3 19.3 18.5 18.5 18.5 19.3 18.5 18.5 18.5
[09:00:34.798] <TB0> INFO: enter test to run
[09:00:34.798] <TB0> INFO: test: pretest no parameter change
[09:00:34.798] <TB0> INFO: running: pretest
[09:00:34.798] <TB0> INFO: ----------------------------------------------------------------------
[09:00:34.798] <TB0> INFO: PixTestPretest::findTiming()
[09:00:34.798] <TB0> INFO: ----------------------------------------------------------------------
[09:00:34.799] <TB0> INFO: PixTestCmd::init()

[09:02:03.371] <TB0> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[09:02:03.371] <TB0> INFO: (success/tries = 100/100), width = 4
[09:02:03.372] <TB0> INFO: enter test to run
[09:02:03.372] <TB0> INFO: test: pretest no parameter change
[09:02:03.372] <TB0> INFO: running: pretest
[09:02:03.373] <TB0> INFO: ----------------------------------------------------------------------
[09:02:03.373] <TB0> INFO: PixTestPretest::findWorkingPixel()
[09:02:03.373] <TB0> INFO: ----------------------------------------------------------------------
[09:02:03.468] <TB0> INFO: Expecting 231680 events.


[09:02:12.690] <TB0> INFO: 231680 events read in total (8666ms).
[09:02:12.695] <TB0> INFO: Test took 9316ms.
[09:02:12.896] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[09:02:12.955] <TB0> INFO: enter test to run
[09:02:12.955] <TB0> INFO: test: pretest no parameter change
[09:02:12.955] <TB0> INFO: running: pretest
[09:02:12.956] <TB0> INFO: ----------------------------------------------------------------------
[09:02:12.956] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[09:02:12.956] <TB0> INFO: ----------------------------------------------------------------------
[09:02:13.049] <TB0> INFO: Expecting 231680 events.
[09:02:22.290] <TB0> INFO: 231680 events read in total (8685ms).
[09:02:22.295] <TB0> INFO: Test took 9335ms.
[09:02:22.508] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[09:02:22.508] <TB0> INFO: CalDel: 149 146 158 149 152 141 133 141 134 131 130 142 140 134 148 129
[09:02:22.508] <TB0> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[09:02:22.549] <TB0> INFO: enter test to run
[09:02:22.549] <TB0> INFO: test: pretest no parameter change
[09:02:22.549] <TB0> INFO: running: pretest
[09:02:22.549] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C0.dat
[09:02:22.570] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C1.dat
[09:02:22.570] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C2.dat
[09:02:22.570] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C3.dat
[09:02:22.570] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C4.dat
[09:02:22.570] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C5.dat
[09:02:22.570] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C6.dat
[09:02:22.571] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C7.dat
[09:02:22.571] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C8.dat
[09:02:22.571] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C9.dat
[09:02:22.571] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C10.dat
[09:02:22.571] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C11.dat
[09:02:22.571] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C12.dat
[09:02:22.571] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C13.dat
[09:02:22.571] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C14.dat
[09:02:22.571] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters_C15.dat
[09:02:22.572] <TB0> INFO: enter test to run
[09:02:22.572] <TB0> INFO: test: fulltest no parameter change
[09:02:22.572] <TB0> INFO: running: fulltest
[09:02:22.572] <TB0> INFO: ######################################################################
[09:02:22.572] <TB0> INFO: PixTestFullTest::doTest()
[09:02:22.572] <TB0> INFO: ######################################################################
[09:02:22.573] <TB0> INFO: ######################################################################
[09:02:22.573] <TB0> INFO: PixTestAlive::doTest()
[09:02:22.573] <TB0> INFO: ######################################################################
[09:02:22.574] <TB0> INFO: ----------------------------------------------------------------------
[09:02:22.574] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:02:22.574] <TB0> INFO: ----------------------------------------------------------------------
[09:02:22.853] <TB0> INFO: Expecting 41600 events.
[09:02:26.288] <TB0> INFO: 41600 events read in total (2879ms).
[09:02:26.289] <TB0> INFO: Test took 3713ms.
[09:02:26.502] <TB0> INFO: PixTestAlive::aliveTest() done
[09:02:26.502] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:02:26.503] <TB0> INFO: ----------------------------------------------------------------------
[09:02:26.503] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:02:26.503] <TB0> INFO: ----------------------------------------------------------------------
[09:02:26.778] <TB0> INFO: Expecting 41600 events.
[09:02:29.474] <TB0> INFO: 41600 events read in total (2139ms).
[09:02:29.474] <TB0> INFO: Test took 2970ms.
[09:02:29.475] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[09:02:29.711] <TB0> INFO: PixTestAlive::maskTest() done
[09:02:29.711] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:02:29.712] <TB0> INFO: ----------------------------------------------------------------------
[09:02:29.712] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:02:29.712] <TB0> INFO: ----------------------------------------------------------------------
[09:02:29.981] <TB0> INFO: Expecting 41600 events.
[09:02:33.447] <TB0> INFO: 41600 events read in total (2910ms).
[09:02:33.448] <TB0> INFO: Test took 3734ms.
[09:02:33.673] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[09:02:33.673] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[09:02:33.673] <TB0> INFO: PixTestAlive::doTest() done, duration: 11 seconds
[09:02:33.673] <TB0> INFO: Decoding statistics:
[09:02:33.673] <TB0> INFO: General information:
[09:02:33.673] <TB0> INFO: 16bit words read: 0
[09:02:33.673] <TB0> INFO: valid events total: 0
[09:02:33.673] <TB0> INFO: empty events: 0
[09:02:33.673] <TB0> INFO: valid events with pixels: 0
[09:02:33.673] <TB0> INFO: valid pixel hits: 0
[09:02:33.673] <TB0> INFO: Event errors: 0
[09:02:33.673] <TB0> INFO: start marker: 0
[09:02:33.673] <TB0> INFO: stop marker: 0
[09:02:33.673] <TB0> INFO: overflow: 0
[09:02:33.673] <TB0> INFO: invalid 5bit words: 0
[09:02:33.673] <TB0> INFO: invalid XOR eye diagram: 0
[09:02:33.673] <TB0> INFO: frame (failed synchr.): 0
[09:02:33.673] <TB0> INFO: idle data (no TBM trl): 0
[09:02:33.673] <TB0> INFO: no data (only TBM hdr): 0
[09:02:33.674] <TB0> INFO: TBM errors: 0
[09:02:33.674] <TB0> INFO: flawed TBM headers: 0
[09:02:33.674] <TB0> INFO: flawed TBM trailers: 0
[09:02:33.674] <TB0> INFO: event ID mismatches: 0
[09:02:33.674] <TB0> INFO: ROC errors: 0
[09:02:33.674] <TB0> INFO: missing ROC header(s): 0
[09:02:33.674] <TB0> INFO: misplaced readback start: 0
[09:02:33.674] <TB0> INFO: Pixel decoding errors: 0
[09:02:33.674] <TB0> INFO: pixel data incomplete: 0
[09:02:33.674] <TB0> INFO: pixel address: 0
[09:02:33.674] <TB0> INFO: pulse height fill bit: 0
[09:02:33.674] <TB0> INFO: buffer corruption: 0
[09:02:33.685] <TB0> INFO: ######################################################################
[09:02:33.686] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[09:02:33.686] <TB0> INFO: ######################################################################
[09:02:33.688] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[09:02:33.803] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:02:33.803] <TB0> INFO: run 1 of 1
[09:02:34.076] <TB0> INFO: Expecting 3120000 events.
[09:03:11.541] <TB0> INFO: 866720 events read in total (36909ms).
[09:03:47.685] <TB0> INFO: 1717080 events read in total (73053ms).
[09:04:24.049] <TB0> INFO: 2578255 events read in total (109417ms).
[09:04:46.814] <TB0> INFO: 3120000 events read in total (132182ms).
[09:04:46.886] <TB0> INFO: Test took 133083ms.
[09:05:12.007] <TB0> INFO: PixTestBBMap::doTest() done, duration: 158 seconds
[09:05:12.007] <TB0> INFO: number of dead bumps (per ROC): 0 0 0 0 0 0 0 0 0 0 0 1 0 1 0 0
[09:05:12.007] <TB0> INFO: separation cut (per ROC): 81 76 97 84 82 89 85 82 78 90 96 82 89 95 84 84
[09:05:12.007] <TB0> INFO: Decoding statistics:
[09:05:12.007] <TB0> INFO: General information:
[09:05:12.007] <TB0> INFO: 16bit words read: 0
[09:05:12.007] <TB0> INFO: valid events total: 0
[09:05:12.007] <TB0> INFO: empty events: 0
[09:05:12.008] <TB0> INFO: valid events with pixels: 0
[09:05:12.008] <TB0> INFO: valid pixel hits: 0
[09:05:12.008] <TB0> INFO: Event errors: 0
[09:05:12.008] <TB0> INFO: start marker: 0
[09:05:12.008] <TB0> INFO: stop marker: 0
[09:05:12.008] <TB0> INFO: overflow: 0
[09:05:12.008] <TB0> INFO: invalid 5bit words: 0
[09:05:12.008] <TB0> INFO: invalid XOR eye diagram: 0
[09:05:12.008] <TB0> INFO: frame (failed synchr.): 0
[09:05:12.008] <TB0> INFO: idle data (no TBM trl): 0
[09:05:12.008] <TB0> INFO: no data (only TBM hdr): 0
[09:05:12.008] <TB0> INFO: TBM errors: 0
[09:05:12.008] <TB0> INFO: flawed TBM headers: 0
[09:05:12.008] <TB0> INFO: flawed TBM trailers: 0
[09:05:12.008] <TB0> INFO: event ID mismatches: 0
[09:05:12.008] <TB0> INFO: ROC errors: 0
[09:05:12.008] <TB0> INFO: missing ROC header(s): 0
[09:05:12.008] <TB0> INFO: misplaced readback start: 0
[09:05:12.008] <TB0> INFO: Pixel decoding errors: 0
[09:05:12.008] <TB0> INFO: pixel data incomplete: 0
[09:05:12.008] <TB0> INFO: pixel address: 0
[09:05:12.008] <TB0> INFO: pulse height fill bit: 0
[09:05:12.008] <TB0> INFO: buffer corruption: 0
[09:05:12.090] <TB0> INFO: ######################################################################
[09:05:12.090] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:05:12.090] <TB0> INFO: ######################################################################
[09:05:12.090] <TB0> INFO: ----------------------------------------------------------------------
[09:05:12.090] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[09:05:12.090] <TB0> INFO: ----------------------------------------------------------------------
[09:05:12.090] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 200 (-1/-1) hits flags = 528 (plus default)
[09:05:12.099] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:05:12.099] <TB0> INFO: run 1 of 1
[09:05:12.396] <TB0> INFO: Expecting 36608000 events.
[09:05:41.582] <TB0> INFO: 804650 events read in total (28630ms).
[09:06:09.279] <TB0> INFO: 1597100 events read in total (56327ms).
[09:06:39.856] <TB0> INFO: 2389850 events read in total (86904ms).
[09:07:09.400] <TB0> INFO: 3181350 events read in total (116448ms).
[09:07:38.151] <TB0> INFO: 3973650 events read in total (145199ms).
[09:08:06.314] <TB0> INFO: 4766400 events read in total (173362ms).
[09:08:34.208] <TB0> INFO: 5558500 events read in total (201256ms).
[09:09:02.066] <TB0> INFO: 6351200 events read in total (229114ms).
[09:09:29.713] <TB0> INFO: 7141150 events read in total (256761ms).
[09:09:57.058] <TB0> INFO: 7931800 events read in total (284106ms).
[09:10:24.724] <TB0> INFO: 8723250 events read in total (311772ms).
[09:10:52.004] <TB0> INFO: 9512550 events read in total (339052ms).
[09:11:19.595] <TB0> INFO: 10302950 events read in total (366643ms).
[09:11:47.447] <TB0> INFO: 11093300 events read in total (394495ms).
[09:12:15.269] <TB0> INFO: 11883750 events read in total (422317ms).
[09:12:43.229] <TB0> INFO: 12675850 events read in total (450277ms).
[09:13:11.334] <TB0> INFO: 13468300 events read in total (478382ms).
[09:13:39.261] <TB0> INFO: 14256800 events read in total (506309ms).
[09:14:07.652] <TB0> INFO: 15047650 events read in total (534700ms).
[09:14:35.613] <TB0> INFO: 15836900 events read in total (562661ms).
[09:15:03.411] <TB0> INFO: 16626950 events read in total (590459ms).
[09:15:31.246] <TB0> INFO: 17417200 events read in total (618294ms).
[09:15:59.008] <TB0> INFO: 18204950 events read in total (646056ms).
[09:16:27.025] <TB0> INFO: 18989850 events read in total (674073ms).
[09:16:55.234] <TB0> INFO: 19773550 events read in total (702282ms).
[09:17:22.870] <TB0> INFO: 20558300 events read in total (729918ms).
[09:17:50.692] <TB0> INFO: 21343000 events read in total (757740ms).
[09:18:18.741] <TB0> INFO: 22126600 events read in total (785789ms).
[09:18:46.650] <TB0> INFO: 22910100 events read in total (813698ms).
[09:19:14.685] <TB0> INFO: 23693950 events read in total (841733ms).
[09:19:42.292] <TB0> INFO: 24477150 events read in total (869340ms).
[09:20:10.264] <TB0> INFO: 25260550 events read in total (897312ms).
[09:20:38.562] <TB0> INFO: 26045450 events read in total (925610ms).
[09:21:06.327] <TB0> INFO: 26827550 events read in total (953375ms).
[09:21:34.262] <TB0> INFO: 27610300 events read in total (981310ms).
[09:22:02.315] <TB0> INFO: 28392550 events read in total (1009363ms).
[09:22:29.937] <TB0> INFO: 29176450 events read in total (1036985ms).
[09:22:58.373] <TB0> INFO: 29959800 events read in total (1065421ms).
[09:23:25.829] <TB0> INFO: 30742500 events read in total (1092877ms).
[09:23:53.663] <TB0> INFO: 31525500 events read in total (1120711ms).
[09:24:21.520] <TB0> INFO: 32309200 events read in total (1148568ms).
[09:24:49.694] <TB0> INFO: 33094750 events read in total (1176742ms).
[09:25:17.850] <TB0> INFO: 33878600 events read in total (1204898ms).
[09:25:45.768] <TB0> INFO: 34664650 events read in total (1232816ms).
[09:26:14.076] <TB0> INFO: 35450000 events read in total (1261124ms).
[09:26:41.958] <TB0> INFO: 36243600 events read in total (1289006ms).
[09:26:55.112] <TB0> INFO: 36608000 events read in total (1302160ms).
[09:26:55.169] <TB0> INFO: Test took 1303070ms.
[09:26:55.620] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:26:57.614] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:26:59.446] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:27:01.175] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:27:03.234] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:27:05.286] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:27:06.988] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:27:08.720] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:27:10.438] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:27:12.138] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:27:14.044] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:27:15.955] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:27:17.736] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:27:19.776] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:27:21.818] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:27:23.928] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[09:27:26.010] <TB0> INFO: PixTestScurves::scurves() done
[09:27:26.010] <TB0> INFO: Vcal mean: 79.97 71.62 87.67 78.22 80.33 79.86 84.86 87.57 88.00 86.43 90.15 73.30 85.45 90.05 77.30 83.07
[09:27:26.010] <TB0> INFO: Vcal RMS: 4.39 4.16 5.49 3.66 4.29 4.61 5.17 6.82 5.60 4.76 5.27 4.91 4.62 4.99 3.75 5.10
[09:27:26.010] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1333 seconds
[09:27:26.010] <TB0> INFO: Decoding statistics:
[09:27:26.010] <TB0> INFO: General information:
[09:27:26.010] <TB0> INFO: 16bit words read: 0
[09:27:26.010] <TB0> INFO: valid events total: 0
[09:27:26.010] <TB0> INFO: empty events: 0
[09:27:26.010] <TB0> INFO: valid events with pixels: 0
[09:27:26.011] <TB0> INFO: valid pixel hits: 0
[09:27:26.011] <TB0> INFO: Event errors: 0
[09:27:26.011] <TB0> INFO: start marker: 0
[09:27:26.011] <TB0> INFO: stop marker: 0
[09:27:26.011] <TB0> INFO: overflow: 0
[09:27:26.011] <TB0> INFO: invalid 5bit words: 0
[09:27:26.011] <TB0> INFO: invalid XOR eye diagram: 0
[09:27:26.011] <TB0> INFO: frame (failed synchr.): 0
[09:27:26.011] <TB0> INFO: idle data (no TBM trl): 0
[09:27:26.011] <TB0> INFO: no data (only TBM hdr): 0
[09:27:26.011] <TB0> INFO: TBM errors: 0
[09:27:26.011] <TB0> INFO: flawed TBM headers: 0
[09:27:26.011] <TB0> INFO: flawed TBM trailers: 0
[09:27:26.011] <TB0> INFO: event ID mismatches: 0
[09:27:26.011] <TB0> INFO: ROC errors: 0
[09:27:26.011] <TB0> INFO: missing ROC header(s): 0
[09:27:26.011] <TB0> INFO: misplaced readback start: 0
[09:27:26.011] <TB0> INFO: Pixel decoding errors: 0
[09:27:26.011] <TB0> INFO: pixel data incomplete: 0
[09:27:26.011] <TB0> INFO: pixel address: 0
[09:27:26.011] <TB0> INFO: pulse height fill bit: 0
[09:27:26.011] <TB0> INFO: buffer corruption: 0
[09:27:26.113] <TB0> INFO: ######################################################################
[09:27:26.113] <TB0> INFO: PixTestTrim::doTest()
[09:27:26.113] <TB0> INFO: ######################################################################
[09:27:26.114] <TB0> INFO: ----------------------------------------------------------------------
[09:27:26.114] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[09:27:26.114] <TB0> INFO: ----------------------------------------------------------------------
[09:27:26.235] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[09:27:26.235] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:27:26.244] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:27:26.244] <TB0> INFO: run 1 of 1
[09:27:26.507] <TB0> INFO: Expecting 6281600 events.
[09:28:03.814] <TB0> INFO: 1093720 events read in total (36747ms).
[09:28:40.416] <TB0> INFO: 2180100 events read in total (73349ms).
[09:29:16.707] <TB0> INFO: 3259550 events read in total (109640ms).
[09:29:53.104] <TB0> INFO: 4345140 events read in total (146037ms).
[09:30:29.128] <TB0> INFO: 5433500 events read in total (182061ms).
[09:30:57.027] <TB0> INFO: 6281600 events read in total (209960ms).
[09:30:57.084] <TB0> INFO: Test took 210840ms.
[09:31:17.791] <TB0> INFO: ROC 0 VthrComp = 85
[09:31:17.791] <TB0> INFO: ROC 1 VthrComp = 80
[09:31:17.791] <TB0> INFO: ROC 2 VthrComp = 97
[09:31:17.791] <TB0> INFO: ROC 3 VthrComp = 86
[09:31:17.791] <TB0> INFO: ROC 4 VthrComp = 87
[09:31:17.792] <TB0> INFO: ROC 5 VthrComp = 89
[09:31:17.792] <TB0> INFO: ROC 6 VthrComp = 90
[09:31:17.792] <TB0> INFO: ROC 7 VthrComp = 90
[09:31:17.792] <TB0> INFO: ROC 8 VthrComp = 92
[09:31:17.792] <TB0> INFO: ROC 9 VthrComp = 96
[09:31:17.792] <TB0> INFO: ROC 10 VthrComp = 97
[09:31:17.792] <TB0> INFO: ROC 11 VthrComp = 80
[09:31:17.792] <TB0> INFO: ROC 12 VthrComp = 93
[09:31:17.792] <TB0> INFO: ROC 13 VthrComp = 97
[09:31:17.792] <TB0> INFO: ROC 14 VthrComp = 87
[09:31:17.793] <TB0> INFO: ROC 15 VthrComp = 90
[09:31:17.793] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[09:31:17.793] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[09:31:17.802] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:31:17.802] <TB0> INFO: run 1 of 1
[09:31:18.077] <TB0> INFO: Expecting 6281600 events.
[09:31:50.861] <TB0> INFO: 754640 events read in total (32228ms).
[09:32:21.556] <TB0> INFO: 1507690 events read in total (62923ms).
[09:32:52.547] <TB0> INFO: 2261240 events read in total (93914ms).
[09:33:23.668] <TB0> INFO: 3015470 events read in total (125035ms).
[09:33:54.981] <TB0> INFO: 3764910 events read in total (156348ms).
[09:34:25.803] <TB0> INFO: 4511820 events read in total (187170ms).
[09:34:56.136] <TB0> INFO: 5257760 events read in total (217503ms).
[09:35:26.887] <TB0> INFO: 6004150 events read in total (248254ms).
[09:35:38.397] <TB0> INFO: 6281600 events read in total (259764ms).
[09:35:38.455] <TB0> INFO: Test took 260653ms.
[09:36:03.085] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 56.6988 for pixel 30/75 mean/min/max = 44.1658/31.6032/56.7284
[09:36:03.086] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 54.0171 for pixel 2/1 mean/min/max = 43.3501/32.5437/54.1564
[09:36:03.086] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 56.6298 for pixel 18/9 mean/min/max = 44.3303/31.8645/56.7961
[09:36:03.086] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 54.5535 for pixel 51/72 mean/min/max = 43.3369/32.1006/54.5733
[09:36:03.086] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 56.8086 for pixel 15/41 mean/min/max = 44.6737/32.4104/56.9369
[09:36:03.087] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 57.2455 for pixel 4/3 mean/min/max = 45.1244/32.9004/57.3485
[09:36:03.087] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 58.4477 for pixel 2/65 mean/min/max = 45.4999/32.2441/58.7558
[09:36:03.087] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 62.5783 for pixel 1/75 mean/min/max = 47.0156/31.3478/62.6833
[09:36:03.088] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 58.8113 for pixel 10/73 mean/min/max = 45.526/32.0575/58.9944
[09:36:03.088] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 56.0713 for pixel 51/7 mean/min/max = 43.954/31.7612/56.1467
[09:36:03.088] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 58.2137 for pixel 11/26 mean/min/max = 45.1698/31.8803/58.4593
[09:36:03.088] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 57.8602 for pixel 6/68 mean/min/max = 45.2562/32.4786/58.0338
[09:36:03.089] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 56.9553 for pixel 25/9 mean/min/max = 44.5743/32.1819/56.9667
[09:36:03.089] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 57.0393 for pixel 7/76 mean/min/max = 44.5809/32.0923/57.0695
[09:36:03.089] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 54.0359 for pixel 6/53 mean/min/max = 43.115/32.0187/54.2113
[09:36:03.089] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 60.5697 for pixel 0/2 mean/min/max = 46.3788/32.0745/60.6832
[09:36:03.090] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[09:36:03.194] <TB0> INFO: Expecting 514560 events.
[09:36:14.305] <TB0> INFO: 514560 events read in total (10554ms).
[09:36:14.316] <TB0> INFO: Expecting 514560 events.
[09:36:25.675] <TB0> INFO: 514560 events read in total (10958ms).
[09:36:25.689] <TB0> INFO: Expecting 514560 events.
[09:36:37.100] <TB0> INFO: 514560 events read in total (11009ms).
[09:36:37.112] <TB0> INFO: Expecting 514560 events.
[09:36:47.940] <TB0> INFO: 514560 events read in total (10426ms).
[09:36:47.955] <TB0> INFO: Expecting 514560 events.
[09:36:58.852] <TB0> INFO: 514560 events read in total (10495ms).
[09:36:58.876] <TB0> INFO: Expecting 514560 events.
[09:37:09.656] <TB0> INFO: 514560 events read in total (10379ms).
[09:37:09.676] <TB0> INFO: Expecting 514560 events.
[09:37:20.540] <TB0> INFO: 514560 events read in total (10462ms).
[09:37:20.567] <TB0> INFO: Expecting 514560 events.
[09:37:31.369] <TB0> INFO: 514560 events read in total (10401ms).
[09:37:31.394] <TB0> INFO: Expecting 514560 events.
[09:37:42.232] <TB0> INFO: 514560 events read in total (10437ms).
[09:37:42.260] <TB0> INFO: Expecting 514560 events.
[09:37:52.971] <TB0> INFO: 514560 events read in total (10309ms).
[09:37:53.002] <TB0> INFO: Expecting 514560 events.
[09:38:03.783] <TB0> INFO: 514560 events read in total (10379ms).
[09:38:03.815] <TB0> INFO: Expecting 514560 events.
[09:38:14.505] <TB0> INFO: 514560 events read in total (10288ms).
[09:38:14.540] <TB0> INFO: Expecting 514560 events.
[09:38:25.165] <TB0> INFO: 514560 events read in total (10223ms).
[09:38:25.207] <TB0> INFO: Expecting 514560 events.
[09:38:35.831] <TB0> INFO: 514560 events read in total (10222ms).
[09:38:35.877] <TB0> INFO: Expecting 514560 events.
[09:38:46.517] <TB0> INFO: 514560 events read in total (10238ms).
[09:38:46.567] <TB0> INFO: Expecting 514560 events.
[09:38:57.270] <TB0> INFO: 514560 events read in total (10301ms).
[09:38:57.316] <TB0> INFO: Test took 174226ms.
[09:38:58.409] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:38:58.417] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:38:58.417] <TB0> INFO: run 1 of 1
[09:38:58.677] <TB0> INFO: Expecting 6281600 events.
[09:39:29.685] <TB0> INFO: 744380 events read in total (30451ms).
[09:40:00.046] <TB0> INFO: 1487340 events read in total (60812ms).
[09:40:31.233] <TB0> INFO: 2230820 events read in total (91999ms).
[09:41:01.788] <TB0> INFO: 2975130 events read in total (122554ms).
[09:41:32.187] <TB0> INFO: 3714130 events read in total (152953ms).
[09:42:02.510] <TB0> INFO: 4450030 events read in total (183276ms).
[09:42:32.900] <TB0> INFO: 5184390 events read in total (213666ms).
[09:43:03.040] <TB0> INFO: 5917310 events read in total (243806ms).
[09:43:18.313] <TB0> INFO: 6281600 events read in total (259079ms).
[09:43:18.373] <TB0> INFO: Test took 259956ms.
[09:43:42.309] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 1.500000 .. 49.245724
[09:43:42.574] <TB0> INFO: Expecting 208000 events.
[09:43:52.472] <TB0> INFO: 208000 events read in total (9342ms).
[09:43:52.473] <TB0> INFO: Test took 10162ms.
[09:43:52.563] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 1 .. 59 (-1/-1) hits flags = 528 (plus default)
[09:43:52.571] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:43:52.571] <TB0> INFO: run 1 of 1
[09:43:52.832] <TB0> INFO: Expecting 2454400 events.
[09:44:27.807] <TB0> INFO: 951040 events read in total (34419ms).
[09:45:01.373] <TB0> INFO: 1894350 events read in total (67985ms).
[09:45:21.943] <TB0> INFO: 2454400 events read in total (88556ms).
[09:45:21.966] <TB0> INFO: Test took 89395ms.
[09:45:37.044] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 21.703120 .. 44.687473
[09:45:37.310] <TB0> INFO: Expecting 208000 events.
[09:45:47.399] <TB0> INFO: 208000 events read in total (9533ms).
[09:45:47.400] <TB0> INFO: Test took 10355ms.
[09:45:47.511] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 11 .. 54 (-1/-1) hits flags = 528 (plus default)
[09:45:47.523] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:45:47.523] <TB0> INFO: run 1 of 1
[09:45:47.815] <TB0> INFO: Expecting 1830400 events.
[09:46:21.709] <TB0> INFO: 928560 events read in total (33338ms).
[09:46:54.143] <TB0> INFO: 1830400 events read in total (65772ms).
[09:46:54.171] <TB0> INFO: Test took 66649ms.
[09:47:07.492] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 22.872905 .. 40.558647
[09:47:07.756] <TB0> INFO: Expecting 208000 events.
[09:47:17.669] <TB0> INFO: 208000 events read in total (9357ms).
[09:47:17.670] <TB0> INFO: Test took 10176ms.
[09:47:17.755] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 12 .. 50 (-1/-1) hits flags = 528 (plus default)
[09:47:17.765] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:47:17.765] <TB0> INFO: run 1 of 1
[09:47:18.026] <TB0> INFO: Expecting 1622400 events.
[09:47:53.193] <TB0> INFO: 961410 events read in total (34611ms).
[09:48:16.837] <TB0> INFO: 1622400 events read in total (58255ms).
[09:48:16.852] <TB0> INFO: Test took 59088ms.
[09:48:30.355] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 20.873521 .. 40.558647
[09:48:30.621] <TB0> INFO: Expecting 208000 events.
[09:48:40.350] <TB0> INFO: 208000 events read in total (9173ms).
[09:48:40.352] <TB0> INFO: Test took 9995ms.
[09:48:40.439] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 10 .. 50 (-1/-1) hits flags = 528 (plus default)
[09:48:40.448] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:48:40.448] <TB0> INFO: run 1 of 1
[09:48:40.713] <TB0> INFO: Expecting 1705600 events.
[09:49:16.420] <TB0> INFO: 975080 events read in total (35151ms).
[09:49:41.903] <TB0> INFO: 1705600 events read in total (60634ms).
[09:49:41.921] <TB0> INFO: Test took 61473ms.
[09:49:55.015] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[09:49:55.015] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[09:49:55.024] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[09:49:55.024] <TB0> INFO: run 1 of 1
[09:49:55.312] <TB0> INFO: Expecting 1705600 events.
[09:50:29.369] <TB0> INFO: 893870 events read in total (33501ms).
[09:50:59.525] <TB0> INFO: 1705600 events read in total (63657ms).
[09:50:59.542] <TB0> INFO: Test took 64518ms.
[09:51:12.774] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C0.dat
[09:51:12.774] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C1.dat
[09:51:12.774] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C2.dat
[09:51:12.774] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C3.dat
[09:51:12.774] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C4.dat
[09:51:12.775] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C5.dat
[09:51:12.775] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C6.dat
[09:51:12.775] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C7.dat
[09:51:12.775] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C8.dat
[09:51:12.775] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C9.dat
[09:51:12.775] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C10.dat
[09:51:12.775] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C11.dat
[09:51:12.775] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C12.dat
[09:51:12.775] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C13.dat
[09:51:12.775] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C14.dat
[09:51:12.776] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C15.dat
[09:51:12.788] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C0.dat
[09:51:12.795] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C1.dat
[09:51:12.803] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C2.dat
[09:51:12.812] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C3.dat
[09:51:12.819] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C4.dat
[09:51:12.827] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C5.dat
[09:51:12.835] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C6.dat
[09:51:12.843] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C7.dat
[09:51:12.850] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C8.dat
[09:51:12.856] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C9.dat
[09:51:12.865] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C10.dat
[09:51:12.873] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C11.dat
[09:51:12.880] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C12.dat
[09:51:12.886] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C13.dat
[09:51:12.893] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C14.dat
[09:51:12.899] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//trimParameters35_C15.dat
[09:51:12.905] <TB0> INFO: PixTestTrim::trimTest() done
[09:51:12.905] <TB0> INFO: vtrim: 100 94 110 93 92 115 103 107 106 98 116 103 99 97 92 104
[09:51:12.905] <TB0> INFO: vthrcomp: 85 80 97 86 87 89 90 90 92 96 97 80 93 97 87 90
[09:51:12.905] <TB0> INFO: vcal mean: 34.97 34.98 34.97 34.96 34.94 34.98 34.97 34.93 34.96 34.94 35.03 34.99 34.98 34.95 34.97 34.99
[09:51:12.905] <TB0> INFO: vcal RMS: 0.82 0.77 0.82 0.80 0.82 0.76 0.83 0.89 0.87 0.80 0.84 0.79 0.82 0.84 0.76 0.80
[09:51:12.905] <TB0> INFO: bits mean: 10.04 10.16 9.84 10.00 9.42 9.54 9.45 9.23 9.56 9.74 10.01 9.48 9.80 9.66 10.14 8.93
[09:51:12.905] <TB0> INFO: bits RMS: 2.57 2.40 2.63 2.57 2.74 2.55 2.67 2.77 2.65 2.70 2.52 2.64 2.53 2.65 2.49 2.84
[09:51:12.912] <TB0> INFO: ----------------------------------------------------------------------
[09:51:12.912] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[09:51:12.912] <TB0> INFO: ----------------------------------------------------------------------
[09:51:12.914] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[09:51:12.922] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:51:12.922] <TB0> INFO: run 1 of 1
[09:51:13.192] <TB0> INFO: Expecting 4160000 events.
[09:51:51.273] <TB0> INFO: 905160 events read in total (37525ms).
[09:52:28.073] <TB0> INFO: 1805310 events read in total (74325ms).
[09:53:04.295] <TB0> INFO: 2698495 events read in total (110547ms).
[09:53:40.912] <TB0> INFO: 3589440 events read in total (147164ms).
[09:54:04.312] <TB0> INFO: 4160000 events read in total (170564ms).
[09:54:04.380] <TB0> INFO: Test took 171458ms.
[09:54:31.988] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 158 (-1/-1) hits flags = 528 (plus default)
[09:54:31.997] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:54:31.997] <TB0> INFO: run 1 of 1
[09:54:32.258] <TB0> INFO: Expecting 3307200 events.
[09:55:13.283] <TB0> INFO: 956510 events read in total (40469ms).
[09:55:50.740] <TB0> INFO: 1903365 events read in total (77926ms).
[09:56:28.054] <TB0> INFO: 2842070 events read in total (115240ms).
[09:56:46.858] <TB0> INFO: 3307200 events read in total (134044ms).
[09:56:46.905] <TB0> INFO: Test took 134908ms.
[09:57:11.676] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 147 (-1/-1) hits flags = 528 (plus default)
[09:57:11.685] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:57:11.685] <TB0> INFO: run 1 of 1
[09:57:11.948] <TB0> INFO: Expecting 3078400 events.
[09:57:51.356] <TB0> INFO: 987240 events read in total (38852ms).
[09:58:29.720] <TB0> INFO: 1962615 events read in total (77216ms).
[09:59:07.671] <TB0> INFO: 2931220 events read in total (115167ms).
[09:59:13.778] <TB0> INFO: 3078400 events read in total (121274ms).
[09:59:13.839] <TB0> INFO: Test took 122154ms.
[09:59:38.298] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[09:59:38.307] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[09:59:38.307] <TB0> INFO: run 1 of 1
[09:59:38.569] <TB0> INFO: Expecting 3140800 events.
[10:00:18.022] <TB0> INFO: 977570 events read in total (38897ms).
[10:00:56.366] <TB0> INFO: 1943545 events read in total (77241ms).
[10:01:34.849] <TB0> INFO: 2902630 events read in total (115724ms).
[10:01:44.643] <TB0> INFO: 3140800 events read in total (125518ms).
[10:01:44.689] <TB0> INFO: Test took 126382ms.
[10:02:10.211] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 148 (-1/-1) hits flags = 528 (plus default)
[10:02:10.219] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[10:02:10.219] <TB0> INFO: run 1 of 1
[10:02:10.485] <TB0> INFO: Expecting 3099200 events.
[10:02:50.127] <TB0> INFO: 983165 events read in total (39086ms).
[10:03:28.606] <TB0> INFO: 1953965 events read in total (77565ms).
[10:04:07.024] <TB0> INFO: 2918170 events read in total (115983ms).
[10:04:14.385] <TB0> INFO: 3099200 events read in total (123344ms).
[10:04:14.431] <TB0> INFO: Test took 124212ms.
[10:04:38.577] <TB0> INFO: PixTestTrim::trimBitTest() done
[10:04:38.578] <TB0> INFO: PixTestTrim::doTest() done, duration: 2232 seconds
[10:04:38.578] <TB0> INFO: Decoding statistics:
[10:04:38.578] <TB0> INFO: General information:
[10:04:38.578] <TB0> INFO: 16bit words read: 0
[10:04:38.578] <TB0> INFO: valid events total: 0
[10:04:38.578] <TB0> INFO: empty events: 0
[10:04:38.578] <TB0> INFO: valid events with pixels: 0
[10:04:38.578] <TB0> INFO: valid pixel hits: 0
[10:04:38.578] <TB0> INFO: Event errors: 0
[10:04:38.578] <TB0> INFO: start marker: 0
[10:04:38.578] <TB0> INFO: stop marker: 0
[10:04:38.578] <TB0> INFO: overflow: 0
[10:04:38.578] <TB0> INFO: invalid 5bit words: 0
[10:04:38.578] <TB0> INFO: invalid XOR eye diagram: 0
[10:04:38.578] <TB0> INFO: frame (failed synchr.): 0
[10:04:38.578] <TB0> INFO: idle data (no TBM trl): 0
[10:04:38.578] <TB0> INFO: no data (only TBM hdr): 0
[10:04:38.578] <TB0> INFO: TBM errors: 0
[10:04:38.579] <TB0> INFO: flawed TBM headers: 0
[10:04:38.579] <TB0> INFO: flawed TBM trailers: 0
[10:04:38.579] <TB0> INFO: event ID mismatches: 0
[10:04:38.579] <TB0> INFO: ROC errors: 0
[10:04:38.579] <TB0> INFO: missing ROC header(s): 0
[10:04:38.579] <TB0> INFO: misplaced readback start: 0
[10:04:38.579] <TB0> INFO: Pixel decoding errors: 0
[10:04:38.579] <TB0> INFO: pixel data incomplete: 0
[10:04:38.579] <TB0> INFO: pixel address: 0
[10:04:38.579] <TB0> INFO: pulse height fill bit: 0
[10:04:38.579] <TB0> INFO: buffer corruption: 0
[10:04:39.264] <TB0> INFO: ######################################################################
[10:04:39.264] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[10:04:39.264] <TB0> INFO: ######################################################################
[10:04:39.531] <TB0> INFO: Expecting 41600 events.
[10:04:43.007] <TB0> INFO: 41600 events read in total (2921ms).
[10:04:43.008] <TB0> INFO: Test took 3742ms.
[10:04:43.473] <TB0> INFO: Expecting 41600 events.
[10:04:46.988] <TB0> INFO: 41600 events read in total (2959ms).
[10:04:46.989] <TB0> INFO: Test took 3786ms.
[10:04:47.286] <TB0> INFO: Expecting 41600 events.
[10:04:50.695] <TB0> INFO: 41600 events read in total (2853ms).
[10:04:50.695] <TB0> INFO: Test took 3685ms.
[10:04:50.950] <TB0> INFO: Expecting 2560 events.
[10:04:51.802] <TB0> INFO: 2560 events read in total (295ms).
[10:04:51.802] <TB0> INFO: Test took 1094ms.
[10:04:52.107] <TB0> INFO: Expecting 2560 events.
[10:04:52.959] <TB0> INFO: 2560 events read in total (296ms).
[10:04:52.959] <TB0> INFO: Test took 1145ms.
[10:04:53.264] <TB0> INFO: Expecting 2560 events.
[10:04:54.116] <TB0> INFO: 2560 events read in total (296ms).
[10:04:54.116] <TB0> INFO: Test took 1157ms.
[10:04:54.421] <TB0> INFO: Expecting 2560 events.
[10:04:55.273] <TB0> INFO: 2560 events read in total (296ms).
[10:04:55.273] <TB0> INFO: Test took 1157ms.
[10:04:55.578] <TB0> INFO: Expecting 2560 events.
[10:04:56.431] <TB0> INFO: 2560 events read in total (297ms).
[10:04:56.431] <TB0> INFO: Test took 1158ms.
[10:04:56.736] <TB0> INFO: Expecting 2560 events.
[10:04:57.587] <TB0> INFO: 2560 events read in total (295ms).
[10:04:57.587] <TB0> INFO: Test took 1156ms.
[10:04:57.892] <TB0> INFO: Expecting 2560 events.
[10:04:58.755] <TB0> INFO: 2560 events read in total (307ms).
[10:04:58.755] <TB0> INFO: Test took 1168ms.
[10:04:59.060] <TB0> INFO: Expecting 2560 events.
[10:04:59.911] <TB0> INFO: 2560 events read in total (295ms).
[10:04:59.912] <TB0> INFO: Test took 1156ms.
[10:05:00.217] <TB0> INFO: Expecting 2560 events.
[10:05:01.068] <TB0> INFO: 2560 events read in total (295ms).
[10:05:01.069] <TB0> INFO: Test took 1157ms.
[10:05:01.374] <TB0> INFO: Expecting 2560 events.
[10:05:02.228] <TB0> INFO: 2560 events read in total (298ms).
[10:05:02.228] <TB0> INFO: Test took 1159ms.
[10:05:02.533] <TB0> INFO: Expecting 2560 events.
[10:05:03.387] <TB0> INFO: 2560 events read in total (298ms).
[10:05:03.387] <TB0> INFO: Test took 1159ms.
[10:05:03.692] <TB0> INFO: Expecting 2560 events.
[10:05:04.545] <TB0> INFO: 2560 events read in total (297ms).
[10:05:04.545] <TB0> INFO: Test took 1157ms.
[10:05:04.850] <TB0> INFO: Expecting 2560 events.
[10:05:05.706] <TB0> INFO: 2560 events read in total (300ms).
[10:05:05.706] <TB0> INFO: Test took 1161ms.
[10:05:06.011] <TB0> INFO: Expecting 2560 events.
[10:05:06.862] <TB0> INFO: 2560 events read in total (295ms).
[10:05:06.862] <TB0> INFO: Test took 1156ms.
[10:05:07.166] <TB0> INFO: Expecting 2560 events.
[10:05:08.020] <TB0> INFO: 2560 events read in total (298ms).
[10:05:08.020] <TB0> INFO: Test took 1158ms.
[10:05:08.325] <TB0> INFO: Expecting 2560 events.
[10:05:09.175] <TB0> INFO: 2560 events read in total (294ms).
[10:05:09.175] <TB0> INFO: Test took 1155ms.
[10:05:09.178] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:05:09.481] <TB0> INFO: Expecting 655360 events.
[10:05:23.450] <TB0> INFO: 655360 events read in total (13413ms).
[10:05:23.461] <TB0> INFO: Expecting 655360 events.
[10:05:37.177] <TB0> INFO: 655360 events read in total (13315ms).
[10:05:37.192] <TB0> INFO: Expecting 655360 events.
[10:05:50.916] <TB0> INFO: 655360 events read in total (13322ms).
[10:05:50.935] <TB0> INFO: Expecting 655360 events.
[10:06:04.823] <TB0> INFO: 655360 events read in total (13486ms).
[10:06:04.846] <TB0> INFO: Expecting 655360 events.
[10:06:18.664] <TB0> INFO: 655360 events read in total (13416ms).
[10:06:18.692] <TB0> INFO: Expecting 655360 events.
[10:06:32.349] <TB0> INFO: 655360 events read in total (13255ms).
[10:06:32.382] <TB0> INFO: Expecting 655360 events.
[10:06:46.339] <TB0> INFO: 655360 events read in total (13555ms).
[10:06:46.394] <TB0> INFO: Expecting 655360 events.
[10:07:00.237] <TB0> INFO: 655360 events read in total (13442ms).
[10:07:00.292] <TB0> INFO: Expecting 655360 events.
[10:07:14.178] <TB0> INFO: 655360 events read in total (13485ms).
[10:07:14.229] <TB0> INFO: Expecting 655360 events.
[10:07:28.054] <TB0> INFO: 655360 events read in total (13423ms).
[10:07:28.116] <TB0> INFO: Expecting 655360 events.
[10:07:41.906] <TB0> INFO: 655360 events read in total (13388ms).
[10:07:41.959] <TB0> INFO: Expecting 655360 events.
[10:07:55.716] <TB0> INFO: 655360 events read in total (13356ms).
[10:07:55.785] <TB0> INFO: Expecting 655360 events.
[10:08:09.448] <TB0> INFO: 655360 events read in total (13262ms).
[10:08:09.509] <TB0> INFO: Expecting 655360 events.
[10:08:23.236] <TB0> INFO: 655360 events read in total (13325ms).
[10:08:23.302] <TB0> INFO: Expecting 655360 events.
[10:08:37.096] <TB0> INFO: 655360 events read in total (13392ms).
[10:08:37.165] <TB0> INFO: Expecting 655360 events.
[10:08:50.944] <TB0> INFO: 655360 events read in total (13377ms).
[10:08:51.019] <TB0> INFO: Test took 221841ms.
[10:08:51.099] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:08:51.329] <TB0> INFO: Expecting 655360 events.
[10:09:05.197] <TB0> INFO: 655360 events read in total (13312ms).
[10:09:05.207] <TB0> INFO: Expecting 655360 events.
[10:09:19.078] <TB0> INFO: 655360 events read in total (13469ms).
[10:09:19.093] <TB0> INFO: Expecting 655360 events.
[10:09:32.912] <TB0> INFO: 655360 events read in total (13417ms).
[10:09:32.931] <TB0> INFO: Expecting 655360 events.
[10:09:46.693] <TB0> INFO: 655360 events read in total (13360ms).
[10:09:46.731] <TB0> INFO: Expecting 655360 events.
[10:10:00.512] <TB0> INFO: 655360 events read in total (13379ms).
[10:10:00.539] <TB0> INFO: Expecting 655360 events.
[10:10:14.392] <TB0> INFO: 655360 events read in total (13451ms).
[10:10:14.427] <TB0> INFO: Expecting 655360 events.
[10:10:28.220] <TB0> INFO: 655360 events read in total (13391ms).
[10:10:28.257] <TB0> INFO: Expecting 655360 events.
[10:10:42.149] <TB0> INFO: 655360 events read in total (13490ms).
[10:10:42.188] <TB0> INFO: Expecting 655360 events.
[10:10:55.845] <TB0> INFO: 655360 events read in total (13256ms).
[10:10:55.889] <TB0> INFO: Expecting 655360 events.
[10:11:09.710] <TB0> INFO: 655360 events read in total (13420ms).
[10:11:09.760] <TB0> INFO: Expecting 655360 events.
[10:11:23.607] <TB0> INFO: 655360 events read in total (13446ms).
[10:11:23.660] <TB0> INFO: Expecting 655360 events.
[10:11:37.451] <TB0> INFO: 655360 events read in total (13389ms).
[10:11:37.520] <TB0> INFO: Expecting 655360 events.
[10:11:51.351] <TB0> INFO: 655360 events read in total (13430ms).
[10:11:51.421] <TB0> INFO: Expecting 655360 events.
[10:12:05.132] <TB0> INFO: 655360 events read in total (13310ms).
[10:12:05.202] <TB0> INFO: Expecting 655360 events.
[10:12:19.005] <TB0> INFO: 655360 events read in total (13401ms).
[10:12:19.079] <TB0> INFO: Expecting 655360 events.
[10:12:32.716] <TB0> INFO: 655360 events read in total (13236ms).
[10:12:32.791] <TB0> INFO: Test took 221692ms.
[10:12:32.990] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:12:32.998] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:12:33.007] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:12:33.014] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:12:33.021] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:12:33.028] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:12:33.035] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:12:33.041] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:12:33.048] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:12:33.055] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:12:33.062] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:12:33.070] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:12:33.078] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:12:33.085] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:12:33.093] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:12:33.100] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[10:12:33.146] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C0.dat
[10:12:33.146] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C1.dat
[10:12:33.146] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C2.dat
[10:12:33.146] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C3.dat
[10:12:33.146] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C4.dat
[10:12:33.146] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C5.dat
[10:12:33.147] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C6.dat
[10:12:33.147] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C7.dat
[10:12:33.147] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C8.dat
[10:12:33.147] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C9.dat
[10:12:33.147] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C10.dat
[10:12:33.147] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C11.dat
[10:12:33.147] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C12.dat
[10:12:33.147] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C13.dat
[10:12:33.147] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C14.dat
[10:12:33.147] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//dacParameters35_C15.dat
[10:12:33.424] <TB0> INFO: Expecting 41600 events.
[10:12:36.571] <TB0> INFO: 41600 events read in total (2591ms).
[10:12:36.572] <TB0> INFO: Test took 3422ms.
[10:12:37.031] <TB0> INFO: Expecting 41600 events.
[10:12:40.095] <TB0> INFO: 41600 events read in total (2508ms).
[10:12:40.095] <TB0> INFO: Test took 3346ms.
[10:12:40.537] <TB0> INFO: Expecting 41600 events.
[10:12:43.660] <TB0> INFO: 41600 events read in total (2567ms).
[10:12:43.660] <TB0> INFO: Test took 3388ms.
[10:12:43.843] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:12:43.932] <TB0> INFO: Expecting 2560 events.
[10:12:44.791] <TB0> INFO: 2560 events read in total (303ms).
[10:12:44.791] <TB0> INFO: Test took 948ms.
[10:12:44.793] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:12:45.098] <TB0> INFO: Expecting 2560 events.
[10:12:45.954] <TB0> INFO: 2560 events read in total (300ms).
[10:12:45.954] <TB0> INFO: Test took 1161ms.
[10:12:45.956] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:12:46.260] <TB0> INFO: Expecting 2560 events.
[10:12:47.112] <TB0> INFO: 2560 events read in total (296ms).
[10:12:47.112] <TB0> INFO: Test took 1156ms.
[10:12:47.115] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:12:47.418] <TB0> INFO: Expecting 2560 events.
[10:12:48.270] <TB0> INFO: 2560 events read in total (296ms).
[10:12:48.270] <TB0> INFO: Test took 1156ms.
[10:12:48.272] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:12:48.575] <TB0> INFO: Expecting 2560 events.
[10:12:49.427] <TB0> INFO: 2560 events read in total (296ms).
[10:12:49.427] <TB0> INFO: Test took 1155ms.
[10:12:49.429] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:12:49.733] <TB0> INFO: Expecting 2560 events.
[10:12:50.584] <TB0> INFO: 2560 events read in total (295ms).
[10:12:50.584] <TB0> INFO: Test took 1155ms.
[10:12:50.587] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:12:50.890] <TB0> INFO: Expecting 2560 events.
[10:12:51.744] <TB0> INFO: 2560 events read in total (297ms).
[10:12:51.746] <TB0> INFO: Test took 1159ms.
[10:12:51.748] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:12:52.049] <TB0> INFO: Expecting 2560 events.
[10:12:52.904] <TB0> INFO: 2560 events read in total (299ms).
[10:12:52.904] <TB0> INFO: Test took 1156ms.
[10:12:52.910] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:12:53.208] <TB0> INFO: Expecting 2560 events.
[10:12:54.060] <TB0> INFO: 2560 events read in total (296ms).
[10:12:54.060] <TB0> INFO: Test took 1150ms.
[10:12:54.062] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:12:54.366] <TB0> INFO: Expecting 2560 events.
[10:12:55.218] <TB0> INFO: 2560 events read in total (296ms).
[10:12:55.218] <TB0> INFO: Test took 1156ms.
[10:12:55.219] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:12:55.524] <TB0> INFO: Expecting 2560 events.
[10:12:56.376] <TB0> INFO: 2560 events read in total (296ms).
[10:12:56.376] <TB0> INFO: Test took 1157ms.
[10:12:56.378] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:12:56.681] <TB0> INFO: Expecting 2560 events.
[10:12:57.540] <TB0> INFO: 2560 events read in total (302ms).
[10:12:57.540] <TB0> INFO: Test took 1162ms.
[10:12:57.542] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:12:57.847] <TB0> INFO: Expecting 2560 events.
[10:12:58.705] <TB0> INFO: 2560 events read in total (302ms).
[10:12:58.705] <TB0> INFO: Test took 1163ms.
[10:12:58.710] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:12:59.010] <TB0> INFO: Expecting 2560 events.
[10:12:59.867] <TB0> INFO: 2560 events read in total (300ms).
[10:12:59.867] <TB0> INFO: Test took 1157ms.
[10:12:59.870] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:13:00.171] <TB0> INFO: Expecting 2560 events.
[10:13:01.025] <TB0> INFO: 2560 events read in total (298ms).
[10:13:01.026] <TB0> INFO: Test took 1156ms.
[10:13:01.028] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:13:01.330] <TB0> INFO: Expecting 2560 events.
[10:13:02.184] <TB0> INFO: 2560 events read in total (298ms).
[10:13:02.185] <TB0> INFO: Test took 1157ms.
[10:13:02.186] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:13:02.491] <TB0> INFO: Expecting 2560 events.
[10:13:03.352] <TB0> INFO: 2560 events read in total (303ms).
[10:13:03.353] <TB0> INFO: Test took 1167ms.
[10:13:03.354] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:13:03.658] <TB0> INFO: Expecting 2560 events.
[10:13:04.510] <TB0> INFO: 2560 events read in total (296ms).
[10:13:04.510] <TB0> INFO: Test took 1156ms.
[10:13:04.513] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:13:04.816] <TB0> INFO: Expecting 2560 events.
[10:13:05.669] <TB0> INFO: 2560 events read in total (297ms).
[10:13:05.669] <TB0> INFO: Test took 1156ms.
[10:13:05.671] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:13:05.975] <TB0> INFO: Expecting 2560 events.
[10:13:06.828] <TB0> INFO: 2560 events read in total (297ms).
[10:13:06.828] <TB0> INFO: Test took 1157ms.
[10:13:06.830] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:13:07.133] <TB0> INFO: Expecting 2560 events.
[10:13:07.992] <TB0> INFO: 2560 events read in total (303ms).
[10:13:07.992] <TB0> INFO: Test took 1162ms.
[10:13:07.994] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:13:08.295] <TB0> INFO: Expecting 2560 events.
[10:13:09.147] <TB0> INFO: 2560 events read in total (296ms).
[10:13:09.148] <TB0> INFO: Test took 1154ms.
[10:13:09.149] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:13:09.453] <TB0> INFO: Expecting 2560 events.
[10:13:10.304] <TB0> INFO: 2560 events read in total (295ms).
[10:13:10.304] <TB0> INFO: Test took 1155ms.
[10:13:10.306] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:13:10.610] <TB0> INFO: Expecting 2560 events.
[10:13:11.467] <TB0> INFO: 2560 events read in total (301ms).
[10:13:11.467] <TB0> INFO: Test took 1161ms.
[10:13:11.470] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:13:11.773] <TB0> INFO: Expecting 2560 events.
[10:13:12.633] <TB0> INFO: 2560 events read in total (304ms).
[10:13:12.633] <TB0> INFO: Test took 1163ms.
[10:13:12.635] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:13:12.939] <TB0> INFO: Expecting 2560 events.
[10:13:13.796] <TB0> INFO: 2560 events read in total (301ms).
[10:13:13.796] <TB0> INFO: Test took 1161ms.
[10:13:13.799] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:13:14.101] <TB0> INFO: Expecting 2560 events.
[10:13:14.954] <TB0> INFO: 2560 events read in total (297ms).
[10:13:14.954] <TB0> INFO: Test took 1156ms.
[10:13:14.956] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:13:15.261] <TB0> INFO: Expecting 2560 events.
[10:13:16.113] <TB0> INFO: 2560 events read in total (296ms).
[10:13:16.113] <TB0> INFO: Test took 1157ms.
[10:13:16.117] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:13:16.418] <TB0> INFO: Expecting 2560 events.
[10:13:17.270] <TB0> INFO: 2560 events read in total (296ms).
[10:13:17.270] <TB0> INFO: Test took 1153ms.
[10:13:17.272] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:13:17.576] <TB0> INFO: Expecting 2560 events.
[10:13:18.429] <TB0> INFO: 2560 events read in total (297ms).
[10:13:18.429] <TB0> INFO: Test took 1157ms.
[10:13:18.431] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:13:18.734] <TB0> INFO: Expecting 2560 events.
[10:13:19.586] <TB0> INFO: 2560 events read in total (296ms).
[10:13:19.586] <TB0> INFO: Test took 1155ms.
[10:13:19.588] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[10:13:19.892] <TB0> INFO: Expecting 2560 events.
[10:13:20.744] <TB0> INFO: 2560 events read in total (296ms).
[10:13:20.744] <TB0> INFO: Test took 1156ms.
[10:13:21.203] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 521 seconds
[10:13:21.203] <TB0> INFO: PH scale (per ROC): 89 91 81 95 81 93 87 84 91 97 85 86 92 82 91 91
[10:13:21.203] <TB0> INFO: PH offset (per ROC): 165 162 146 143 156 139 164 169 155 150 165 143 145 175 130 153
[10:13:21.207] <TB0> INFO: Decoding statistics:
[10:13:21.207] <TB0> INFO: General information:
[10:13:21.207] <TB0> INFO: 16bit words read: 86922
[10:13:21.207] <TB0> INFO: valid events total: 10240
[10:13:21.207] <TB0> INFO: empty events: 7739
[10:13:21.207] <TB0> INFO: valid events with pixels: 2501
[10:13:21.207] <TB0> INFO: valid pixel hits: 2501
[10:13:21.208] <TB0> INFO: Event errors: 0
[10:13:21.208] <TB0> INFO: start marker: 0
[10:13:21.208] <TB0> INFO: stop marker: 0
[10:13:21.208] <TB0> INFO: overflow: 0
[10:13:21.208] <TB0> INFO: invalid 5bit words: 0
[10:13:21.208] <TB0> INFO: invalid XOR eye diagram: 0
[10:13:21.208] <TB0> INFO: frame (failed synchr.): 0
[10:13:21.208] <TB0> INFO: idle data (no TBM trl): 0
[10:13:21.208] <TB0> INFO: no data (only TBM hdr): 0
[10:13:21.208] <TB0> INFO: TBM errors: 0
[10:13:21.208] <TB0> INFO: flawed TBM headers: 0
[10:13:21.208] <TB0> INFO: flawed TBM trailers: 0
[10:13:21.208] <TB0> INFO: event ID mismatches: 0
[10:13:21.208] <TB0> INFO: ROC errors: 0
[10:13:21.208] <TB0> INFO: missing ROC header(s): 0
[10:13:21.208] <TB0> INFO: misplaced readback start: 0
[10:13:21.208] <TB0> INFO: Pixel decoding errors: 0
[10:13:21.208] <TB0> INFO: pixel data incomplete: 0
[10:13:21.208] <TB0> INFO: pixel address: 0
[10:13:21.208] <TB0> INFO: pulse height fill bit: 0
[10:13:21.208] <TB0> INFO: buffer corruption: 0
[10:13:21.402] <TB0> INFO: ######################################################################
[10:13:21.402] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[10:13:21.402] <TB0> INFO: ######################################################################
[10:13:21.412] <TB0> INFO: scanning low vcal = 10
[10:13:21.680] <TB0> INFO: Expecting 41600 events.
[10:13:25.056] <TB0> INFO: 41600 events read in total (2820ms).
[10:13:25.056] <TB0> INFO: Test took 3643ms.
[10:13:25.058] <TB0> INFO: scanning low vcal = 20
[10:13:25.353] <TB0> INFO: Expecting 41600 events.
[10:13:28.776] <TB0> INFO: 41600 events read in total (2867ms).
[10:13:28.776] <TB0> INFO: Test took 3718ms.
[10:13:28.778] <TB0> INFO: scanning low vcal = 30
[10:13:29.073] <TB0> INFO: Expecting 41600 events.
[10:13:32.478] <TB0> INFO: 41600 events read in total (2848ms).
[10:13:32.479] <TB0> INFO: Test took 3701ms.
[10:13:32.481] <TB0> INFO: scanning low vcal = 40
[10:13:32.773] <TB0> INFO: Expecting 41600 events.
[10:13:36.668] <TB0> INFO: 41600 events read in total (3339ms).
[10:13:36.669] <TB0> INFO: Test took 4188ms.
[10:13:36.672] <TB0> INFO: scanning low vcal = 50
[10:13:36.935] <TB0> INFO: Expecting 41600 events.
[10:13:40.910] <TB0> INFO: 41600 events read in total (3418ms).
[10:13:40.910] <TB0> INFO: Test took 4238ms.
[10:13:40.913] <TB0> INFO: scanning low vcal = 60
[10:13:41.176] <TB0> INFO: Expecting 41600 events.
[10:13:45.142] <TB0> INFO: 41600 events read in total (3409ms).
[10:13:45.143] <TB0> INFO: Test took 4230ms.
[10:13:45.145] <TB0> INFO: scanning low vcal = 70
[10:13:45.417] <TB0> INFO: Expecting 41600 events.
[10:13:49.375] <TB0> INFO: 41600 events read in total (3402ms).
[10:13:49.376] <TB0> INFO: Test took 4230ms.
[10:13:49.378] <TB0> INFO: scanning low vcal = 80
[10:13:49.638] <TB0> INFO: Expecting 41600 events.
[10:13:53.617] <TB0> INFO: 41600 events read in total (3423ms).
[10:13:53.618] <TB0> INFO: Test took 4239ms.
[10:13:53.620] <TB0> INFO: scanning low vcal = 90
[10:13:53.897] <TB0> INFO: Expecting 41600 events.
[10:13:57.857] <TB0> INFO: 41600 events read in total (3404ms).
[10:13:57.858] <TB0> INFO: Test took 4238ms.
[10:13:57.861] <TB0> INFO: scanning low vcal = 100
[10:13:58.147] <TB0> INFO: Expecting 41600 events.
[10:14:02.110] <TB0> INFO: 41600 events read in total (3407ms).
[10:14:02.111] <TB0> INFO: Test took 4250ms.
[10:14:02.114] <TB0> INFO: scanning low vcal = 110
[10:14:02.380] <TB0> INFO: Expecting 41600 events.
[10:14:06.313] <TB0> INFO: 41600 events read in total (3377ms).
[10:14:06.314] <TB0> INFO: Test took 4200ms.
[10:14:06.317] <TB0> INFO: scanning low vcal = 120
[10:14:06.596] <TB0> INFO: Expecting 41600 events.
[10:14:10.532] <TB0> INFO: 41600 events read in total (3380ms).
[10:14:10.532] <TB0> INFO: Test took 4215ms.
[10:14:10.535] <TB0> INFO: scanning low vcal = 130
[10:14:10.796] <TB0> INFO: Expecting 41600 events.
[10:14:14.761] <TB0> INFO: 41600 events read in total (3408ms).
[10:14:14.761] <TB0> INFO: Test took 4226ms.
[10:14:14.764] <TB0> INFO: scanning low vcal = 140
[10:14:15.038] <TB0> INFO: Expecting 41600 events.
[10:14:18.990] <TB0> INFO: 41600 events read in total (3396ms).
[10:14:18.990] <TB0> INFO: Test took 4226ms.
[10:14:18.994] <TB0> INFO: scanning low vcal = 150
[10:14:19.295] <TB0> INFO: Expecting 41600 events.
[10:14:23.245] <TB0> INFO: 41600 events read in total (3394ms).
[10:14:23.245] <TB0> INFO: Test took 4251ms.
[10:14:23.248] <TB0> INFO: scanning low vcal = 160
[10:14:23.512] <TB0> INFO: Expecting 41600 events.
[10:14:27.436] <TB0> INFO: 41600 events read in total (3368ms).
[10:14:27.436] <TB0> INFO: Test took 4188ms.
[10:14:27.439] <TB0> INFO: scanning low vcal = 170
[10:14:27.705] <TB0> INFO: Expecting 41600 events.
[10:14:31.672] <TB0> INFO: 41600 events read in total (3411ms).
[10:14:31.673] <TB0> INFO: Test took 4234ms.
[10:14:31.677] <TB0> INFO: scanning low vcal = 180
[10:14:31.939] <TB0> INFO: Expecting 41600 events.
[10:14:35.884] <TB0> INFO: 41600 events read in total (3389ms).
[10:14:35.884] <TB0> INFO: Test took 4207ms.
[10:14:35.886] <TB0> INFO: scanning low vcal = 190
[10:14:36.150] <TB0> INFO: Expecting 41600 events.
[10:14:40.096] <TB0> INFO: 41600 events read in total (3390ms).
[10:14:40.097] <TB0> INFO: Test took 4211ms.
[10:14:40.099] <TB0> INFO: scanning low vcal = 200
[10:14:40.362] <TB0> INFO: Expecting 41600 events.
[10:14:44.301] <TB0> INFO: 41600 events read in total (3382ms).
[10:14:44.301] <TB0> INFO: Test took 4202ms.
[10:14:44.304] <TB0> INFO: scanning low vcal = 210
[10:14:44.567] <TB0> INFO: Expecting 41600 events.
[10:14:48.513] <TB0> INFO: 41600 events read in total (3390ms).
[10:14:48.514] <TB0> INFO: Test took 4210ms.
[10:14:48.516] <TB0> INFO: scanning low vcal = 220
[10:14:48.780] <TB0> INFO: Expecting 41600 events.
[10:14:52.711] <TB0> INFO: 41600 events read in total (3375ms).
[10:14:52.711] <TB0> INFO: Test took 4195ms.
[10:14:52.714] <TB0> INFO: scanning low vcal = 230
[10:14:52.976] <TB0> INFO: Expecting 41600 events.
[10:14:56.899] <TB0> INFO: 41600 events read in total (3367ms).
[10:14:56.899] <TB0> INFO: Test took 4185ms.
[10:14:56.902] <TB0> INFO: scanning low vcal = 240
[10:14:57.174] <TB0> INFO: Expecting 41600 events.
[10:15:01.110] <TB0> INFO: 41600 events read in total (3380ms).
[10:15:01.111] <TB0> INFO: Test took 4209ms.
[10:15:01.113] <TB0> INFO: scanning low vcal = 250
[10:15:01.495] <TB0> INFO: Expecting 41600 events.
[10:15:05.436] <TB0> INFO: 41600 events read in total (3385ms).
[10:15:05.437] <TB0> INFO: Test took 4324ms.
[10:15:05.440] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[10:15:05.753] <TB0> INFO: Expecting 41600 events.
[10:15:09.705] <TB0> INFO: 41600 events read in total (3396ms).
[10:15:09.707] <TB0> INFO: Test took 4267ms.
[10:15:09.711] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[10:15:09.975] <TB0> INFO: Expecting 41600 events.
[10:15:13.912] <TB0> INFO: 41600 events read in total (3381ms).
[10:15:13.913] <TB0> INFO: Test took 4202ms.
[10:15:13.916] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[10:15:14.186] <TB0> INFO: Expecting 41600 events.
[10:15:18.156] <TB0> INFO: 41600 events read in total (3414ms).
[10:15:18.157] <TB0> INFO: Test took 4241ms.
[10:15:18.160] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[10:15:18.427] <TB0> INFO: Expecting 41600 events.
[10:15:22.376] <TB0> INFO: 41600 events read in total (3393ms).
[10:15:22.377] <TB0> INFO: Test took 4217ms.
[10:15:22.379] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[10:15:22.658] <TB0> INFO: Expecting 41600 events.
[10:15:26.644] <TB0> INFO: 41600 events read in total (3430ms).
[10:15:26.644] <TB0> INFO: Test took 4249ms.
[10:15:27.155] <TB0> INFO: PixTestGainPedestal::measure() done
[10:16:00.771] <TB0> INFO: PixTestGainPedestal::fit() done
[10:16:00.771] <TB0> INFO: non-linearity mean: 0.949 0.947 0.953 0.951 0.949 0.948 0.950 0.960 0.953 0.949 0.955 0.955 0.955 0.955 0.948 0.951
[10:16:00.771] <TB0> INFO: non-linearity RMS: 0.006 0.007 0.006 0.006 0.006 0.006 0.007 0.005 0.006 0.006 0.005 0.005 0.006 0.006 0.006 0.006
[10:16:00.771] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[10:16:00.790] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[10:16:00.807] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[10:16:00.825] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[10:16:00.843] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[10:16:00.861] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[10:16:00.894] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[10:16:00.912] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[10:16:00.931] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[10:16:00.955] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[10:16:00.973] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[10:16:00.991] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[10:16:01.009] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[10:16:01.027] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[10:16:01.050] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[10:16:01.076] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[10:16:01.102] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 159 seconds
[10:16:01.102] <TB0> INFO: Decoding statistics:
[10:16:01.102] <TB0> INFO: General information:
[10:16:01.102] <TB0> INFO: 16bit words read: 2662400
[10:16:01.102] <TB0> INFO: valid events total: 166400
[10:16:01.102] <TB0> INFO: empty events: 0
[10:16:01.102] <TB0> INFO: valid events with pixels: 166400
[10:16:01.102] <TB0> INFO: valid pixel hits: 665600
[10:16:01.102] <TB0> INFO: Event errors: 0
[10:16:01.102] <TB0> INFO: start marker: 0
[10:16:01.102] <TB0> INFO: stop marker: 0
[10:16:01.102] <TB0> INFO: overflow: 0
[10:16:01.102] <TB0> INFO: invalid 5bit words: 0
[10:16:01.102] <TB0> INFO: invalid XOR eye diagram: 0
[10:16:01.102] <TB0> INFO: frame (failed synchr.): 0
[10:16:01.102] <TB0> INFO: idle data (no TBM trl): 0
[10:16:01.102] <TB0> INFO: no data (only TBM hdr): 0
[10:16:01.102] <TB0> INFO: TBM errors: 0
[10:16:01.102] <TB0> INFO: flawed TBM headers: 0
[10:16:01.102] <TB0> INFO: flawed TBM trailers: 0
[10:16:01.102] <TB0> INFO: event ID mismatches: 0
[10:16:01.102] <TB0> INFO: ROC errors: 0
[10:16:01.102] <TB0> INFO: missing ROC header(s): 0
[10:16:01.102] <TB0> INFO: misplaced readback start: 0
[10:16:01.102] <TB0> INFO: Pixel decoding errors: 0
[10:16:01.102] <TB0> INFO: pixel data incomplete: 0
[10:16:01.102] <TB0> INFO: pixel address: 0
[10:16:01.102] <TB0> INFO: pulse height fill bit: 0
[10:16:01.102] <TB0> INFO: buffer corruption: 0
[10:16:01.112] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C15.dat
[10:16:01.138] <TB0> INFO: ######################################################################
[10:16:01.138] <TB0> INFO: PixTestReadback::doTest()
[10:16:01.138] <TB0> INFO: ######################################################################
[10:16:01.138] <TB0> INFO: ----------------------------------------------------------------------
[10:16:01.138] <TB0> INFO: PixTestReadback::CalibrateVd()
[10:16:01.138] <TB0> INFO: ----------------------------------------------------------------------
[10:16:10.578] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C0.dat
[10:16:10.589] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C1.dat
[10:16:10.589] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C2.dat
[10:16:10.589] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C3.dat
[10:16:10.589] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C4.dat
[10:16:10.589] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C5.dat
[10:16:10.589] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C6.dat
[10:16:10.589] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C7.dat
[10:16:10.589] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C8.dat
[10:16:10.589] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C9.dat
[10:16:10.589] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C10.dat
[10:16:10.589] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C11.dat
[10:16:10.590] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C12.dat
[10:16:10.590] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C13.dat
[10:16:10.590] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C14.dat
[10:16:10.590] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C15.dat
[10:16:10.617] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[10:16:10.617] <TB0> INFO: ----------------------------------------------------------------------
[10:16:10.617] <TB0> INFO: PixTestReadback::CalibrateVa()
[10:16:10.617] <TB0> INFO: ----------------------------------------------------------------------
[10:16:20.878] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C0.dat
[10:16:20.878] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C1.dat
[10:16:20.878] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C2.dat
[10:16:20.878] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C3.dat
[10:16:20.879] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C4.dat
[10:16:20.879] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C5.dat
[10:16:20.879] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C6.dat
[10:16:20.879] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C7.dat
[10:16:20.879] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C8.dat
[10:16:20.879] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C9.dat
[10:16:20.879] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C10.dat
[10:16:20.879] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C11.dat
[10:16:20.879] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C12.dat
[10:16:20.879] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C13.dat
[10:16:20.880] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C14.dat
[10:16:20.880] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C15.dat
[10:16:20.905] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[10:16:20.905] <TB0> INFO: ----------------------------------------------------------------------
[10:16:20.905] <TB0> INFO: PixTestReadback::readbackVbg()
[10:16:20.905] <TB0> INFO: ----------------------------------------------------------------------
[10:16:28.192] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[10:16:28.192] <TB0> INFO: ----------------------------------------------------------------------
[10:16:28.192] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[10:16:28.192] <TB0> INFO: ----------------------------------------------------------------------
[10:16:28.192] <TB0> INFO: Vbg will be calibrated using Vd calibration
[10:16:28.192] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 161calibrated Vbg = 1.22189 :::*/*/*/*/
[10:16:28.192] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 153calibrated Vbg = 1.21649 :::*/*/*/*/
[10:16:28.192] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 153.4calibrated Vbg = 1.22444 :::*/*/*/*/
[10:16:28.192] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 148.2calibrated Vbg = 1.22563 :::*/*/*/*/
[10:16:28.192] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 151.5calibrated Vbg = 1.22299 :::*/*/*/*/
[10:16:28.192] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 145calibrated Vbg = 1.23392 :::*/*/*/*/
[10:16:28.192] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 152.3calibrated Vbg = 1.2252 :::*/*/*/*/
[10:16:28.192] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 156.5calibrated Vbg = 1.22973 :::*/*/*/*/
[10:16:28.192] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 156.2calibrated Vbg = 1.23429 :::*/*/*/*/
[10:16:28.192] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 145.1calibrated Vbg = 1.23582 :::*/*/*/*/
[10:16:28.192] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 153.5calibrated Vbg = 1.23159 :::*/*/*/*/
[10:16:28.192] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 154.4calibrated Vbg = 1.22896 :::*/*/*/*/
[10:16:28.192] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 148.3calibrated Vbg = 1.21603 :::*/*/*/*/
[10:16:28.192] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 158calibrated Vbg = 1.21575 :::*/*/*/*/
[10:16:28.192] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 154.5calibrated Vbg = 1.22468 :::*/*/*/*/
[10:16:28.192] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 152.8calibrated Vbg = 1.22238 :::*/*/*/*/
[10:16:28.194] <TB0> INFO: ----------------------------------------------------------------------
[10:16:28.194] <TB0> INFO: PixTestReadback::CalibrateIa()
[10:16:28.194] <TB0> INFO: ----------------------------------------------------------------------
[10:19:03.341] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C0.dat
[10:19:03.341] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C1.dat
[10:19:03.341] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C2.dat
[10:19:03.342] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C3.dat
[10:19:03.342] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C4.dat
[10:19:03.342] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C5.dat
[10:19:03.342] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C6.dat
[10:19:03.342] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C7.dat
[10:19:03.342] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C8.dat
[10:19:03.342] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C9.dat
[10:19:03.342] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C10.dat
[10:19:03.342] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C11.dat
[10:19:03.342] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C12.dat
[10:19:03.342] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C13.dat
[10:19:03.342] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C14.dat
[10:19:03.342] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2325_FullQualification_2016-09-23_10h49m_1474620540//000_FulltestPxar_m20//readbackCal_C15.dat
[10:19:03.371] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[10:19:03.372] <TB0> INFO: PixTestReadback::doTest() done
[10:19:03.372] <TB0> INFO: Decoding statistics:
[10:19:03.372] <TB0> INFO: General information:
[10:19:03.372] <TB0> INFO: 16bit words read: 1024
[10:19:03.372] <TB0> INFO: valid events total: 128
[10:19:03.372] <TB0> INFO: empty events: 128
[10:19:03.372] <TB0> INFO: valid events with pixels: 0
[10:19:03.372] <TB0> INFO: valid pixel hits: 0
[10:19:03.372] <TB0> INFO: Event errors: 0
[10:19:03.372] <TB0> INFO: start marker: 0
[10:19:03.372] <TB0> INFO: stop marker: 0
[10:19:03.372] <TB0> INFO: overflow: 0
[10:19:03.372] <TB0> INFO: invalid 5bit words: 0
[10:19:03.372] <TB0> INFO: invalid XOR eye diagram: 0
[10:19:03.372] <TB0> INFO: frame (failed synchr.): 0
[10:19:03.372] <TB0> INFO: idle data (no TBM trl): 0
[10:19:03.372] <TB0> INFO: no data (only TBM hdr): 0
[10:19:03.372] <TB0> INFO: TBM errors: 0
[10:19:03.372] <TB0> INFO: flawed TBM headers: 0
[10:19:03.372] <TB0> INFO: flawed TBM trailers: 0
[10:19:03.372] <TB0> INFO: event ID mismatches: 0
[10:19:03.372] <TB0> INFO: ROC errors: 0
[10:19:03.372] <TB0> INFO: missing ROC header(s): 0
[10:19:03.372] <TB0> INFO: misplaced readback start: 0
[10:19:03.372] <TB0> INFO: Pixel decoding errors: 0
[10:19:03.372] <TB0> INFO: pixel data incomplete: 0
[10:19:03.372] <TB0> INFO: pixel address: 0
[10:19:03.372] <TB0> INFO: pulse height fill bit: 0
[10:19:03.372] <TB0> INFO: buffer corruption: 0
[10:19:03.441] <TB0> INFO: Decoding statistics:
[10:19:03.441] <TB0> INFO: General information:
[10:19:03.441] <TB0> INFO: 16bit words read: 2750346
[10:19:03.441] <TB0> INFO: valid events total: 176768
[10:19:03.441] <TB0> INFO: empty events: 7867
[10:19:03.441] <TB0> INFO: valid events with pixels: 168901
[10:19:03.441] <TB0> INFO: valid pixel hits: 668101
[10:19:03.441] <TB0> INFO: Event errors: 0
[10:19:03.441] <TB0> INFO: start marker: 0
[10:19:03.441] <TB0> INFO: stop marker: 0
[10:19:03.441] <TB0> INFO: overflow: 0
[10:19:03.441] <TB0> INFO: invalid 5bit words: 0
[10:19:03.441] <TB0> INFO: invalid XOR eye diagram: 0
[10:19:03.441] <TB0> INFO: frame (failed synchr.): 0
[10:19:03.441] <TB0> INFO: idle data (no TBM trl): 0
[10:19:03.441] <TB0> INFO: no data (only TBM hdr): 0
[10:19:03.441] <TB0> INFO: TBM errors: 0
[10:19:03.441] <TB0> INFO: flawed TBM headers: 0
[10:19:03.441] <TB0> INFO: flawed TBM trailers: 0
[10:19:03.441] <TB0> INFO: event ID mismatches: 0
[10:19:03.441] <TB0> INFO: ROC errors: 0
[10:19:03.441] <TB0> INFO: missing ROC header(s): 0
[10:19:03.441] <TB0> INFO: misplaced readback start: 0
[10:19:03.441] <TB0> INFO: Pixel decoding errors: 0
[10:19:03.442] <TB0> INFO: pixel data incomplete: 0
[10:19:03.442] <TB0> INFO: pixel address: 0
[10:19:03.442] <TB0> INFO: pulse height fill bit: 0
[10:19:03.442] <TB0> INFO: buffer corruption: 0
[10:19:03.442] <TB0> INFO: enter test to run
[10:19:03.442] <TB0> INFO: test: exit no parameter change
[10:19:03.636] <TB0> QUIET: Connection to board 73 closed.
[10:19:03.716] <TB0> INFO: pXar: this is the end, my friend