[12:30:35.552] <TB0> INFO: *** Welcome to pxar ***
[12:30:35.552] <TB0> INFO: *** Today: 2016/04/12
[12:30:36.540] <TB0> INFO: *** Version: 9751-dirty
[12:30:36.540] <TB0> INFO: readRocDacs: /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters_C15.dat
[12:30:36.540] <TB0> INFO: readTbmDacs: /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//tbmParameters_C0b.dat
[12:30:36.541] <TB0> INFO: readMaskFile: /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//defaultMaskFile.dat
[12:30:36.541] <TB0> INFO: readTrimFile: /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//trimParameters_C15.dat
[12:30:36.607] <TB0> INFO: clk: 4
[12:30:36.607] <TB0> INFO: ctr: 4
[12:30:36.607] <TB0> INFO: sda: 19
[12:30:36.607] <TB0> INFO: tin: 9
[12:30:36.607] <TB0> INFO: level: 15
[12:30:36.607] <TB0> INFO: triggerdelay: 0
[12:30:36.607] <TB0> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[12:30:36.607] <TB0> INFO: Log level: INFO
[12:30:36.614] <TB0> INFO: Found DTB DTB_WS6AYH
[12:30:36.625] <TB0> QUIET: Connection to board DTB_WS6AYH opened.
[12:30:36.627] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 73
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WS6AYH
MAC address: 40D855118049
Hostname: pixelDTB073
Comment:
------------------------------------------------------
[12:30:36.629] <TB0> INFO: RPC call hashes of host and DTB match: 484264910
[12:30:38.154] <TB0> INFO: DUT info:
[12:30:38.154] <TB0> INFO: The DUT currently contains the following objects:
[12:30:38.154] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[12:30:38.154] <TB0> INFO: TBM Core alpha (0): 7 registers set
[12:30:38.154] <TB0> INFO: TBM Core beta (1): 7 registers set
[12:30:38.154] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:30:38.154] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:30:38.154] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:30:38.154] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:30:38.154] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:30:38.154] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:30:38.154] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:30:38.154] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:30:38.154] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:30:38.154] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:30:38.154] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:30:38.154] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:30:38.154] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:30:38.154] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:30:38.154] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:30:38.154] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:30:38.154] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:30:38.554] <TB0> INFO: enter 'restricted' command line mode
[12:30:38.554] <TB0> INFO: enter test to run
[12:30:38.554] <TB0> INFO: test: pretest no parameter change
[12:30:38.555] <TB0> INFO: running: pretest
[12:30:38.558] <TB0> INFO: ----------------------------------------------------------------------
[12:30:38.558] <TB0> INFO: PixTestPretest::programROC()
[12:30:38.558] <TB0> INFO: ----------------------------------------------------------------------
[12:30:56.572] <TB0> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:30:56.572] <TB0> INFO: IA differences per ROC: 19.3 20.1 20.1 19.3 18.5 19.3 20.1 17.7 20.9 21.7 18.5 21.7 19.3 20.1 20.1 17.7
[12:30:56.662] <TB0> INFO: enter test to run
[12:30:56.662] <TB0> INFO: test: pretest no parameter change
[12:30:56.662] <TB0> INFO: running: pretest
[12:30:56.663] <TB0> INFO: ----------------------------------------------------------------------
[12:30:56.663] <TB0> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:30:56.663] <TB0> INFO: ----------------------------------------------------------------------
[12:31:04.254] <TB0> INFO: PixTestPretest::setVana() done, Module Ia 374.6 mA = 23.4125 mA/ROC
[12:31:04.254] <TB0> INFO: i(loss) [mA/ROC]: 18.5 19.3 19.3 18.5 17.7 18.5 18.5 19.3 18.5 19.3 18.5 18.5 18.5 19.3 19.3 18.5
[12:31:04.283] <TB0> INFO: enter test to run
[12:31:04.284] <TB0> INFO: test: pretest no parameter change
[12:31:04.284] <TB0> INFO: running: pretest
[12:31:04.284] <TB0> INFO: ----------------------------------------------------------------------
[12:31:04.284] <TB0> INFO: PixTestPretest::findTiming()
[12:31:04.284] <TB0> INFO: ----------------------------------------------------------------------
[12:31:04.284] <TB0> INFO: PixTestCmd::init()

[12:32:35.767] <TB0> INFO: TBM phases: 160MHz: 4, 400MHz: 2, TBM delays: ROC(0/1):3, header/trailer: 1, token: 0
[12:32:35.767] <TB0> INFO: (success/tries = 100/100), width = 4
[12:32:35.768] <TB0> INFO: enter test to run
[12:32:35.768] <TB0> INFO: test: pretest no parameter change
[12:32:35.768] <TB0> INFO: running: pretest
[12:32:35.770] <TB0> INFO: ----------------------------------------------------------------------
[12:32:35.770] <TB0> INFO: PixTestPretest::findWorkingPixel()
[12:32:35.770] <TB0> INFO: ----------------------------------------------------------------------
[12:32:35.863] <TB0> INFO: Expecting 231680 events.


[12:32:45.978] <TB0> INFO: 231680 events read in total (9559ms).
[12:32:45.982] <TB0> INFO: Test took 10207ms.
[12:32:46.208] <TB0> INFO: Found working pixel in all ROCs: col/row = 12/22
[12:32:46.286] <TB0> INFO: enter test to run
[12:32:46.286] <TB0> INFO: test: pretest no parameter change
[12:32:46.286] <TB0> INFO: running: pretest
[12:32:46.287] <TB0> INFO: ----------------------------------------------------------------------
[12:32:46.287] <TB0> INFO: PixTestPretest::setVthrCompCalDel()
[12:32:46.287] <TB0> INFO: ----------------------------------------------------------------------
[12:32:46.380] <TB0> INFO: Expecting 231680 events.
[12:32:56.619] <TB0> INFO: 231680 events read in total (9683ms).
[12:32:56.622] <TB0> INFO: Test took 10330ms.
[12:32:56.887] <TB0> INFO: PixTestPretest::setVthrCompCalDel() done
[12:32:56.888] <TB0> INFO: CalDel: 139 146 160 144 151 128 145 132 140 142 131 138 136 144 133 143
[12:32:56.888] <TB0> INFO: VthrComp: 51 51 52 51 51 51 51 51 51 51 51 51 51 51 51 51
[12:32:56.925] <TB0> INFO: enter test to run
[12:32:56.925] <TB0> INFO: test: pretest no parameter change
[12:32:56.925] <TB0> INFO: running: pretest
[12:32:56.925] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters_C0.dat
[12:32:56.925] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters_C1.dat
[12:32:56.925] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters_C2.dat
[12:32:56.925] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters_C3.dat
[12:32:56.926] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters_C4.dat
[12:32:56.926] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters_C5.dat
[12:32:56.926] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters_C6.dat
[12:32:56.926] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters_C7.dat
[12:32:56.926] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters_C8.dat
[12:32:56.926] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters_C9.dat
[12:32:56.926] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters_C10.dat
[12:32:56.926] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters_C11.dat
[12:32:56.926] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters_C12.dat
[12:32:56.926] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters_C13.dat
[12:32:56.926] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters_C14.dat
[12:32:56.926] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters_C15.dat
[12:32:56.927] <TB0> INFO: enter test to run
[12:32:56.927] <TB0> INFO: test: fulltest no parameter change
[12:32:56.927] <TB0> INFO: running: fulltest
[12:32:56.927] <TB0> INFO: ######################################################################
[12:32:56.927] <TB0> INFO: PixTestFullTest::doTest()
[12:32:56.927] <TB0> INFO: ######################################################################
[12:32:56.928] <TB0> INFO: ######################################################################
[12:32:56.928] <TB0> INFO: PixTestAlive::doTest()
[12:32:56.928] <TB0> INFO: ######################################################################
[12:32:56.929] <TB0> INFO: ----------------------------------------------------------------------
[12:32:56.929] <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)
[12:32:56.929] <TB0> INFO: ----------------------------------------------------------------------
[12:32:57.200] <TB0> INFO: Expecting 41600 events.
[12:33:00.960] <TB0> INFO: 41600 events read in total (3204ms).
[12:33:00.961] <TB0> INFO: Test took 4031ms.
[12:33:01.185] <TB0> INFO: PixTestAlive::aliveTest() done
[12:33:01.185] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:33:01.186] <TB0> INFO: ----------------------------------------------------------------------
[12:33:01.186] <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)
[12:33:01.186] <TB0> INFO: ----------------------------------------------------------------------
[12:33:01.479] <TB0> INFO: Expecting 41600 events.
[12:33:04.448] <TB0> INFO: 41600 events read in total (2413ms).
[12:33:04.448] <TB0> INFO: Test took 3261ms.
[12:33:04.449] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:33:04.690] <TB0> INFO: PixTestAlive::maskTest() done
[12:33:04.690] <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
[12:33:04.691] <TB0> INFO: ----------------------------------------------------------------------
[12:33:04.691] <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)
[12:33:04.692] <TB0> INFO: ----------------------------------------------------------------------
[12:33:04.966] <TB0> INFO: Expecting 41600 events.
[12:33:08.741] <TB0> INFO: 41600 events read in total (3219ms).
[12:33:08.741] <TB0> INFO: Test took 4048ms.
[12:33:08.968] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[12:33:08.968] <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
[12:33:08.968] <TB0> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[12:33:08.968] <TB0> INFO: Decoding statistics:
[12:33:08.968] <TB0> INFO: General information:
[12:33:08.968] <TB0> INFO: 16bit words read: 0
[12:33:08.969] <TB0> INFO: valid events total: 0
[12:33:08.969] <TB0> INFO: empty events: 0
[12:33:08.969] <TB0> INFO: valid events with pixels: 0
[12:33:08.969] <TB0> INFO: valid pixel hits: 0
[12:33:08.969] <TB0> INFO: Event errors: 0
[12:33:08.969] <TB0> INFO: start marker: 0
[12:33:08.969] <TB0> INFO: stop marker: 0
[12:33:08.969] <TB0> INFO: overflow: 0
[12:33:08.969] <TB0> INFO: invalid 5bit words: 0
[12:33:08.969] <TB0> INFO: invalid XOR eye diagram: 0
[12:33:08.969] <TB0> INFO: frame (failed synchr.): 0
[12:33:08.969] <TB0> INFO: idle data (no TBM trl): 0
[12:33:08.969] <TB0> INFO: no data (only TBM hdr): 0
[12:33:08.969] <TB0> INFO: TBM errors: 0
[12:33:08.969] <TB0> INFO: flawed TBM headers: 0
[12:33:08.969] <TB0> INFO: flawed TBM trailers: 0
[12:33:08.969] <TB0> INFO: event ID mismatches: 0
[12:33:08.969] <TB0> INFO: ROC errors: 0
[12:33:08.969] <TB0> INFO: missing ROC header(s): 0
[12:33:08.969] <TB0> INFO: misplaced readback start: 0
[12:33:08.969] <TB0> INFO: Pixel decoding errors: 0
[12:33:08.969] <TB0> INFO: pixel data incomplete: 0
[12:33:08.969] <TB0> INFO: pixel address: 0
[12:33:08.969] <TB0> INFO: pulse height fill bit: 0
[12:33:08.969] <TB0> INFO: buffer corruption: 0
[12:33:08.975] <TB0> INFO: ######################################################################
[12:33:08.976] <TB0> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[12:33:08.976] <TB0> INFO: ######################################################################
[12:33:08.978] <TB0> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[12:33:08.989] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[12:33:08.989] <TB0> INFO: run 1 of 1
[12:33:09.258] <TB0> INFO: Expecting 3120000 events.
[12:33:50.492] <TB0> INFO: 874790 events read in total (40679ms).
[12:34:31.227] <TB0> INFO: 1738210 events read in total (81414ms).
[12:35:12.350] <TB0> INFO: 2614275 events read in total (122537ms).
[12:35:35.905] <TB0> INFO: 3120000 events read in total (146091ms).
[12:35:35.954] <TB0> INFO: Test took 146965ms.
[12:35:59.719] <TB0> INFO: PixTestBBMap::doTest() done, duration: 170 seconds
[12:35:59.719] <TB0> INFO: number of dead bumps (per ROC): 63 50 19 28 32 15 24 16 30 16 24 16 16 21 12 45
[12:35:59.719] <TB0> INFO: separation cut (per ROC): 67 67 92 74 65 73 65 73 72 79 75 82 79 63 76 75
[12:35:59.719] <TB0> INFO: Decoding statistics:
[12:35:59.719] <TB0> INFO: General information:
[12:35:59.719] <TB0> INFO: 16bit words read: 0
[12:35:59.719] <TB0> INFO: valid events total: 0
[12:35:59.719] <TB0> INFO: empty events: 0
[12:35:59.719] <TB0> INFO: valid events with pixels: 0
[12:35:59.719] <TB0> INFO: valid pixel hits: 0
[12:35:59.719] <TB0> INFO: Event errors: 0
[12:35:59.719] <TB0> INFO: start marker: 0
[12:35:59.719] <TB0> INFO: stop marker: 0
[12:35:59.719] <TB0> INFO: overflow: 0
[12:35:59.719] <TB0> INFO: invalid 5bit words: 0
[12:35:59.719] <TB0> INFO: invalid XOR eye diagram: 0
[12:35:59.719] <TB0> INFO: frame (failed synchr.): 0
[12:35:59.719] <TB0> INFO: idle data (no TBM trl): 0
[12:35:59.719] <TB0> INFO: no data (only TBM hdr): 0
[12:35:59.719] <TB0> INFO: TBM errors: 0
[12:35:59.719] <TB0> INFO: flawed TBM headers: 0
[12:35:59.719] <TB0> INFO: flawed TBM trailers: 0
[12:35:59.719] <TB0> INFO: event ID mismatches: 0
[12:35:59.719] <TB0> INFO: ROC errors: 0
[12:35:59.719] <TB0> INFO: missing ROC header(s): 0
[12:35:59.719] <TB0> INFO: misplaced readback start: 0
[12:35:59.719] <TB0> INFO: Pixel decoding errors: 0
[12:35:59.720] <TB0> INFO: pixel data incomplete: 0
[12:35:59.720] <TB0> INFO: pixel address: 0
[12:35:59.720] <TB0> INFO: pulse height fill bit: 0
[12:35:59.720] <TB0> INFO: buffer corruption: 0
[12:35:59.831] <TB0> INFO: ######################################################################
[12:35:59.831] <TB0> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:35:59.831] <TB0> INFO: ######################################################################
[12:35:59.831] <TB0> INFO: ----------------------------------------------------------------------
[12:35:59.831] <TB0> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:35:59.831] <TB0> INFO: ----------------------------------------------------------------------
[12:35:59.832] <TB0> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:35:59.843] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[12:35:59.843] <TB0> INFO: run 1 of 1
[12:36:00.140] <TB0> INFO: Expecting 26208000 events.
[12:36:33.628] <TB0> INFO: 879150 events read in total (32932ms).
[12:37:06.353] <TB0> INFO: 1744000 events read in total (65657ms).
[12:37:39.262] <TB0> INFO: 2609900 events read in total (98566ms).
[12:38:11.954] <TB0> INFO: 3474850 events read in total (131258ms).
[12:38:44.323] <TB0> INFO: 4338850 events read in total (163627ms).
[12:39:16.966] <TB0> INFO: 5201750 events read in total (196270ms).
[12:39:49.380] <TB0> INFO: 6066300 events read in total (228684ms).
[12:40:21.586] <TB0> INFO: 6927250 events read in total (260890ms).
[12:40:54.428] <TB0> INFO: 7789450 events read in total (293732ms).
[12:41:26.865] <TB0> INFO: 8650800 events read in total (326169ms).
[12:41:59.388] <TB0> INFO: 9511800 events read in total (358692ms).
[12:42:31.953] <TB0> INFO: 10372350 events read in total (391257ms).
[12:43:05.053] <TB0> INFO: 11233150 events read in total (424357ms).
[12:43:37.381] <TB0> INFO: 12094900 events read in total (456685ms).
[12:44:09.783] <TB0> INFO: 12951350 events read in total (489087ms).
[12:44:42.085] <TB0> INFO: 13803550 events read in total (521389ms).
[12:45:15.094] <TB0> INFO: 14653850 events read in total (554398ms).
[12:45:47.410] <TB0> INFO: 15502850 events read in total (586714ms).
[12:46:19.524] <TB0> INFO: 16350600 events read in total (618828ms).
[12:46:52.206] <TB0> INFO: 17197800 events read in total (651510ms).
[12:47:24.865] <TB0> INFO: 18044300 events read in total (684169ms).
[12:47:57.596] <TB0> INFO: 18892250 events read in total (716900ms).
[12:48:30.658] <TB0> INFO: 19737450 events read in total (749962ms).
[12:49:03.583] <TB0> INFO: 20582500 events read in total (782887ms).
[12:49:36.379] <TB0> INFO: 21428400 events read in total (815683ms).
[12:50:09.465] <TB0> INFO: 22273800 events read in total (848769ms).
[12:50:42.172] <TB0> INFO: 23119000 events read in total (881476ms).
[12:51:14.841] <TB0> INFO: 23964950 events read in total (914145ms).
[12:51:47.523] <TB0> INFO: 24811850 events read in total (946827ms).
[12:52:20.190] <TB0> INFO: 25657650 events read in total (979494ms).
[12:52:40.947] <TB0> INFO: 26208000 events read in total (1000251ms).
[12:52:40.984] <TB0> INFO: Test took 1001141ms.
[12:52:41.281] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:52:43.014] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:52:44.667] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:52:46.074] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:52:47.464] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:52:49.031] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:52:50.676] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:52:52.346] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:52:53.785] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:52:55.656] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:52:57.328] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:52:58.776] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:53:00.263] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:53:01.665] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:53:03.302] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:53:04.786] <TB0> INFO: dumping ASCII scurve output file: SCurveData
[12:53:06.525] <TB0> INFO: PixTestScurves::scurves() done
[12:53:06.525] <TB0> INFO: Vcal mean: 80.92 92.45 99.71 82.48 79.73 84.73 75.66 82.47 86.54 91.76 75.31 88.69 88.39 74.63 86.30 86.63
[12:53:06.525] <TB0> INFO: Vcal RMS: 4.23 6.04 7.19 6.03 5.69 4.99 4.50 4.43 5.12 5.68 5.13 5.83 5.65 4.14 4.91 6.01
[12:53:06.526] <TB0> INFO: PixTestScurves::fullTest() done, duration: 1026 seconds
[12:53:06.526] <TB0> INFO: Decoding statistics:
[12:53:06.526] <TB0> INFO: General information:
[12:53:06.526] <TB0> INFO: 16bit words read: 0
[12:53:06.526] <TB0> INFO: valid events total: 0
[12:53:06.526] <TB0> INFO: empty events: 0
[12:53:06.526] <TB0> INFO: valid events with pixels: 0
[12:53:06.526] <TB0> INFO: valid pixel hits: 0
[12:53:06.526] <TB0> INFO: Event errors: 0
[12:53:06.526] <TB0> INFO: start marker: 0
[12:53:06.526] <TB0> INFO: stop marker: 0
[12:53:06.526] <TB0> INFO: overflow: 0
[12:53:06.526] <TB0> INFO: invalid 5bit words: 0
[12:53:06.526] <TB0> INFO: invalid XOR eye diagram: 0
[12:53:06.526] <TB0> INFO: frame (failed synchr.): 0
[12:53:06.526] <TB0> INFO: idle data (no TBM trl): 0
[12:53:06.526] <TB0> INFO: no data (only TBM hdr): 0
[12:53:06.526] <TB0> INFO: TBM errors: 0
[12:53:06.526] <TB0> INFO: flawed TBM headers: 0
[12:53:06.526] <TB0> INFO: flawed TBM trailers: 0
[12:53:06.526] <TB0> INFO: event ID mismatches: 0
[12:53:06.526] <TB0> INFO: ROC errors: 0
[12:53:06.526] <TB0> INFO: missing ROC header(s): 0
[12:53:06.526] <TB0> INFO: misplaced readback start: 0
[12:53:06.526] <TB0> INFO: Pixel decoding errors: 0
[12:53:06.526] <TB0> INFO: pixel data incomplete: 0
[12:53:06.526] <TB0> INFO: pixel address: 0
[12:53:06.526] <TB0> INFO: pulse height fill bit: 0
[12:53:06.526] <TB0> INFO: buffer corruption: 0
[12:53:06.624] <TB0> INFO: ######################################################################
[12:53:06.624] <TB0> INFO: PixTestTrim::doTest()
[12:53:06.624] <TB0> INFO: ######################################################################
[12:53:06.625] <TB0> INFO: ----------------------------------------------------------------------
[12:53:06.625] <TB0> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[12:53:06.625] <TB0> INFO: ----------------------------------------------------------------------
[12:53:06.748] <TB0> INFO: ---> VthrComp thr map (minimal VthrComp)
[12:53:06.748] <TB0> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:53:06.760] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[12:53:06.760] <TB0> INFO: run 1 of 1
[12:53:07.098] <TB0> INFO: Expecting 6281600 events.
[12:53:47.818] <TB0> INFO: 1089280 events read in total (40163ms).
[12:54:27.700] <TB0> INFO: 2173070 events read in total (80045ms).
[12:55:07.059] <TB0> INFO: 3251280 events read in total (119404ms).
[12:55:46.156] <TB0> INFO: 4335030 events read in total (158501ms).
[12:56:25.212] <TB0> INFO: 5423280 events read in total (197557ms).
[12:56:56.045] <TB0> INFO: 6281600 events read in total (228390ms).
[12:56:56.074] <TB0> INFO: Test took 229314ms.
[12:57:14.669] <TB0> INFO: ROC 0 VthrComp = 85
[12:57:14.669] <TB0> INFO: ROC 1 VthrComp = 92
[12:57:14.670] <TB0> INFO: ROC 2 VthrComp = 95
[12:57:14.670] <TB0> INFO: ROC 3 VthrComp = 80
[12:57:14.670] <TB0> INFO: ROC 4 VthrComp = 78
[12:57:14.670] <TB0> INFO: ROC 5 VthrComp = 82
[12:57:14.670] <TB0> INFO: ROC 6 VthrComp = 78
[12:57:14.670] <TB0> INFO: ROC 7 VthrComp = 84
[12:57:14.670] <TB0> INFO: ROC 8 VthrComp = 90
[12:57:14.670] <TB0> INFO: ROC 9 VthrComp = 92
[12:57:14.670] <TB0> INFO: ROC 10 VthrComp = 78
[12:57:14.671] <TB0> INFO: ROC 11 VthrComp = 89
[12:57:14.671] <TB0> INFO: ROC 12 VthrComp = 90
[12:57:14.671] <TB0> INFO: ROC 13 VthrComp = 79
[12:57:14.671] <TB0> INFO: ROC 14 VthrComp = 89
[12:57:14.671] <TB0> INFO: ROC 15 VthrComp = 86
[12:57:14.671] <TB0> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[12:57:14.671] <TB0> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:57:14.679] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[12:57:14.679] <TB0> INFO: run 1 of 1
[12:57:14.939] <TB0> INFO: Expecting 6281600 events.
[12:57:50.474] <TB0> INFO: 759200 events read in total (34978ms).
[12:58:25.226] <TB0> INFO: 1516030 events read in total (69730ms).
[12:58:59.920] <TB0> INFO: 2273430 events read in total (104424ms).
[12:59:34.831] <TB0> INFO: 3030900 events read in total (139335ms).
[13:00:09.612] <TB0> INFO: 3782890 events read in total (174116ms).
[13:00:44.610] <TB0> INFO: 4531910 events read in total (209114ms).
[13:01:19.696] <TB0> INFO: 5278770 events read in total (244200ms).
[13:01:54.854] <TB0> INFO: 6025720 events read in total (279358ms).
[13:02:07.019] <TB0> INFO: 6281600 events read in total (291523ms).
[13:02:07.057] <TB0> INFO: Test took 292377ms.
[13:02:32.173] <TB0> INFO: roc 0 with ID = 0 has maximal Vcal 56.1234 for pixel 23/15 mean/min/max = 44.1433/32.0855/56.2011
[13:02:32.173] <TB0> INFO: roc 1 with ID = 1 has maximal Vcal 60.4868 for pixel 50/78 mean/min/max = 46.3864/32.234/60.5388
[13:02:32.173] <TB0> INFO: roc 2 with ID = 2 has maximal Vcal 65.1147 for pixel 6/79 mean/min/max = 47.9777/30.4257/65.5297
[13:02:32.174] <TB0> INFO: roc 3 with ID = 3 has maximal Vcal 63.9498 for pixel 0/75 mean/min/max = 47.4941/31.0258/63.9624
[13:02:32.174] <TB0> INFO: roc 4 with ID = 4 has maximal Vcal 65.0753 for pixel 8/60 mean/min/max = 49.0361/32.914/65.1582
[13:02:32.174] <TB0> INFO: roc 5 with ID = 5 has maximal Vcal 59.6451 for pixel 37/79 mean/min/max = 46.4442/33.1436/59.7448
[13:02:32.175] <TB0> INFO: roc 6 with ID = 6 has maximal Vcal 58.7127 for pixel 0/68 mean/min/max = 46.8645/34.8043/58.9246
[13:02:32.175] <TB0> INFO: roc 7 with ID = 7 has maximal Vcal 57.0087 for pixel 22/40 mean/min/max = 45.0937/33.0593/57.1281
[13:02:32.175] <TB0> INFO: roc 8 with ID = 8 has maximal Vcal 58.4491 for pixel 22/79 mean/min/max = 45.7559/33.0554/58.4564
[13:02:32.175] <TB0> INFO: roc 9 with ID = 9 has maximal Vcal 59.3803 for pixel 51/70 mean/min/max = 45.8912/32.3991/59.3834
[13:02:32.176] <TB0> INFO: roc 10 with ID = 10 has maximal Vcal 61.3673 for pixel 2/64 mean/min/max = 47.6531/33.7971/61.5092
[13:02:32.176] <TB0> INFO: roc 11 with ID = 11 has maximal Vcal 61.1143 for pixel 7/79 mean/min/max = 46.8778/32.6313/61.1243
[13:02:32.176] <TB0> INFO: roc 12 with ID = 12 has maximal Vcal 60.453 for pixel 2/74 mean/min/max = 46.5493/32.5992/60.4994
[13:02:32.177] <TB0> INFO: roc 13 with ID = 13 has maximal Vcal 57.6682 for pixel 9/8 mean/min/max = 46.6274/35.4901/57.7647
[13:02:32.177] <TB0> INFO: roc 14 with ID = 14 has maximal Vcal 57.8218 for pixel 48/2 mean/min/max = 45.5604/33.2835/57.8372
[13:02:32.177] <TB0> INFO: roc 15 with ID = 15 has maximal Vcal 61.608 for pixel 0/29 mean/min/max = 46.4919/31.2721/61.7117
[13:02:32.177] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:02:32.265] <TB0> INFO: Expecting 514560 events.
[13:02:44.987] <TB0> INFO: 514560 events read in total (12166ms).
[13:02:44.991] <TB0> INFO: Expecting 514560 events.
[13:02:57.682] <TB0> INFO: 514560 events read in total (12268ms).
[13:02:57.687] <TB0> INFO: Expecting 514560 events.
[13:03:10.335] <TB0> INFO: 514560 events read in total (12220ms).
[13:03:10.343] <TB0> INFO: Expecting 514560 events.
[13:03:22.989] <TB0> INFO: 514560 events read in total (12222ms).
[13:03:22.998] <TB0> INFO: Expecting 514560 events.
[13:03:35.750] <TB0> INFO: 514560 events read in total (12330ms).
[13:03:35.761] <TB0> INFO: Expecting 514560 events.
[13:03:48.488] <TB0> INFO: 514560 events read in total (12307ms).
[13:03:48.501] <TB0> INFO: Expecting 514560 events.
[13:04:01.162] <TB0> INFO: 514560 events read in total (12244ms).
[13:04:01.178] <TB0> INFO: Expecting 514560 events.
[13:04:14.043] <TB0> INFO: 514560 events read in total (12451ms).
[13:04:14.060] <TB0> INFO: Expecting 514560 events.
[13:04:26.823] <TB0> INFO: 514560 events read in total (12354ms).
[13:04:26.843] <TB0> INFO: Expecting 514560 events.
[13:04:39.648] <TB0> INFO: 514560 events read in total (12403ms).
[13:04:39.669] <TB0> INFO: Expecting 514560 events.
[13:04:52.637] <TB0> INFO: 514560 events read in total (12563ms).
[13:04:52.658] <TB0> INFO: Expecting 514560 events.
[13:05:05.370] <TB0> INFO: 514560 events read in total (12305ms).
[13:05:05.394] <TB0> INFO: Expecting 514560 events.
[13:05:18.197] <TB0> INFO: 514560 events read in total (12395ms).
[13:05:18.221] <TB0> INFO: Expecting 514560 events.
[13:05:31.020] <TB0> INFO: 514560 events read in total (12397ms).
[13:05:31.047] <TB0> INFO: Expecting 514560 events.
[13:05:43.852] <TB0> INFO: 514560 events read in total (12403ms).
[13:05:43.881] <TB0> INFO: Expecting 514560 events.
[13:05:56.509] <TB0> INFO: 514560 events read in total (12227ms).
[13:05:56.538] <TB0> INFO: Test took 204361ms.
[13:05:57.420] <TB0> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[13:05:57.428] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[13:05:57.428] <TB0> INFO: run 1 of 1
[13:05:57.700] <TB0> INFO: Expecting 6281600 events.
[13:06:32.987] <TB0> INFO: 743820 events read in total (34730ms).
[13:07:07.822] <TB0> INFO: 1486100 events read in total (69565ms).
[13:07:42.690] <TB0> INFO: 2228110 events read in total (104433ms).
[13:08:17.591] <TB0> INFO: 2970730 events read in total (139334ms).
[13:08:52.463] <TB0> INFO: 3706840 events read in total (174206ms).
[13:09:26.608] <TB0> INFO: 4439950 events read in total (208351ms).
[13:10:00.754] <TB0> INFO: 5170500 events read in total (242497ms).
[13:10:34.951] <TB0> INFO: 5900550 events read in total (276694ms).
[13:10:52.882] <TB0> INFO: 6281600 events read in total (294625ms).
[13:10:52.929] <TB0> INFO: Test took 295501ms.
[13:11:17.330] <TB0> INFO: ---> TrimStepCorr4 extremal thresholds: 0.004340 .. 255.000000
[13:11:17.406] <TB0> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[13:11:17.414] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[13:11:17.414] <TB0> INFO: run 1 of 1
[13:11:17.676] <TB0> INFO: Expecting 10649600 events.
[13:11:52.449] <TB0> INFO: 712150 events read in total (34217ms).
[13:12:26.458] <TB0> INFO: 1424180 events read in total (68226ms).
[13:13:00.407] <TB0> INFO: 2136450 events read in total (102175ms).
[13:13:34.897] <TB0> INFO: 2848670 events read in total (136665ms).
[13:14:09.153] <TB0> INFO: 3561270 events read in total (170921ms).
[13:14:44.049] <TB0> INFO: 4273650 events read in total (205817ms).
[13:15:18.151] <TB0> INFO: 4986250 events read in total (239919ms).
[13:15:52.412] <TB0> INFO: 5698870 events read in total (274180ms).
[13:16:26.554] <TB0> INFO: 6411280 events read in total (308322ms).
[13:17:00.532] <TB0> INFO: 7123360 events read in total (342300ms).
[13:17:34.868] <TB0> INFO: 7835310 events read in total (376636ms).
[13:18:08.609] <TB0> INFO: 8546430 events read in total (410377ms).
[13:18:42.399] <TB0> INFO: 9257710 events read in total (444167ms).
[13:19:16.438] <TB0> INFO: 9968870 events read in total (478206ms).
[13:19:49.107] <TB0> INFO: 10649600 events read in total (510875ms).
[13:19:49.192] <TB0> INFO: Test took 511779ms.
[13:20:20.032] <TB0> INFO: ---> TrimStepCorr2 extremal thresholds: 16.174311 .. 47.454314
[13:20:20.167] <TB0> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 6 .. 57 (-1/-1) hits flags = 528 (plus default)
[13:20:20.179] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[13:20:20.179] <TB0> INFO: run 1 of 1
[13:20:20.491] <TB0> INFO: Expecting 2163200 events.
[13:20:58.803] <TB0> INFO: 917840 events read in total (37756ms).
[13:21:36.357] <TB0> INFO: 1834760 events read in total (75310ms).
[13:21:50.144] <TB0> INFO: 2163200 events read in total (89097ms).
[13:21:50.157] <TB0> INFO: Test took 89978ms.
[13:22:04.939] <TB0> INFO: ---> TrimStepCorr1a extremal thresholds: 20.662054 .. 45.657871
[13:22:05.017] <TB0> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 10 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:22:05.025] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[13:22:05.025] <TB0> INFO: run 1 of 1
[13:22:05.289] <TB0> INFO: Expecting 1913600 events.
[13:22:43.363] <TB0> INFO: 911430 events read in total (37518ms).
[13:23:21.156] <TB0> INFO: 1821650 events read in total (75311ms).
[13:23:25.287] <TB0> INFO: 1913600 events read in total (79442ms).
[13:23:25.298] <TB0> INFO: Test took 80273ms.
[13:23:40.405] <TB0> INFO: ---> TrimStepCorr1b extremal thresholds: 20.662054 .. 45.657871
[13:23:40.484] <TB0> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 10 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:23:40.492] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[13:23:40.492] <TB0> INFO: run 1 of 1
[13:23:40.756] <TB0> INFO: Expecting 1913600 events.
[13:24:19.116] <TB0> INFO: 911350 events read in total (37804ms).
[13:24:56.959] <TB0> INFO: 1822250 events read in total (75647ms).
[13:25:01.129] <TB0> INFO: 1913600 events read in total (79818ms).
[13:25:01.149] <TB0> INFO: Test took 80657ms.
[13:25:14.565] <TB0> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[13:25:14.565] <TB0> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:25:14.574] <TB0> INFO: dacScan split into 1 runs with ntrig = 10
[13:25:14.575] <TB0> INFO: run 1 of 1
[13:25:14.837] <TB0> INFO: Expecting 1705600 events.
[13:25:52.923] <TB0> INFO: 878750 events read in total (37530ms).
[13:26:28.066] <TB0> INFO: 1705600 events read in total (72673ms).
[13:26:28.077] <TB0> INFO: Test took 73502ms.
[13:26:41.391] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C0.dat
[13:26:41.392] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C1.dat
[13:26:41.392] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C2.dat
[13:26:41.392] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C3.dat
[13:26:41.392] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C4.dat
[13:26:41.392] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C5.dat
[13:26:41.392] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C6.dat
[13:26:41.392] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C7.dat
[13:26:41.392] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C8.dat
[13:26:41.393] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C9.dat
[13:26:41.393] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C10.dat
[13:26:41.393] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C11.dat
[13:26:41.393] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C12.dat
[13:26:41.393] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C13.dat
[13:26:41.393] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C14.dat
[13:26:41.393] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C15.dat
[13:26:41.393] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//trimParameters35_C0.dat
[13:26:41.400] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//trimParameters35_C1.dat
[13:26:41.408] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//trimParameters35_C2.dat
[13:26:41.417] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//trimParameters35_C3.dat
[13:26:41.425] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//trimParameters35_C4.dat
[13:26:41.431] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//trimParameters35_C5.dat
[13:26:41.439] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//trimParameters35_C6.dat
[13:26:41.450] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//trimParameters35_C7.dat
[13:26:41.461] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//trimParameters35_C8.dat
[13:26:41.472] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//trimParameters35_C9.dat
[13:26:41.484] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//trimParameters35_C10.dat
[13:26:41.495] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//trimParameters35_C11.dat
[13:26:41.503] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//trimParameters35_C12.dat
[13:26:41.509] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//trimParameters35_C13.dat
[13:26:41.515] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//trimParameters35_C14.dat
[13:26:41.521] <TB0> INFO: write trim parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//trimParameters35_C15.dat
[13:26:41.527] <TB0> INFO: PixTestTrim::trimTest() done
[13:26:41.527] <TB0> INFO: vtrim: 77 95 99 98 105 85 78 86 86 98 106 89 96 100 94 85
[13:26:41.527] <TB0> INFO: vthrcomp: 85 92 95 80 78 82 78 84 90 92 78 89 90 79 89 86
[13:26:41.527] <TB0> INFO: vcal mean: 34.95 35.02 35.03 34.98 35.00 34.98 35.01 34.96 35.04 34.94 35.00 35.00 34.99 35.02 34.98 34.99
[13:26:41.527] <TB0> INFO: vcal RMS: 0.85 0.92 0.96 0.93 0.92 0.83 0.80 0.83 0.82 0.89 0.84 0.91 0.85 0.81 0.86 1.03
[13:26:41.527] <TB0> INFO: bits mean: 10.01 9.30 8.75 9.00 8.79 8.73 8.31 9.58 8.76 9.39 8.90 8.91 9.30 8.94 9.19 9.24
[13:26:41.527] <TB0> INFO: bits RMS: 2.55 2.71 2.99 2.86 2.63 2.80 2.71 2.59 2.84 2.69 2.53 2.77 2.62 2.39 2.70 2.87
[13:26:41.533] <TB0> INFO: ----------------------------------------------------------------------
[13:26:41.533] <TB0> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[13:26:41.533] <TB0> INFO: ----------------------------------------------------------------------
[13:26:41.536] <TB0> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[13:26:41.543] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[13:26:41.544] <TB0> INFO: run 1 of 1
[13:26:41.816] <TB0> INFO: Expecting 4160000 events.
[13:27:24.058] <TB0> INFO: 920480 events read in total (41686ms).
[13:28:05.758] <TB0> INFO: 1835260 events read in total (83386ms).
[13:28:46.922] <TB0> INFO: 2741940 events read in total (124551ms).
[13:29:28.033] <TB0> INFO: 3645185 events read in total (165661ms).
[13:29:51.646] <TB0> INFO: 4160000 events read in total (189274ms).
[13:29:51.690] <TB0> INFO: Test took 190146ms.
[13:30:21.417] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 185 (-1/-1) hits flags = 528 (plus default)
[13:30:21.425] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[13:30:21.425] <TB0> INFO: run 1 of 1
[13:30:21.686] <TB0> INFO: Expecting 3868800 events.
[13:31:03.856] <TB0> INFO: 912095 events read in total (41614ms).
[13:31:44.442] <TB0> INFO: 1818450 events read in total (82200ms).
[13:32:25.875] <TB0> INFO: 2714770 events read in total (123634ms).
[13:33:07.109] <TB0> INFO: 3609070 events read in total (164867ms).
[13:33:19.164] <TB0> INFO: 3868800 events read in total (176922ms).
[13:33:19.208] <TB0> INFO: Test took 177784ms.
[13:33:48.312] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 173 (-1/-1) hits flags = 528 (plus default)
[13:33:48.321] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[13:33:48.321] <TB0> INFO: run 1 of 1
[13:33:48.583] <TB0> INFO: Expecting 3619200 events.
[13:34:30.454] <TB0> INFO: 934975 events read in total (41315ms).
[13:35:11.913] <TB0> INFO: 1862790 events read in total (82774ms).
[13:35:53.177] <TB0> INFO: 2779475 events read in total (124038ms).
[13:36:32.169] <TB0> INFO: 3619200 events read in total (163030ms).
[13:36:32.217] <TB0> INFO: Test took 163896ms.
[13:36:59.846] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 170 (-1/-1) hits flags = 528 (plus default)
[13:36:59.855] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[13:36:59.855] <TB0> INFO: run 1 of 1
[13:37:00.123] <TB0> INFO: Expecting 3556800 events.
[13:37:42.658] <TB0> INFO: 940635 events read in total (41979ms).
[13:38:24.765] <TB0> INFO: 1873745 events read in total (84086ms).
[13:39:05.906] <TB0> INFO: 2795340 events read in total (125228ms).
[13:39:40.342] <TB0> INFO: 3556800 events read in total (159663ms).
[13:39:40.384] <TB0> INFO: Test took 160529ms.
[13:40:10.972] <TB0> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 168 (-1/-1) hits flags = 528 (plus default)
[13:40:10.983] <TB0> INFO: dacScan split into 1 runs with ntrig = 5
[13:40:10.984] <TB0> INFO: run 1 of 1
[13:40:11.274] <TB0> INFO: Expecting 3515200 events.
[13:40:53.769] <TB0> INFO: 944460 events read in total (41939ms).
[13:41:35.634] <TB0> INFO: 1880885 events read in total (83804ms).
[13:42:17.750] <TB0> INFO: 2806080 events read in total (125920ms).
[13:42:49.334] <TB0> INFO: 3515200 events read in total (157504ms).
[13:42:49.372] <TB0> INFO: Test took 158388ms.
[13:43:16.117] <TB0> INFO: PixTestTrim::trimBitTest() done
[13:43:16.118] <TB0> INFO: PixTestTrim::doTest() done, duration: 3009 seconds
[13:43:16.118] <TB0> INFO: Decoding statistics:
[13:43:16.118] <TB0> INFO: General information:
[13:43:16.118] <TB0> INFO: 16bit words read: 0
[13:43:16.118] <TB0> INFO: valid events total: 0
[13:43:16.118] <TB0> INFO: empty events: 0
[13:43:16.118] <TB0> INFO: valid events with pixels: 0
[13:43:16.118] <TB0> INFO: valid pixel hits: 0
[13:43:16.118] <TB0> INFO: Event errors: 0
[13:43:16.118] <TB0> INFO: start marker: 0
[13:43:16.118] <TB0> INFO: stop marker: 0
[13:43:16.118] <TB0> INFO: overflow: 0
[13:43:16.118] <TB0> INFO: invalid 5bit words: 0
[13:43:16.118] <TB0> INFO: invalid XOR eye diagram: 0
[13:43:16.118] <TB0> INFO: frame (failed synchr.): 0
[13:43:16.118] <TB0> INFO: idle data (no TBM trl): 0
[13:43:16.118] <TB0> INFO: no data (only TBM hdr): 0
[13:43:16.118] <TB0> INFO: TBM errors: 0
[13:43:16.118] <TB0> INFO: flawed TBM headers: 0
[13:43:16.118] <TB0> INFO: flawed TBM trailers: 0
[13:43:16.118] <TB0> INFO: event ID mismatches: 0
[13:43:16.118] <TB0> INFO: ROC errors: 0
[13:43:16.118] <TB0> INFO: missing ROC header(s): 0
[13:43:16.118] <TB0> INFO: misplaced readback start: 0
[13:43:16.118] <TB0> INFO: Pixel decoding errors: 0
[13:43:16.118] <TB0> INFO: pixel data incomplete: 0
[13:43:16.118] <TB0> INFO: pixel address: 0
[13:43:16.118] <TB0> INFO: pulse height fill bit: 0
[13:43:16.118] <TB0> INFO: buffer corruption: 0
[13:43:16.847] <TB0> INFO: ######################################################################
[13:43:16.847] <TB0> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[13:43:16.847] <TB0> INFO: ######################################################################
[13:43:17.117] <TB0> INFO: Expecting 41600 events.
[13:43:20.875] <TB0> INFO: 41600 events read in total (3201ms).
[13:43:20.875] <TB0> INFO: Test took 4027ms.
[13:43:21.341] <TB0> INFO: Expecting 41600 events.
[13:43:25.125] <TB0> INFO: 41600 events read in total (3228ms).
[13:43:25.125] <TB0> INFO: Test took 4046ms.
[13:43:25.537] <TB0> INFO: Expecting 41600 events.
[13:43:29.462] <TB0> INFO: 41600 events read in total (3369ms).
[13:43:29.463] <TB0> INFO: Test took 4316ms.
[13:43:29.717] <TB0> INFO: Expecting 2560 events.
[13:43:30.572] <TB0> INFO: 2560 events read in total (298ms).
[13:43:30.572] <TB0> INFO: Test took 1096ms.
[13:43:30.877] <TB0> INFO: Expecting 2560 events.
[13:43:31.730] <TB0> INFO: 2560 events read in total (297ms).
[13:43:31.730] <TB0> INFO: Test took 1157ms.
[13:43:32.035] <TB0> INFO: Expecting 2560 events.
[13:43:32.888] <TB0> INFO: 2560 events read in total (297ms).
[13:43:32.888] <TB0> INFO: Test took 1157ms.
[13:43:33.193] <TB0> INFO: Expecting 2560 events.
[13:43:34.045] <TB0> INFO: 2560 events read in total (296ms).
[13:43:34.046] <TB0> INFO: Test took 1157ms.
[13:43:34.351] <TB0> INFO: Expecting 2560 events.
[13:43:35.204] <TB0> INFO: 2560 events read in total (297ms).
[13:43:35.204] <TB0> INFO: Test took 1158ms.
[13:43:35.509] <TB0> INFO: Expecting 2560 events.
[13:43:36.362] <TB0> INFO: 2560 events read in total (297ms).
[13:43:36.363] <TB0> INFO: Test took 1159ms.
[13:43:36.667] <TB0> INFO: Expecting 2560 events.
[13:43:37.521] <TB0> INFO: 2560 events read in total (298ms).
[13:43:37.521] <TB0> INFO: Test took 1158ms.
[13:43:37.827] <TB0> INFO: Expecting 2560 events.
[13:43:38.681] <TB0> INFO: 2560 events read in total (298ms).
[13:43:38.682] <TB0> INFO: Test took 1161ms.
[13:43:38.986] <TB0> INFO: Expecting 2560 events.
[13:43:39.840] <TB0> INFO: 2560 events read in total (298ms).
[13:43:39.840] <TB0> INFO: Test took 1158ms.
[13:43:40.145] <TB0> INFO: Expecting 2560 events.
[13:43:40.999] <TB0> INFO: 2560 events read in total (298ms).
[13:43:40.999] <TB0> INFO: Test took 1159ms.
[13:43:41.304] <TB0> INFO: Expecting 2560 events.
[13:43:42.158] <TB0> INFO: 2560 events read in total (298ms).
[13:43:42.158] <TB0> INFO: Test took 1159ms.
[13:43:42.463] <TB0> INFO: Expecting 2560 events.
[13:43:43.316] <TB0> INFO: 2560 events read in total (297ms).
[13:43:43.317] <TB0> INFO: Test took 1158ms.
[13:43:43.622] <TB0> INFO: Expecting 2560 events.
[13:43:44.478] <TB0> INFO: 2560 events read in total (300ms).
[13:43:44.478] <TB0> INFO: Test took 1161ms.
[13:43:44.784] <TB0> INFO: Expecting 2560 events.
[13:43:45.637] <TB0> INFO: 2560 events read in total (297ms).
[13:43:45.638] <TB0> INFO: Test took 1159ms.
[13:43:45.943] <TB0> INFO: Expecting 2560 events.
[13:43:46.796] <TB0> INFO: 2560 events read in total (297ms).
[13:43:46.797] <TB0> INFO: Test took 1159ms.
[13:43:47.102] <TB0> INFO: Expecting 2560 events.
[13:43:47.958] <TB0> INFO: 2560 events read in total (300ms).
[13:43:47.958] <TB0> INFO: Test took 1161ms.
[13:43:47.961] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:43:48.264] <TB0> INFO: Expecting 655360 events.
[13:44:05.181] <TB0> INFO: 655360 events read in total (16360ms).
[13:44:05.190] <TB0> INFO: Expecting 655360 events.
[13:44:21.909] <TB0> INFO: 655360 events read in total (16317ms).
[13:44:21.921] <TB0> INFO: Expecting 655360 events.
[13:44:38.625] <TB0> INFO: 655360 events read in total (16302ms).
[13:44:38.641] <TB0> INFO: Expecting 655360 events.
[13:44:55.452] <TB0> INFO: 655360 events read in total (16409ms).
[13:44:55.471] <TB0> INFO: Expecting 655360 events.
[13:45:12.268] <TB0> INFO: 655360 events read in total (16396ms).
[13:45:12.293] <TB0> INFO: Expecting 655360 events.
[13:45:29.100] <TB0> INFO: 655360 events read in total (16405ms).
[13:45:29.130] <TB0> INFO: Expecting 655360 events.
[13:45:46.045] <TB0> INFO: 655360 events read in total (16513ms).
[13:45:46.074] <TB0> INFO: Expecting 655360 events.
[13:46:02.871] <TB0> INFO: 655360 events read in total (16395ms).
[13:46:02.904] <TB0> INFO: Expecting 655360 events.
[13:46:19.709] <TB0> INFO: 655360 events read in total (16403ms).
[13:46:19.748] <TB0> INFO: Expecting 655360 events.
[13:46:36.560] <TB0> INFO: 655360 events read in total (16410ms).
[13:46:36.605] <TB0> INFO: Expecting 655360 events.
[13:46:53.348] <TB0> INFO: 655360 events read in total (16342ms).
[13:46:53.394] <TB0> INFO: Expecting 655360 events.
[13:47:10.132] <TB0> INFO: 655360 events read in total (16336ms).
[13:47:10.186] <TB0> INFO: Expecting 655360 events.
[13:47:26.998] <TB0> INFO: 655360 events read in total (16411ms).
[13:47:27.052] <TB0> INFO: Expecting 655360 events.
[13:47:43.842] <TB0> INFO: 655360 events read in total (16388ms).
[13:47:43.899] <TB0> INFO: Expecting 655360 events.
[13:48:00.687] <TB0> INFO: 655360 events read in total (16387ms).
[13:48:00.856] <TB0> INFO: Expecting 655360 events.
[13:48:17.640] <TB0> INFO: 655360 events read in total (16382ms).
[13:48:17.698] <TB0> INFO: Test took 269738ms.
[13:48:17.780] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:48:18.009] <TB0> INFO: Expecting 655360 events.
[13:48:34.987] <TB0> INFO: 655360 events read in total (16422ms).
[13:48:34.995] <TB0> INFO: Expecting 655360 events.
[13:48:51.951] <TB0> INFO: 655360 events read in total (16554ms).
[13:48:51.963] <TB0> INFO: Expecting 655360 events.
[13:49:08.677] <TB0> INFO: 655360 events read in total (16312ms).
[13:49:08.694] <TB0> INFO: Expecting 655360 events.
[13:49:25.321] <TB0> INFO: 655360 events read in total (16225ms).
[13:49:25.340] <TB0> INFO: Expecting 655360 events.
[13:49:42.163] <TB0> INFO: 655360 events read in total (16421ms).
[13:49:42.189] <TB0> INFO: Expecting 655360 events.
[13:49:58.916] <TB0> INFO: 655360 events read in total (16325ms).
[13:49:58.943] <TB0> INFO: Expecting 655360 events.
[13:50:15.710] <TB0> INFO: 655360 events read in total (16365ms).
[13:50:15.743] <TB0> INFO: Expecting 655360 events.
[13:50:32.629] <TB0> INFO: 655360 events read in total (16484ms).
[13:50:32.662] <TB0> INFO: Expecting 655360 events.
[13:50:49.431] <TB0> INFO: 655360 events read in total (16367ms).
[13:50:49.468] <TB0> INFO: Expecting 655360 events.
[13:51:06.224] <TB0> INFO: 655360 events read in total (16354ms).
[13:51:06.268] <TB0> INFO: Expecting 655360 events.
[13:51:23.196] <TB0> INFO: 655360 events read in total (16526ms).
[13:51:23.249] <TB0> INFO: Expecting 655360 events.
[13:51:40.075] <TB0> INFO: 655360 events read in total (16425ms).
[13:51:40.126] <TB0> INFO: Expecting 655360 events.
[13:51:56.841] <TB0> INFO: 655360 events read in total (16313ms).
[13:51:56.896] <TB0> INFO: Expecting 655360 events.
[13:52:13.647] <TB0> INFO: 655360 events read in total (16350ms).
[13:52:13.705] <TB0> INFO: Expecting 655360 events.
[13:52:30.607] <TB0> INFO: 655360 events read in total (16500ms).
[13:52:30.665] <TB0> INFO: Expecting 655360 events.
[13:52:47.749] <TB0> INFO: 655360 events read in total (16682ms).
[13:52:47.812] <TB0> INFO: Test took 270032ms.
[13:52:48.010] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:48.017] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:48.024] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:48.030] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:48.037] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:48.043] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:48.051] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:48.060] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:48.066] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:48.073] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:48.080] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:48.087] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[13:52:48.094] <TB0> INFO: safety margin for low PH: adding 2, margin is now 22
[13:52:48.100] <TB0> INFO: safety margin for low PH: adding 3, margin is now 23
[13:52:48.107] <TB0> INFO: safety margin for low PH: adding 4, margin is now 24
[13:52:48.114] <TB0> INFO: safety margin for low PH: adding 5, margin is now 25
[13:52:48.120] <TB0> INFO: safety margin for low PH: adding 6, margin is now 26
[13:52:48.129] <TB0> INFO: safety margin for low PH: adding 7, margin is now 27
[13:52:48.137] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:48.144] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:48.151] <TB0> INFO: safety margin for low PH: adding 1, margin is now 21
[13:52:48.158] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:48.164] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:48.171] <TB0> INFO: safety margin for low PH: adding 0, margin is now 20
[13:52:48.206] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C0.dat
[13:52:48.206] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C1.dat
[13:52:48.206] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C2.dat
[13:52:48.206] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C3.dat
[13:52:48.206] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C4.dat
[13:52:48.207] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C5.dat
[13:52:48.207] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C6.dat
[13:52:48.207] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C7.dat
[13:52:48.208] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C8.dat
[13:52:48.208] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C9.dat
[13:52:48.208] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C10.dat
[13:52:48.208] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C11.dat
[13:52:48.208] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C12.dat
[13:52:48.208] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C13.dat
[13:52:48.208] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C14.dat
[13:52:48.208] <TB0> INFO: write dac parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//dacParameters35_C15.dat
[13:52:48.471] <TB0> INFO: Expecting 41600 events.
[13:52:51.973] <TB0> INFO: 41600 events read in total (2946ms).
[13:52:51.973] <TB0> INFO: Test took 3762ms.
[13:52:52.414] <TB0> INFO: Expecting 41600 events.
[13:52:55.912] <TB0> INFO: 41600 events read in total (2941ms).
[13:52:55.913] <TB0> INFO: Test took 3765ms.
[13:52:56.352] <TB0> INFO: Expecting 41600 events.
[13:52:59.834] <TB0> INFO: 41600 events read in total (2926ms).
[13:52:59.834] <TB0> INFO: Test took 3744ms.
[13:53:00.016] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:00.105] <TB0> INFO: Expecting 2560 events.
[13:53:00.959] <TB0> INFO: 2560 events read in total (298ms).
[13:53:00.959] <TB0> INFO: Test took 943ms.
[13:53:00.961] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:01.264] <TB0> INFO: Expecting 2560 events.
[13:53:02.118] <TB0> INFO: 2560 events read in total (298ms).
[13:53:02.118] <TB0> INFO: Test took 1157ms.
[13:53:02.120] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:02.423] <TB0> INFO: Expecting 2560 events.
[13:53:03.277] <TB0> INFO: 2560 events read in total (297ms).
[13:53:03.277] <TB0> INFO: Test took 1158ms.
[13:53:03.279] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:03.582] <TB0> INFO: Expecting 2560 events.
[13:53:04.438] <TB0> INFO: 2560 events read in total (299ms).
[13:53:04.438] <TB0> INFO: Test took 1159ms.
[13:53:04.440] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:04.744] <TB0> INFO: Expecting 2560 events.
[13:53:05.599] <TB0> INFO: 2560 events read in total (299ms).
[13:53:05.599] <TB0> INFO: Test took 1159ms.
[13:53:05.601] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:05.904] <TB0> INFO: Expecting 2560 events.
[13:53:06.758] <TB0> INFO: 2560 events read in total (298ms).
[13:53:06.758] <TB0> INFO: Test took 1157ms.
[13:53:06.761] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:07.064] <TB0> INFO: Expecting 2560 events.
[13:53:07.917] <TB0> INFO: 2560 events read in total (297ms).
[13:53:07.917] <TB0> INFO: Test took 1156ms.
[13:53:07.919] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:08.223] <TB0> INFO: Expecting 2560 events.
[13:53:09.079] <TB0> INFO: 2560 events read in total (300ms).
[13:53:09.079] <TB0> INFO: Test took 1160ms.
[13:53:09.082] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:09.384] <TB0> INFO: Expecting 2560 events.
[13:53:10.238] <TB0> INFO: 2560 events read in total (297ms).
[13:53:10.238] <TB0> INFO: Test took 1156ms.
[13:53:10.241] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:10.544] <TB0> INFO: Expecting 2560 events.
[13:53:11.399] <TB0> INFO: 2560 events read in total (299ms).
[13:53:11.399] <TB0> INFO: Test took 1158ms.
[13:53:11.401] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:11.705] <TB0> INFO: Expecting 2560 events.
[13:53:12.559] <TB0> INFO: 2560 events read in total (297ms).
[13:53:12.559] <TB0> INFO: Test took 1158ms.
[13:53:12.562] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:12.865] <TB0> INFO: Expecting 2560 events.
[13:53:13.719] <TB0> INFO: 2560 events read in total (298ms).
[13:53:13.719] <TB0> INFO: Test took 1158ms.
[13:53:13.720] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:14.025] <TB0> INFO: Expecting 2560 events.
[13:53:14.879] <TB0> INFO: 2560 events read in total (298ms).
[13:53:14.880] <TB0> INFO: Test took 1160ms.
[13:53:14.882] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:15.185] <TB0> INFO: Expecting 2560 events.
[13:53:16.047] <TB0> INFO: 2560 events read in total (305ms).
[13:53:16.048] <TB0> INFO: Test took 1166ms.
[13:53:16.050] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:16.352] <TB0> INFO: Expecting 2560 events.
[13:53:17.208] <TB0> INFO: 2560 events read in total (299ms).
[13:53:17.208] <TB0> INFO: Test took 1158ms.
[13:53:17.210] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:17.514] <TB0> INFO: Expecting 2560 events.
[13:53:18.372] <TB0> INFO: 2560 events read in total (301ms).
[13:53:18.372] <TB0> INFO: Test took 1162ms.
[13:53:18.375] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:18.678] <TB0> INFO: Expecting 2560 events.
[13:53:19.536] <TB0> INFO: 2560 events read in total (302ms).
[13:53:19.537] <TB0> INFO: Test took 1162ms.
[13:53:19.539] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:19.842] <TB0> INFO: Expecting 2560 events.
[13:53:20.696] <TB0> INFO: 2560 events read in total (297ms).
[13:53:20.697] <TB0> INFO: Test took 1158ms.
[13:53:20.699] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:21.002] <TB0> INFO: Expecting 2560 events.
[13:53:21.859] <TB0> INFO: 2560 events read in total (300ms).
[13:53:21.859] <TB0> INFO: Test took 1160ms.
[13:53:21.862] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:22.165] <TB0> INFO: Expecting 2560 events.
[13:53:23.019] <TB0> INFO: 2560 events read in total (298ms).
[13:53:23.019] <TB0> INFO: Test took 1158ms.
[13:53:23.022] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:23.325] <TB0> INFO: Expecting 2560 events.
[13:53:24.185] <TB0> INFO: 2560 events read in total (304ms).
[13:53:24.186] <TB0> INFO: Test took 1164ms.
[13:53:24.188] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:24.491] <TB0> INFO: Expecting 2560 events.
[13:53:25.345] <TB0> INFO: 2560 events read in total (298ms).
[13:53:25.345] <TB0> INFO: Test took 1157ms.
[13:53:25.348] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:25.650] <TB0> INFO: Expecting 2560 events.
[13:53:26.505] <TB0> INFO: 2560 events read in total (298ms).
[13:53:26.505] <TB0> INFO: Test took 1157ms.
[13:53:26.508] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:26.810] <TB0> INFO: Expecting 2560 events.
[13:53:27.664] <TB0> INFO: 2560 events read in total (298ms).
[13:53:27.664] <TB0> INFO: Test took 1156ms.
[13:53:27.666] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:27.970] <TB0> INFO: Expecting 2560 events.
[13:53:28.828] <TB0> INFO: 2560 events read in total (301ms).
[13:53:28.828] <TB0> INFO: Test took 1162ms.
[13:53:28.830] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:29.135] <TB0> INFO: Expecting 2560 events.
[13:53:29.995] <TB0> INFO: 2560 events read in total (304ms).
[13:53:29.995] <TB0> INFO: Test took 1165ms.
[13:53:29.998] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:30.299] <TB0> INFO: Expecting 2560 events.
[13:53:31.158] <TB0> INFO: 2560 events read in total (302ms).
[13:53:31.158] <TB0> INFO: Test took 1161ms.
[13:53:31.160] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:31.465] <TB0> INFO: Expecting 2560 events.
[13:53:32.317] <TB0> INFO: 2560 events read in total (296ms).
[13:53:32.318] <TB0> INFO: Test took 1158ms.
[13:53:32.320] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:32.623] <TB0> INFO: Expecting 2560 events.
[13:53:33.478] <TB0> INFO: 2560 events read in total (299ms).
[13:53:33.478] <TB0> INFO: Test took 1159ms.
[13:53:33.480] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:33.783] <TB0> INFO: Expecting 2560 events.
[13:53:34.638] <TB0> INFO: 2560 events read in total (299ms).
[13:53:34.638] <TB0> INFO: Test took 1159ms.
[13:53:34.640] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:34.944] <TB0> INFO: Expecting 2560 events.
[13:53:35.803] <TB0> INFO: 2560 events read in total (302ms).
[13:53:35.804] <TB0> INFO: Test took 1164ms.
[13:53:35.806] <TB0> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:53:36.109] <TB0> INFO: Expecting 2560 events.
[13:53:36.966] <TB0> INFO: 2560 events read in total (301ms).
[13:53:36.966] <TB0> INFO: Test took 1160ms.
[13:53:37.419] <TB0> INFO: PixTestPhOptimization::doTest() done, duration: 620 seconds
[13:53:37.419] <TB0> INFO: PH scale (per ROC): 86 82 84 79 83 79 83 91 82 80 80 84 80 79 75 77
[13:53:37.419] <TB0> INFO: PH offset (per ROC): 153 161 162 171 192 172 147 163 178 175 176 157 157 157 174 162
[13:53:37.427] <TB0> INFO: Decoding statistics:
[13:53:37.427] <TB0> INFO: General information:
[13:53:37.427] <TB0> INFO: 16bit words read: 91928
[13:53:37.427] <TB0> INFO: valid events total: 10240
[13:53:37.427] <TB0> INFO: empty events: 7738
[13:53:37.427] <TB0> INFO: valid events with pixels: 2502
[13:53:37.428] <TB0> INFO: valid pixel hits: 2502
[13:53:37.428] <TB0> INFO: Event errors: 0
[13:53:37.428] <TB0> INFO: start marker: 0
[13:53:37.428] <TB0> INFO: stop marker: 0
[13:53:37.428] <TB0> INFO: overflow: 0
[13:53:37.428] <TB0> INFO: invalid 5bit words: 0
[13:53:37.428] <TB0> INFO: invalid XOR eye diagram: 0
[13:53:37.428] <TB0> INFO: frame (failed synchr.): 0
[13:53:37.428] <TB0> INFO: idle data (no TBM trl): 0
[13:53:37.428] <TB0> INFO: no data (only TBM hdr): 0
[13:53:37.428] <TB0> INFO: TBM errors: 0
[13:53:37.428] <TB0> INFO: flawed TBM headers: 0
[13:53:37.428] <TB0> INFO: flawed TBM trailers: 0
[13:53:37.428] <TB0> INFO: event ID mismatches: 0
[13:53:37.428] <TB0> INFO: ROC errors: 0
[13:53:37.428] <TB0> INFO: missing ROC header(s): 0
[13:53:37.428] <TB0> INFO: misplaced readback start: 0
[13:53:37.428] <TB0> INFO: Pixel decoding errors: 0
[13:53:37.428] <TB0> INFO: pixel data incomplete: 0
[13:53:37.428] <TB0> INFO: pixel address: 0
[13:53:37.428] <TB0> INFO: pulse height fill bit: 0
[13:53:37.428] <TB0> INFO: buffer corruption: 0
[13:53:37.633] <TB0> INFO: ######################################################################
[13:53:37.633] <TB0> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[13:53:37.633] <TB0> INFO: ######################################################################
[13:53:37.644] <TB0> INFO: scanning low vcal = 10
[13:53:37.929] <TB0> INFO: Expecting 41600 events.
[13:53:41.484] <TB0> INFO: 41600 events read in total (2999ms).
[13:53:41.484] <TB0> INFO: Test took 3840ms.
[13:53:41.485] <TB0> INFO: scanning low vcal = 20
[13:53:41.789] <TB0> INFO: Expecting 41600 events.
[13:53:45.359] <TB0> INFO: 41600 events read in total (3014ms).
[13:53:45.359] <TB0> INFO: Test took 3873ms.
[13:53:45.361] <TB0> INFO: scanning low vcal = 30
[13:53:45.664] <TB0> INFO: Expecting 41600 events.
[13:53:49.264] <TB0> INFO: 41600 events read in total (3044ms).
[13:53:49.265] <TB0> INFO: Test took 3904ms.
[13:53:49.268] <TB0> INFO: scanning low vcal = 40
[13:53:49.563] <TB0> INFO: Expecting 41600 events.
[13:53:53.717] <TB0> INFO: 41600 events read in total (3598ms).
[13:53:53.718] <TB0> INFO: Test took 4450ms.
[13:53:53.720] <TB0> INFO: scanning low vcal = 50
[13:53:53.998] <TB0> INFO: Expecting 41600 events.
[13:53:58.231] <TB0> INFO: 41600 events read in total (3677ms).
[13:53:58.232] <TB0> INFO: Test took 4511ms.
[13:53:58.234] <TB0> INFO: scanning low vcal = 60
[13:53:58.505] <TB0> INFO: Expecting 41600 events.
[13:54:02.708] <TB0> INFO: 41600 events read in total (3647ms).
[13:54:02.708] <TB0> INFO: Test took 4473ms.
[13:54:02.711] <TB0> INFO: scanning low vcal = 70
[13:54:02.988] <TB0> INFO: Expecting 41600 events.
[13:54:07.228] <TB0> INFO: 41600 events read in total (3684ms).
[13:54:07.229] <TB0> INFO: Test took 4518ms.
[13:54:07.231] <TB0> INFO: scanning low vcal = 80
[13:54:07.494] <TB0> INFO: Expecting 41600 events.
[13:54:11.707] <TB0> INFO: 41600 events read in total (3656ms).
[13:54:11.708] <TB0> INFO: Test took 4477ms.
[13:54:11.710] <TB0> INFO: scanning low vcal = 90
[13:54:11.987] <TB0> INFO: Expecting 41600 events.
[13:54:16.201] <TB0> INFO: 41600 events read in total (3658ms).
[13:54:16.202] <TB0> INFO: Test took 4491ms.
[13:54:16.205] <TB0> INFO: scanning low vcal = 100
[13:54:16.475] <TB0> INFO: Expecting 41600 events.
[13:54:20.698] <TB0> INFO: 41600 events read in total (3667ms).
[13:54:20.699] <TB0> INFO: Test took 4494ms.
[13:54:20.702] <TB0> INFO: scanning low vcal = 110
[13:54:20.972] <TB0> INFO: Expecting 41600 events.
[13:54:25.203] <TB0> INFO: 41600 events read in total (3675ms).
[13:54:25.204] <TB0> INFO: Test took 4502ms.
[13:54:25.206] <TB0> INFO: scanning low vcal = 120
[13:54:25.470] <TB0> INFO: Expecting 41600 events.
[13:54:29.683] <TB0> INFO: 41600 events read in total (3657ms).
[13:54:29.683] <TB0> INFO: Test took 4477ms.
[13:54:29.686] <TB0> INFO: scanning low vcal = 130
[13:54:29.948] <TB0> INFO: Expecting 41600 events.
[13:54:34.136] <TB0> INFO: 41600 events read in total (3631ms).
[13:54:34.136] <TB0> INFO: Test took 4450ms.
[13:54:34.139] <TB0> INFO: scanning low vcal = 140
[13:54:34.412] <TB0> INFO: Expecting 41600 events.
[13:54:38.638] <TB0> INFO: 41600 events read in total (3670ms).
[13:54:38.639] <TB0> INFO: Test took 4500ms.
[13:54:38.641] <TB0> INFO: scanning low vcal = 150
[13:54:38.913] <TB0> INFO: Expecting 41600 events.
[13:54:43.118] <TB0> INFO: 41600 events read in total (3650ms).
[13:54:43.119] <TB0> INFO: Test took 4478ms.
[13:54:43.122] <TB0> INFO: scanning low vcal = 160
[13:54:43.393] <TB0> INFO: Expecting 41600 events.
[13:54:47.611] <TB0> INFO: 41600 events read in total (3662ms).
[13:54:47.612] <TB0> INFO: Test took 4490ms.
[13:54:47.614] <TB0> INFO: scanning low vcal = 170
[13:54:47.899] <TB0> INFO: Expecting 41600 events.
[13:54:52.103] <TB0> INFO: 41600 events read in total (3648ms).
[13:54:52.104] <TB0> INFO: Test took 4490ms.
[13:54:52.107] <TB0> INFO: scanning low vcal = 180
[13:54:52.377] <TB0> INFO: Expecting 41600 events.
[13:54:56.551] <TB0> INFO: 41600 events read in total (3618ms).
[13:54:56.551] <TB0> INFO: Test took 4443ms.
[13:54:56.553] <TB0> INFO: scanning low vcal = 190
[13:54:56.816] <TB0> INFO: Expecting 41600 events.
[13:55:01.028] <TB0> INFO: 41600 events read in total (3656ms).
[13:55:01.028] <TB0> INFO: Test took 4475ms.
[13:55:01.031] <TB0> INFO: scanning low vcal = 200
[13:55:01.294] <TB0> INFO: Expecting 41600 events.
[13:55:05.479] <TB0> INFO: 41600 events read in total (3628ms).
[13:55:05.479] <TB0> INFO: Test took 4448ms.
[13:55:05.482] <TB0> INFO: scanning low vcal = 210
[13:55:05.752] <TB0> INFO: Expecting 41600 events.
[13:55:09.942] <TB0> INFO: 41600 events read in total (3634ms).
[13:55:09.942] <TB0> INFO: Test took 4460ms.
[13:55:09.945] <TB0> INFO: scanning low vcal = 220
[13:55:10.222] <TB0> INFO: Expecting 41600 events.
[13:55:14.489] <TB0> INFO: 41600 events read in total (3711ms).
[13:55:14.489] <TB0> INFO: Test took 4544ms.
[13:55:14.492] <TB0> INFO: scanning low vcal = 230
[13:55:14.787] <TB0> INFO: Expecting 41600 events.
[13:55:19.056] <TB0> INFO: 41600 events read in total (3712ms).
[13:55:19.057] <TB0> INFO: Test took 4565ms.
[13:55:19.060] <TB0> INFO: scanning low vcal = 240
[13:55:19.372] <TB0> INFO: Expecting 41600 events.
[13:55:23.572] <TB0> INFO: 41600 events read in total (3643ms).
[13:55:23.572] <TB0> INFO: Test took 4513ms.
[13:55:23.575] <TB0> INFO: scanning low vcal = 250
[13:55:23.834] <TB0> INFO: Expecting 41600 events.
[13:55:28.109] <TB0> INFO: 41600 events read in total (3719ms).
[13:55:28.110] <TB0> INFO: Test took 4535ms.
[13:55:28.113] <TB0> INFO: scanning high vcal = 30 (= 210 in low range)
[13:55:28.391] <TB0> INFO: Expecting 41600 events.
[13:55:32.576] <TB0> INFO: 41600 events read in total (3628ms).
[13:55:32.577] <TB0> INFO: Test took 4463ms.
[13:55:32.588] <TB0> INFO: scanning high vcal = 50 (= 350 in low range)
[13:55:32.866] <TB0> INFO: Expecting 41600 events.
[13:55:37.047] <TB0> INFO: 41600 events read in total (3624ms).
[13:55:37.048] <TB0> INFO: Test took 4460ms.
[13:55:37.050] <TB0> INFO: scanning high vcal = 70 (= 490 in low range)
[13:55:37.322] <TB0> INFO: Expecting 41600 events.
[13:55:41.507] <TB0> INFO: 41600 events read in total (3629ms).
[13:55:41.507] <TB0> INFO: Test took 4457ms.
[13:55:41.510] <TB0> INFO: scanning high vcal = 90 (= 630 in low range)
[13:55:41.783] <TB0> INFO: Expecting 41600 events.
[13:55:46.030] <TB0> INFO: 41600 events read in total (3691ms).
[13:55:46.031] <TB0> INFO: Test took 4521ms.
[13:55:46.033] <TB0> INFO: scanning high vcal = 200 (= 1400 in low range)
[13:55:46.312] <TB0> INFO: Expecting 41600 events.
[13:55:50.523] <TB0> INFO: 41600 events read in total (3655ms).
[13:55:50.524] <TB0> INFO: Test took 4491ms.
[13:55:50.984] <TB0> INFO: PixTestGainPedestal::measure() done
[13:56:23.919] <TB0> INFO: PixTestGainPedestal::fit() done
[13:56:23.919] <TB0> INFO: non-linearity mean: 0.952 0.959 0.958 0.946 0.961 0.957 0.951 0.959 0.957 0.953 0.957 0.958 0.958 0.955 0.954 0.951
[13:56:23.919] <TB0> INFO: non-linearity RMS: 0.006 0.007 0.006 0.007 0.005 0.006 0.006 0.005 0.007 0.005 0.004 0.006 0.005 0.006 0.007 0.008
[13:56:23.919] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[13:56:23.938] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[13:56:23.962] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[13:56:23.986] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[13:56:24.014] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[13:56:24.043] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[13:56:24.070] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[13:56:24.099] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[13:56:24.128] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[13:56:24.157] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[13:56:24.187] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[13:56:24.217] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[13:56:24.246] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[13:56:24.274] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[13:56:24.301] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[13:56:24.326] <TB0> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[13:56:24.349] <TB0> INFO: PixTestGainPedestal::fullTest() done, duration: 166 seconds
[13:56:24.350] <TB0> INFO: Decoding statistics:
[13:56:24.350] <TB0> INFO: General information:
[13:56:24.350] <TB0> INFO: 16bit words read: 2662400
[13:56:24.350] <TB0> INFO: valid events total: 166400
[13:56:24.350] <TB0> INFO: empty events: 0
[13:56:24.350] <TB0> INFO: valid events with pixels: 166400
[13:56:24.350] <TB0> INFO: valid pixel hits: 665571
[13:56:24.350] <TB0> INFO: Event errors: 0
[13:56:24.350] <TB0> INFO: start marker: 0
[13:56:24.350] <TB0> INFO: stop marker: 0
[13:56:24.350] <TB0> INFO: overflow: 0
[13:56:24.350] <TB0> INFO: invalid 5bit words: 0
[13:56:24.350] <TB0> INFO: invalid XOR eye diagram: 0
[13:56:24.350] <TB0> INFO: frame (failed synchr.): 0
[13:56:24.350] <TB0> INFO: idle data (no TBM trl): 0
[13:56:24.350] <TB0> INFO: no data (only TBM hdr): 0
[13:56:24.350] <TB0> INFO: TBM errors: 0
[13:56:24.350] <TB0> INFO: flawed TBM headers: 0
[13:56:24.350] <TB0> INFO: flawed TBM trailers: 0
[13:56:24.350] <TB0> INFO: event ID mismatches: 0
[13:56:24.350] <TB0> INFO: ROC errors: 0
[13:56:24.350] <TB0> INFO: missing ROC header(s): 0
[13:56:24.350] <TB0> INFO: misplaced readback start: 0
[13:56:24.350] <TB0> INFO: Pixel decoding errors: 0
[13:56:24.350] <TB0> INFO: pixel data incomplete: 0
[13:56:24.350] <TB0> INFO: pixel address: 0
[13:56:24.350] <TB0> INFO: pulse height fill bit: 0
[13:56:24.350] <TB0> INFO: buffer corruption: 0
[13:56:24.360] <TB0> INFO: readReadbackCal: /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C15.dat
[13:56:24.361] <TB0> INFO: ######################################################################
[13:56:24.361] <TB0> INFO: PixTestReadback::doTest()
[13:56:24.361] <TB0> INFO: ######################################################################
[13:56:24.361] <TB0> INFO: ----------------------------------------------------------------------
[13:56:24.361] <TB0> INFO: PixTestReadback::CalibrateVd()
[13:56:24.361] <TB0> INFO: ----------------------------------------------------------------------
[13:56:33.839] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C0.dat
[13:56:33.839] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C1.dat
[13:56:33.839] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C2.dat
[13:56:33.839] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C3.dat
[13:56:33.840] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C4.dat
[13:56:33.840] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C5.dat
[13:56:33.840] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C6.dat
[13:56:33.840] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C7.dat
[13:56:33.841] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C8.dat
[13:56:33.841] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C9.dat
[13:56:33.841] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C10.dat
[13:56:33.842] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C11.dat
[13:56:33.842] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C12.dat
[13:56:33.842] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C13.dat
[13:56:33.842] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C14.dat
[13:56:33.843] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C15.dat
[13:56:33.879] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[13:56:33.879] <TB0> INFO: ----------------------------------------------------------------------
[13:56:33.879] <TB0> INFO: PixTestReadback::CalibrateVa()
[13:56:33.879] <TB0> INFO: ----------------------------------------------------------------------
[13:56:43.354] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C0.dat
[13:56:43.354] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C1.dat
[13:56:43.354] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C2.dat
[13:56:43.354] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C3.dat
[13:56:43.355] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C4.dat
[13:56:43.355] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C5.dat
[13:56:43.355] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C6.dat
[13:56:43.355] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C7.dat
[13:56:43.355] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C8.dat
[13:56:43.355] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C9.dat
[13:56:43.355] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C10.dat
[13:56:43.355] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C11.dat
[13:56:43.355] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C12.dat
[13:56:43.355] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C13.dat
[13:56:43.355] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C14.dat
[13:56:43.355] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C15.dat
[13:56:43.385] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[13:56:43.386] <TB0> INFO: ----------------------------------------------------------------------
[13:56:43.386] <TB0> INFO: PixTestReadback::readbackVbg()
[13:56:43.386] <TB0> INFO: ----------------------------------------------------------------------
[13:56:50.701] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[13:56:50.701] <TB0> INFO: ----------------------------------------------------------------------
[13:56:50.701] <TB0> INFO: PixTestReadback::getCalibratedVbg()
[13:56:50.701] <TB0> INFO: ----------------------------------------------------------------------
[13:56:50.701] <TB0> INFO: Vbg will be calibrated using Vd calibration
[13:56:50.701] <TB0> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 153.8calibrated Vbg = 1.2005 :::*/*/*/*/
[13:56:50.701] <TB0> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 158calibrated Vbg = 1.20278 :::*/*/*/*/
[13:56:50.701] <TB0> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 161.8calibrated Vbg = 1.20209 :::*/*/*/*/
[13:56:50.701] <TB0> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 156.8calibrated Vbg = 1.20783 :::*/*/*/*/
[13:56:50.701] <TB0> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 151.8calibrated Vbg = 1.21422 :::*/*/*/*/
[13:56:50.701] <TB0> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 140.3calibrated Vbg = 1.21108 :::*/*/*/*/
[13:56:50.701] <TB0> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 157.2calibrated Vbg = 1.20981 :::*/*/*/*/
[13:56:50.701] <TB0> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 150calibrated Vbg = 1.2139 :::*/*/*/*/
[13:56:50.701] <TB0> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 156calibrated Vbg = 1.21547 :::*/*/*/*/
[13:56:50.701] <TB0> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 152.7calibrated Vbg = 1.21755 :::*/*/*/*/
[13:56:50.701] <TB0> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 159.6calibrated Vbg = 1.20804 :::*/*/*/*/
[13:56:50.701] <TB0> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 145.4calibrated Vbg = 1.20814 :::*/*/*/*/
[13:56:50.701] <TB0> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 157.9calibrated Vbg = 1.20345 :::*/*/*/*/
[13:56:50.701] <TB0> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 146.5calibrated Vbg = 1.2064 :::*/*/*/*/
[13:56:50.701] <TB0> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 156.9calibrated Vbg = 1.20789 :::*/*/*/*/
[13:56:50.701] <TB0> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 151.1calibrated Vbg = 1.20369 :::*/*/*/*/
[13:56:50.704] <TB0> INFO: ----------------------------------------------------------------------
[13:56:50.704] <TB0> INFO: PixTestReadback::CalibrateIa()
[13:56:50.704] <TB0> INFO: ----------------------------------------------------------------------
[13:59:26.110] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C0.dat
[13:59:26.110] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C1.dat
[13:59:26.110] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C2.dat
[13:59:26.110] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C3.dat
[13:59:26.110] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C4.dat
[13:59:26.111] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C5.dat
[13:59:26.111] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C6.dat
[13:59:26.111] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C7.dat
[13:59:26.111] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C8.dat
[13:59:26.111] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C9.dat
[13:59:26.111] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C10.dat
[13:59:26.111] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C11.dat
[13:59:26.111] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C12.dat
[13:59:26.111] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C13.dat
[13:59:26.111] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C14.dat
[13:59:26.111] <TB0> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2200_FullQualification_2016-04-12_10h06m_1460448371//002_FulltestPxar_m20//readbackCal_C15.dat
[13:59:26.140] <TB0> INFO: PixTestPattern:: pg_setup set to default.
[13:59:26.141] <TB0> INFO: PixTestReadback::doTest() done
[13:59:26.142] <TB0> INFO: Decoding statistics:
[13:59:26.142] <TB0> INFO: General information:
[13:59:26.142] <TB0> INFO: 16bit words read: 1024
[13:59:26.142] <TB0> INFO: valid events total: 128
[13:59:26.142] <TB0> INFO: empty events: 128
[13:59:26.142] <TB0> INFO: valid events with pixels: 0
[13:59:26.142] <TB0> INFO: valid pixel hits: 0
[13:59:26.142] <TB0> INFO: Event errors: 0
[13:59:26.142] <TB0> INFO: start marker: 0
[13:59:26.142] <TB0> INFO: stop marker: 0
[13:59:26.142] <TB0> INFO: overflow: 0
[13:59:26.142] <TB0> INFO: invalid 5bit words: 0
[13:59:26.142] <TB0> INFO: invalid XOR eye diagram: 0
[13:59:26.142] <TB0> INFO: frame (failed synchr.): 0
[13:59:26.142] <TB0> INFO: idle data (no TBM trl): 0
[13:59:26.142] <TB0> INFO: no data (only TBM hdr): 0
[13:59:26.142] <TB0> INFO: TBM errors: 0
[13:59:26.142] <TB0> INFO: flawed TBM headers: 0
[13:59:26.142] <TB0> INFO: flawed TBM trailers: 0
[13:59:26.142] <TB0> INFO: event ID mismatches: 0
[13:59:26.142] <TB0> INFO: ROC errors: 0
[13:59:26.142] <TB0> INFO: missing ROC header(s): 0
[13:59:26.142] <TB0> INFO: misplaced readback start: 0
[13:59:26.142] <TB0> INFO: Pixel decoding errors: 0
[13:59:26.142] <TB0> INFO: pixel data incomplete: 0
[13:59:26.142] <TB0> INFO: pixel address: 0
[13:59:26.142] <TB0> INFO: pulse height fill bit: 0
[13:59:26.142] <TB0> INFO: buffer corruption: 0
[13:59:26.165] <TB0> INFO: Decoding statistics:
[13:59:26.165] <TB0> INFO: General information:
[13:59:26.165] <TB0> INFO: 16bit words read: 2755352
[13:59:26.165] <TB0> INFO: valid events total: 176768
[13:59:26.165] <TB0> INFO: empty events: 7866
[13:59:26.165] <TB0> INFO: valid events with pixels: 168902
[13:59:26.165] <TB0> INFO: valid pixel hits: 668073
[13:59:26.165] <TB0> INFO: Event errors: 0
[13:59:26.165] <TB0> INFO: start marker: 0
[13:59:26.165] <TB0> INFO: stop marker: 0
[13:59:26.165] <TB0> INFO: overflow: 0
[13:59:26.165] <TB0> INFO: invalid 5bit words: 0
[13:59:26.165] <TB0> INFO: invalid XOR eye diagram: 0
[13:59:26.165] <TB0> INFO: frame (failed synchr.): 0
[13:59:26.165] <TB0> INFO: idle data (no TBM trl): 0
[13:59:26.165] <TB0> INFO: no data (only TBM hdr): 0
[13:59:26.165] <TB0> INFO: TBM errors: 0
[13:59:26.165] <TB0> INFO: flawed TBM headers: 0
[13:59:26.165] <TB0> INFO: flawed TBM trailers: 0
[13:59:26.165] <TB0> INFO: event ID mismatches: 0
[13:59:26.165] <TB0> INFO: ROC errors: 0
[13:59:26.165] <TB0> INFO: missing ROC header(s): 0
[13:59:26.165] <TB0> INFO: misplaced readback start: 0
[13:59:26.165] <TB0> INFO: Pixel decoding errors: 0
[13:59:26.165] <TB0> INFO: pixel data incomplete: 0
[13:59:26.165] <TB0> INFO: pixel address: 0
[13:59:26.165] <TB0> INFO: pulse height fill bit: 0
[13:59:26.165] <TB0> INFO: buffer corruption: 0
[13:59:26.165] <TB0> INFO: enter test to run
[13:59:26.165] <TB0> INFO: test: exit no parameter change
[13:59:26.326] <TB0> QUIET: Connection to board 73 closed.
[13:59:26.406] <TB0> INFO: pXar: this is the end, my friend