[12:02:47.885] <TB1> INFO: *** Welcome to pxar ***
[12:02:47.885] <TB1> INFO: *** Today: 2016/06/29
[12:02:47.974] <TB1> INFO: *** Version: 9751-dirty
[12:02:47.974] <TB1> INFO: readRocDacs: /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C15.dat
[12:02:47.976] <TB1> INFO: readTbmDacs: /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//tbmParameters_C0b.dat
[12:02:47.977] <TB1> INFO: readMaskFile: /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//defaultMaskFile.dat
[12:02:47.977] <TB1> INFO: readTrimFile: /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters_C15.dat
[12:02:48.065] <TB1> INFO: clk: 4
[12:02:48.065] <TB1> INFO: ctr: 4
[12:02:48.065] <TB1> INFO: sda: 19
[12:02:48.065] <TB1> INFO: tin: 9
[12:02:48.065] <TB1> INFO: level: 15
[12:02:48.065] <TB1> INFO: triggerdelay: 0
[12:02:48.065] <TB1> QUIET: Instanciating API for pxar v2.7.5+16~g4df2ad8
[12:02:48.065] <TB1> INFO: Log level: INFO
[12:02:48.072] <TB1> INFO: Found DTB DTB_WXBYFL
[12:02:48.081] <TB1> QUIET: Connection to board DTB_WXBYFL opened.
[12:02:48.083] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 153
HW version: DTB1.2
FW version: 4.4
SW version: 4.6
Options:
USB id: DTB_WXBYFL
MAC address: 40D855118099
Hostname: pixelDTB153
Comment:
------------------------------------------------------
[12:02:48.085] <TB1> INFO: RPC call hashes of host and DTB match: 484264910
[12:02:49.624] <TB1> INFO: DUT info:
[12:02:49.624] <TB1> INFO: The DUT currently contains the following objects:
[12:02:49.624] <TB1> INFO: 2 TBM Cores tbm09c (2 ON)
[12:02:49.624] <TB1> INFO: TBM Core alpha (0): 7 registers set
[12:02:49.624] <TB1> INFO: TBM Core beta (1): 7 registers set
[12:02:49.624] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:02:49.624] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:49.624] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:49.624] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:49.624] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:49.624] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:49.624] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:49.624] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:49.624] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:49.624] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:49.624] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:49.624] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:49.624] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:49.624] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:49.624] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:49.625] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:49.625] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:02:50.025] <TB1> INFO: enter 'restricted' command line mode
[12:02:50.025] <TB1> INFO: enter test to run
[12:02:50.026] <TB1> INFO: test: pretest no parameter change
[12:02:50.026] <TB1> INFO: running: pretest
[12:02:50.036] <TB1> INFO: ----------------------------------------------------------------------
[12:02:50.036] <TB1> INFO: PixTestPretest::programROC()
[12:02:50.036] <TB1> INFO: ----------------------------------------------------------------------
[12:03:08.050] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:03:08.050] <TB1> INFO: IA differences per ROC: 20.1 19.3 20.1 19.3 20.9 16.9 16.9 18.5 17.7 18.5 16.9 20.1 19.3 17.7 20.9 20.1
[12:03:08.140] <TB1> INFO: enter test to run
[12:03:08.140] <TB1> INFO: test: pretest no parameter change
[12:03:08.140] <TB1> INFO: running: pretest
[12:03:08.141] <TB1> INFO: ----------------------------------------------------------------------
[12:03:08.141] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:03:08.141] <TB1> INFO: ----------------------------------------------------------------------
[12:03:29.404] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 381 mA = 23.8125 mA/ROC
[12:03:29.404] <TB1> INFO: i(loss) [mA/ROC]: 19.3 18.5 20.1 19.3 19.3 19.3 19.3 19.3 20.1 19.3 18.5 19.3 18.5 18.5 18.5 18.5
[12:03:29.431] <TB1> INFO: enter test to run
[12:03:29.431] <TB1> INFO: test: pretest no parameter change
[12:03:29.431] <TB1> INFO: running: pretest
[12:03:29.431] <TB1> INFO: ----------------------------------------------------------------------
[12:03:29.431] <TB1> INFO: PixTestPretest::findTiming()
[12:03:29.431] <TB1> INFO: ----------------------------------------------------------------------
[12:03:29.431] <TB1> INFO: PixTestCmd::init()

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


[12:05:09.396] <TB1> INFO: 231680 events read in total (9717ms).
[12:05:09.400] <TB1> INFO: Test took 10365ms.
[12:05:09.626] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[12:05:09.691] <TB1> INFO: enter test to run
[12:05:09.691] <TB1> INFO: test: pretest no parameter change
[12:05:09.691] <TB1> INFO: running: pretest
[12:05:09.692] <TB1> INFO: ----------------------------------------------------------------------
[12:05:09.692] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[12:05:09.692] <TB1> INFO: ----------------------------------------------------------------------
[12:05:09.784] <TB1> INFO: Expecting 231680 events.
[12:05:20.022] <TB1> INFO: 231680 events read in total (9682ms).
[12:05:20.026] <TB1> INFO: Test took 10330ms.
[12:05:20.279] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[12:05:20.279] <TB1> INFO: CalDel: 161 150 163 141 134 140 120 148 147 134 152 183 128 132 144 143
[12:05:20.279] <TB1> INFO: VthrComp: 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51 51
[12:05:20.316] <TB1> INFO: enter test to run
[12:05:20.316] <TB1> INFO: test: pretest no parameter change
[12:05:20.316] <TB1> INFO: running: pretest
[12:05:20.316] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C0.dat
[12:05:20.316] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C1.dat
[12:05:20.317] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C2.dat
[12:05:20.317] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C3.dat
[12:05:20.317] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C4.dat
[12:05:20.317] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C5.dat
[12:05:20.317] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C6.dat
[12:05:20.317] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C7.dat
[12:05:20.317] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C8.dat
[12:05:20.318] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C9.dat
[12:05:20.318] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C10.dat
[12:05:20.318] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C11.dat
[12:05:20.318] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C12.dat
[12:05:20.318] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C13.dat
[12:05:20.318] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C14.dat
[12:05:20.318] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters_C15.dat
[12:05:20.319] <TB1> INFO: enter test to run
[12:05:20.319] <TB1> INFO: test: fulltest no parameter change
[12:05:20.319] <TB1> INFO: running: fulltest
[12:05:20.319] <TB1> INFO: ######################################################################
[12:05:20.319] <TB1> INFO: PixTestFullTest::doTest()
[12:05:20.319] <TB1> INFO: ######################################################################
[12:05:20.320] <TB1> INFO: ######################################################################
[12:05:20.320] <TB1> INFO: PixTestAlive::doTest()
[12:05:20.320] <TB1> INFO: ######################################################################
[12:05:20.321] <TB1> INFO: ----------------------------------------------------------------------
[12:05:20.321] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:05:20.321] <TB1> INFO: ----------------------------------------------------------------------
[12:05:20.585] <TB1> INFO: Expecting 41600 events.
[12:05:24.378] <TB1> INFO: 41600 events read in total (3236ms).
[12:05:24.378] <TB1> INFO: Test took 4056ms.
[12:05:24.599] <TB1> INFO: PixTestAlive::aliveTest() done
[12:05:24.599] <TB1> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:05:24.600] <TB1> INFO: ----------------------------------------------------------------------
[12:05:24.600] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:05:24.600] <TB1> INFO: ----------------------------------------------------------------------
[12:05:24.879] <TB1> INFO: Expecting 41600 events.
[12:05:27.863] <TB1> INFO: 41600 events read in total (2427ms).
[12:05:27.863] <TB1> INFO: Test took 3261ms.
[12:05:27.863] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:05:28.107] <TB1> INFO: PixTestAlive::maskTest() done
[12:05:28.107] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:05:28.108] <TB1> INFO: ----------------------------------------------------------------------
[12:05:28.108] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:05:28.108] <TB1> INFO: ----------------------------------------------------------------------
[12:05:28.399] <TB1> INFO: Expecting 41600 events.
[12:05:32.207] <TB1> INFO: 41600 events read in total (3251ms).
[12:05:32.207] <TB1> INFO: Test took 4098ms.
[12:05:32.432] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[12:05:32.432] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:05:32.432] <TB1> INFO: PixTestAlive::doTest() done, duration: 12 seconds
[12:05:32.432] <TB1> INFO: Decoding statistics:
[12:05:32.432] <TB1> INFO: General information:
[12:05:32.432] <TB1> INFO: 16bit words read: 0
[12:05:32.432] <TB1> INFO: valid events total: 0
[12:05:32.432] <TB1> INFO: empty events: 0
[12:05:32.432] <TB1> INFO: valid events with pixels: 0
[12:05:32.432] <TB1> INFO: valid pixel hits: 0
[12:05:32.432] <TB1> INFO: Event errors: 0
[12:05:32.432] <TB1> INFO: start marker: 0
[12:05:32.432] <TB1> INFO: stop marker: 0
[12:05:32.432] <TB1> INFO: overflow: 0
[12:05:32.432] <TB1> INFO: invalid 5bit words: 0
[12:05:32.432] <TB1> INFO: invalid XOR eye diagram: 0
[12:05:32.432] <TB1> INFO: frame (failed synchr.): 0
[12:05:32.432] <TB1> INFO: idle data (no TBM trl): 0
[12:05:32.432] <TB1> INFO: no data (only TBM hdr): 0
[12:05:32.432] <TB1> INFO: TBM errors: 0
[12:05:32.432] <TB1> INFO: flawed TBM headers: 0
[12:05:32.432] <TB1> INFO: flawed TBM trailers: 0
[12:05:32.432] <TB1> INFO: event ID mismatches: 0
[12:05:32.432] <TB1> INFO: ROC errors: 0
[12:05:32.432] <TB1> INFO: missing ROC header(s): 0
[12:05:32.432] <TB1> INFO: misplaced readback start: 0
[12:05:32.432] <TB1> INFO: Pixel decoding errors: 0
[12:05:32.432] <TB1> INFO: pixel data incomplete: 0
[12:05:32.432] <TB1> INFO: pixel address: 0
[12:05:32.432] <TB1> INFO: pulse height fill bit: 0
[12:05:32.432] <TB1> INFO: buffer corruption: 0
[12:05:32.440] <TB1> INFO: ######################################################################
[12:05:32.440] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[12:05:32.440] <TB1> INFO: ######################################################################
[12:05:32.442] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[12:05:32.453] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[12:05:32.453] <TB1> INFO: run 1 of 1
[12:05:32.719] <TB1> INFO: Expecting 3120000 events.
[12:06:13.956] <TB1> INFO: 875305 events read in total (40680ms).
[12:06:54.161] <TB1> INFO: 1739040 events read in total (80885ms).
[12:07:34.857] <TB1> INFO: 2612585 events read in total (121581ms).
[12:07:58.629] <TB1> INFO: 3120000 events read in total (145353ms).
[12:07:58.678] <TB1> INFO: Test took 146225ms.
[12:08:22.929] <TB1> INFO: PixTestBBMap::doTest() done, duration: 170 seconds
[12:08:22.929] <TB1> INFO: number of dead bumps (per ROC): 0 0 0 0 2 0 0 0 0 0 0 0 0 0 0 0
[12:08:22.929] <TB1> INFO: separation cut (per ROC): 80 81 82 98 98 89 91 73 82 91 87 87 88 91 89 94
[12:08:22.929] <TB1> INFO: Decoding statistics:
[12:08:22.929] <TB1> INFO: General information:
[12:08:22.929] <TB1> INFO: 16bit words read: 0
[12:08:22.929] <TB1> INFO: valid events total: 0
[12:08:22.929] <TB1> INFO: empty events: 0
[12:08:22.929] <TB1> INFO: valid events with pixels: 0
[12:08:22.929] <TB1> INFO: valid pixel hits: 0
[12:08:22.929] <TB1> INFO: Event errors: 0
[12:08:22.929] <TB1> INFO: start marker: 0
[12:08:22.929] <TB1> INFO: stop marker: 0
[12:08:22.929] <TB1> INFO: overflow: 0
[12:08:22.930] <TB1> INFO: invalid 5bit words: 0
[12:08:22.930] <TB1> INFO: invalid XOR eye diagram: 0
[12:08:22.930] <TB1> INFO: frame (failed synchr.): 0
[12:08:22.930] <TB1> INFO: idle data (no TBM trl): 0
[12:08:22.930] <TB1> INFO: no data (only TBM hdr): 0
[12:08:22.930] <TB1> INFO: TBM errors: 0
[12:08:22.930] <TB1> INFO: flawed TBM headers: 0
[12:08:22.930] <TB1> INFO: flawed TBM trailers: 0
[12:08:22.930] <TB1> INFO: event ID mismatches: 0
[12:08:22.930] <TB1> INFO: ROC errors: 0
[12:08:22.930] <TB1> INFO: missing ROC header(s): 0
[12:08:22.930] <TB1> INFO: misplaced readback start: 0
[12:08:22.930] <TB1> INFO: Pixel decoding errors: 0
[12:08:22.930] <TB1> INFO: pixel data incomplete: 0
[12:08:22.930] <TB1> INFO: pixel address: 0
[12:08:22.930] <TB1> INFO: pulse height fill bit: 0
[12:08:22.930] <TB1> INFO: buffer corruption: 0
[12:08:23.004] <TB1> INFO: ######################################################################
[12:08:23.004] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:08:23.004] <TB1> INFO: ######################################################################
[12:08:23.004] <TB1> INFO: ----------------------------------------------------------------------
[12:08:23.004] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:08:23.004] <TB1> INFO: ----------------------------------------------------------------------
[12:08:23.004] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:08:23.012] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[12:08:23.012] <TB1> INFO: run 1 of 1
[12:08:23.288] <TB1> INFO: Expecting 26208000 events.
[12:08:56.709] <TB1> INFO: 901400 events read in total (32864ms).
[12:09:29.653] <TB1> INFO: 1788800 events read in total (65808ms).
[12:10:02.359] <TB1> INFO: 2675200 events read in total (98514ms).
[12:10:34.931] <TB1> INFO: 3559400 events read in total (131086ms).
[12:11:07.634] <TB1> INFO: 4444150 events read in total (163789ms).
[12:11:40.285] <TB1> INFO: 5326850 events read in total (196440ms).
[12:12:13.160] <TB1> INFO: 6210550 events read in total (229315ms).
[12:12:46.373] <TB1> INFO: 7092350 events read in total (262528ms).
[12:13:19.251] <TB1> INFO: 7973450 events read in total (295406ms).
[12:13:52.139] <TB1> INFO: 8855750 events read in total (328294ms).
[12:14:25.146] <TB1> INFO: 9738900 events read in total (361301ms).
[12:14:57.760] <TB1> INFO: 10620900 events read in total (393915ms).
[12:15:30.206] <TB1> INFO: 11501500 events read in total (426361ms).
[12:16:02.753] <TB1> INFO: 12383500 events read in total (458908ms).
[12:16:35.413] <TB1> INFO: 13261350 events read in total (491568ms).
[12:17:08.516] <TB1> INFO: 14134100 events read in total (524671ms).
[12:17:41.103] <TB1> INFO: 15003350 events read in total (557258ms).
[12:18:13.938] <TB1> INFO: 15874450 events read in total (590093ms).
[12:18:46.681] <TB1> INFO: 16744400 events read in total (622836ms).
[12:19:19.311] <TB1> INFO: 17612500 events read in total (655466ms).
[12:19:52.372] <TB1> INFO: 18481700 events read in total (688527ms).
[12:20:25.174] <TB1> INFO: 19350450 events read in total (721329ms).
[12:20:57.668] <TB1> INFO: 20217350 events read in total (753823ms).
[12:21:30.826] <TB1> INFO: 21084900 events read in total (786981ms).
[12:22:04.821] <TB1> INFO: 21954050 events read in total (820977ms).
[12:22:38.759] <TB1> INFO: 22822850 events read in total (854914ms).
[12:23:11.831] <TB1> INFO: 23693450 events read in total (887986ms).
[12:23:44.913] <TB1> INFO: 24564150 events read in total (921068ms).
[12:24:17.846] <TB1> INFO: 25437900 events read in total (954001ms).
[12:24:46.308] <TB1> INFO: 26208000 events read in total (982463ms).
[12:24:46.350] <TB1> INFO: Test took 983337ms.
[12:24:46.627] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:24:48.113] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:24:49.548] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:24:50.959] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:24:52.342] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:24:53.725] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:24:55.126] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:24:56.514] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:24:57.933] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:24:59.357] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:25:00.743] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:25:02.358] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:25:03.990] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:25:05.470] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:25:06.923] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:25:08.382] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[12:25:09.923] <TB1> INFO: PixTestScurves::scurves() done
[12:25:09.923] <TB1> INFO: Vcal mean: 90.16 81.34 85.43 101.17 99.46 99.16 103.53 81.27 82.53 96.63 91.62 83.22 86.25 85.76 87.92 88.29
[12:25:09.923] <TB1> INFO: Vcal RMS: 5.64 5.06 5.34 5.77 6.13 5.90 5.54 4.84 4.67 5.22 5.69 4.99 4.89 5.15 5.51 5.34
[12:25:09.923] <TB1> INFO: PixTestScurves::fullTest() done, duration: 1006 seconds
[12:25:09.923] <TB1> INFO: Decoding statistics:
[12:25:09.923] <TB1> INFO: General information:
[12:25:09.923] <TB1> INFO: 16bit words read: 0
[12:25:09.923] <TB1> INFO: valid events total: 0
[12:25:09.923] <TB1> INFO: empty events: 0
[12:25:09.923] <TB1> INFO: valid events with pixels: 0
[12:25:09.923] <TB1> INFO: valid pixel hits: 0
[12:25:09.923] <TB1> INFO: Event errors: 0
[12:25:09.924] <TB1> INFO: start marker: 0
[12:25:09.924] <TB1> INFO: stop marker: 0
[12:25:09.924] <TB1> INFO: overflow: 0
[12:25:09.924] <TB1> INFO: invalid 5bit words: 0
[12:25:09.924] <TB1> INFO: invalid XOR eye diagram: 0
[12:25:09.924] <TB1> INFO: frame (failed synchr.): 0
[12:25:09.924] <TB1> INFO: idle data (no TBM trl): 0
[12:25:09.924] <TB1> INFO: no data (only TBM hdr): 0
[12:25:09.924] <TB1> INFO: TBM errors: 0
[12:25:09.924] <TB1> INFO: flawed TBM headers: 0
[12:25:09.924] <TB1> INFO: flawed TBM trailers: 0
[12:25:09.924] <TB1> INFO: event ID mismatches: 0
[12:25:09.924] <TB1> INFO: ROC errors: 0
[12:25:09.924] <TB1> INFO: missing ROC header(s): 0
[12:25:09.924] <TB1> INFO: misplaced readback start: 0
[12:25:09.924] <TB1> INFO: Pixel decoding errors: 0
[12:25:09.924] <TB1> INFO: pixel data incomplete: 0
[12:25:09.924] <TB1> INFO: pixel address: 0
[12:25:09.924] <TB1> INFO: pulse height fill bit: 0
[12:25:09.924] <TB1> INFO: buffer corruption: 0
[12:25:10.020] <TB1> INFO: ######################################################################
[12:25:10.020] <TB1> INFO: PixTestTrim::doTest()
[12:25:10.021] <TB1> INFO: ######################################################################
[12:25:10.022] <TB1> INFO: ----------------------------------------------------------------------
[12:25:10.022] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[12:25:10.022] <TB1> INFO: ----------------------------------------------------------------------
[12:25:10.146] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[12:25:10.146] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:25:10.157] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[12:25:10.157] <TB1> INFO: run 1 of 1
[12:25:10.427] <TB1> INFO: Expecting 6281600 events.
[12:25:50.103] <TB1> INFO: 1090750 events read in total (39120ms).
[12:26:29.732] <TB1> INFO: 2176390 events read in total (78749ms).
[12:27:08.764] <TB1> INFO: 3255150 events read in total (117781ms).
[12:27:48.039] <TB1> INFO: 4337680 events read in total (157056ms).
[12:28:27.414] <TB1> INFO: 5422000 events read in total (196431ms).
[12:28:58.320] <TB1> INFO: 6281600 events read in total (227337ms).
[12:28:58.348] <TB1> INFO: Test took 228190ms.
[12:29:17.855] <TB1> INFO: ROC 0 VthrComp = 89
[12:29:17.856] <TB1> INFO: ROC 1 VthrComp = 83
[12:29:17.856] <TB1> INFO: ROC 2 VthrComp = 88
[12:29:17.856] <TB1> INFO: ROC 3 VthrComp = 101
[12:29:17.856] <TB1> INFO: ROC 4 VthrComp = 96
[12:29:17.856] <TB1> INFO: ROC 5 VthrComp = 96
[12:29:17.856] <TB1> INFO: ROC 6 VthrComp = 101
[12:29:17.856] <TB1> INFO: ROC 7 VthrComp = 84
[12:29:17.856] <TB1> INFO: ROC 8 VthrComp = 85
[12:29:17.856] <TB1> INFO: ROC 9 VthrComp = 100
[12:29:17.857] <TB1> INFO: ROC 10 VthrComp = 86
[12:29:17.857] <TB1> INFO: ROC 11 VthrComp = 85
[12:29:17.857] <TB1> INFO: ROC 12 VthrComp = 91
[12:29:17.857] <TB1> INFO: ROC 13 VthrComp = 90
[12:29:17.857] <TB1> INFO: ROC 14 VthrComp = 87
[12:29:17.857] <TB1> INFO: ROC 15 VthrComp = 91
[12:29:17.857] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[12:29:17.857] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[12:29:17.865] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[12:29:17.865] <TB1> INFO: run 1 of 1
[12:29:18.127] <TB1> INFO: Expecting 6281600 events.
[12:29:54.255] <TB1> INFO: 754960 events read in total (35572ms).
[12:30:28.837] <TB1> INFO: 1508230 events read in total (70154ms).
[12:31:03.906] <TB1> INFO: 2261740 events read in total (105223ms).
[12:31:38.570] <TB1> INFO: 3016280 events read in total (139887ms).
[12:32:13.631] <TB1> INFO: 3766100 events read in total (174948ms).
[12:32:48.545] <TB1> INFO: 4512760 events read in total (209862ms).
[12:33:23.500] <TB1> INFO: 5257800 events read in total (244817ms).
[12:33:57.934] <TB1> INFO: 6003610 events read in total (279251ms).
[12:34:11.046] <TB1> INFO: 6281600 events read in total (292363ms).
[12:34:11.090] <TB1> INFO: Test took 293224ms.
[12:34:37.564] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 60.9035 for pixel 28/1 mean/min/max = 46.9283/32.8261/61.0306
[12:34:37.564] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 59.559 for pixel 10/1 mean/min/max = 45.7344/31.7174/59.7514
[12:34:37.564] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 59.443 for pixel 46/79 mean/min/max = 46.4387/33.3601/59.5174
[12:34:37.565] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 60.5249 for pixel 21/8 mean/min/max = 46.0394/31.3662/60.7126
[12:34:37.565] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 61.7902 for pixel 7/2 mean/min/max = 46.6793/31.5192/61.8393
[12:34:37.565] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 61.4905 for pixel 14/79 mean/min/max = 46.5727/31.5727/61.5727
[12:34:37.565] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 59.5511 for pixel 17/66 mean/min/max = 45.6427/31.6115/59.6739
[12:34:37.566] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 58.9242 for pixel 40/79 mean/min/max = 45.6266/32.2837/58.9695
[12:34:37.566] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 57.9371 for pixel 0/33 mean/min/max = 45.2711/32.5504/57.9918
[12:34:37.566] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 57.7165 for pixel 13/15 mean/min/max = 45.149/32.5273/57.7708
[12:34:37.566] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 62.4791 for pixel 50/70 mean/min/max = 47.1987/31.8565/62.5409
[12:34:37.567] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 58.2033 for pixel 12/3 mean/min/max = 44.995/31.7412/58.2489
[12:34:37.567] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 56.0637 for pixel 11/78 mean/min/max = 44.4003/32.6453/56.1553
[12:34:37.567] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 58.4433 for pixel 11/37 mean/min/max = 45.614/32.7449/58.4831
[12:34:37.567] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 59.2204 for pixel 0/3 mean/min/max = 45.7026/32.0436/59.3616
[12:34:37.568] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 58.4192 for pixel 48/9 mean/min/max = 45.5462/32.6205/58.4719
[12:34:37.568] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[12:34:37.656] <TB1> INFO: Expecting 514560 events.
[12:34:50.573] <TB1> INFO: 514560 events read in total (12361ms).
[12:34:50.579] <TB1> INFO: Expecting 514560 events.
[12:35:03.231] <TB1> INFO: 514560 events read in total (12242ms).
[12:35:03.237] <TB1> INFO: Expecting 514560 events.
[12:35:15.993] <TB1> INFO: 514560 events read in total (12335ms).
[12:35:16.003] <TB1> INFO: Expecting 514560 events.
[12:35:28.768] <TB1> INFO: 514560 events read in total (12348ms).
[12:35:28.777] <TB1> INFO: Expecting 514560 events.
[12:35:41.587] <TB1> INFO: 514560 events read in total (12390ms).
[12:35:41.600] <TB1> INFO: Expecting 514560 events.
[12:35:54.268] <TB1> INFO: 514560 events read in total (12256ms).
[12:35:54.281] <TB1> INFO: Expecting 514560 events.
[12:36:07.055] <TB1> INFO: 514560 events read in total (12356ms).
[12:36:07.070] <TB1> INFO: Expecting 514560 events.
[12:36:19.793] <TB1> INFO: 514560 events read in total (12306ms).
[12:36:19.809] <TB1> INFO: Expecting 514560 events.
[12:36:32.668] <TB1> INFO: 514560 events read in total (12443ms).
[12:36:32.686] <TB1> INFO: Expecting 514560 events.
[12:36:45.500] <TB1> INFO: 514560 events read in total (12411ms).
[12:36:45.520] <TB1> INFO: Expecting 514560 events.
[12:36:58.315] <TB1> INFO: 514560 events read in total (12389ms).
[12:36:58.337] <TB1> INFO: Expecting 514560 events.
[12:37:11.043] <TB1> INFO: 514560 events read in total (12297ms).
[12:37:11.066] <TB1> INFO: Expecting 514560 events.
[12:37:23.887] <TB1> INFO: 514560 events read in total (12417ms).
[12:37:23.912] <TB1> INFO: Expecting 514560 events.
[12:37:36.729] <TB1> INFO: 514560 events read in total (12415ms).
[12:37:36.757] <TB1> INFO: Expecting 514560 events.
[12:37:49.481] <TB1> INFO: 514560 events read in total (12323ms).
[12:37:49.514] <TB1> INFO: Expecting 514560 events.
[12:38:02.256] <TB1> INFO: 514560 events read in total (12340ms).
[12:38:02.286] <TB1> INFO: Test took 204718ms.
[12:38:03.140] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:38:03.148] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[12:38:03.148] <TB1> INFO: run 1 of 1
[12:38:03.412] <TB1> INFO: Expecting 6281600 events.
[12:38:38.725] <TB1> INFO: 741050 events read in total (34757ms).
[12:39:13.826] <TB1> INFO: 1480980 events read in total (69858ms).
[12:39:48.577] <TB1> INFO: 2220260 events read in total (104609ms).
[12:40:23.346] <TB1> INFO: 2960710 events read in total (139378ms).
[12:40:58.251] <TB1> INFO: 3695620 events read in total (174283ms).
[12:41:33.114] <TB1> INFO: 4426690 events read in total (209146ms).
[12:42:08.006] <TB1> INFO: 5155960 events read in total (244038ms).
[12:42:42.560] <TB1> INFO: 5884260 events read in total (278592ms).
[12:43:01.861] <TB1> INFO: 6281600 events read in total (297893ms).
[12:43:01.907] <TB1> INFO: Test took 298759ms.
[12:43:26.403] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.006478 .. 255.000000
[12:43:26.478] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[12:43:26.486] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[12:43:26.486] <TB1> INFO: run 1 of 1
[12:43:26.748] <TB1> INFO: Expecting 10649600 events.
[12:44:01.885] <TB1> INFO: 712200 events read in total (34575ms).
[12:44:36.180] <TB1> INFO: 1424750 events read in total (68870ms).
[12:45:10.606] <TB1> INFO: 2137100 events read in total (103296ms).
[12:45:45.008] <TB1> INFO: 2849700 events read in total (137698ms).
[12:46:19.207] <TB1> INFO: 3562030 events read in total (171897ms).
[12:46:53.299] <TB1> INFO: 4274580 events read in total (205989ms).
[12:47:27.641] <TB1> INFO: 4987470 events read in total (240331ms).
[12:48:02.044] <TB1> INFO: 5699920 events read in total (274734ms).
[12:48:35.788] <TB1> INFO: 6412110 events read in total (308478ms).
[12:49:09.792] <TB1> INFO: 7123370 events read in total (342482ms).
[12:49:43.969] <TB1> INFO: 7834860 events read in total (376659ms).
[12:50:18.094] <TB1> INFO: 8545470 events read in total (410784ms).
[12:50:52.170] <TB1> INFO: 9256010 events read in total (444860ms).
[12:51:26.896] <TB1> INFO: 9966570 events read in total (479586ms).
[12:52:00.556] <TB1> INFO: 10649600 events read in total (513246ms).
[12:52:00.683] <TB1> INFO: Test took 514198ms.
[12:52:34.222] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 1.030524 .. 46.656902
[12:52:34.303] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 1 .. 56 (-1/-1) hits flags = 528 (plus default)
[12:52:34.311] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[12:52:34.311] <TB1> INFO: run 1 of 1
[12:52:34.572] <TB1> INFO: Expecting 2329600 events.
[12:53:13.834] <TB1> INFO: 949740 events read in total (38705ms).
[12:53:51.729] <TB1> INFO: 1898240 events read in total (76600ms).
[12:54:09.579] <TB1> INFO: 2329600 events read in total (94451ms).
[12:54:09.593] <TB1> INFO: Test took 95283ms.
[12:54:23.323] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 4.500000 .. 44.232181
[12:54:23.398] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 4 .. 54 (-1/-1) hits flags = 528 (plus default)
[12:54:23.406] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[12:54:23.406] <TB1> INFO: run 1 of 1
[12:54:23.668] <TB1> INFO: Expecting 2121600 events.
[12:55:02.165] <TB1> INFO: 953160 events read in total (37941ms).
[12:55:40.079] <TB1> INFO: 1904840 events read in total (75855ms).
[12:55:49.101] <TB1> INFO: 2121600 events read in total (84877ms).
[12:55:49.114] <TB1> INFO: Test took 85707ms.
[12:56:03.524] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 4.500000 .. 43.609842
[12:56:03.600] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 4 .. 53 (-1/-1) hits flags = 528 (plus default)
[12:56:03.634] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[12:56:03.634] <TB1> INFO: run 1 of 1
[12:56:03.919] <TB1> INFO: Expecting 2080000 events.
[12:56:42.565] <TB1> INFO: 961480 events read in total (38090ms).
[12:57:20.539] <TB1> INFO: 1922520 events read in total (76064ms).
[12:57:27.188] <TB1> INFO: 2080000 events read in total (82713ms).
[12:57:27.203] <TB1> INFO: Test took 83569ms.
[12:57:41.641] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[12:57:41.641] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[12:57:41.650] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[12:57:41.650] <TB1> INFO: run 1 of 1
[12:57:41.937] <TB1> INFO: Expecting 1705600 events.
[12:58:19.883] <TB1> INFO: 877620 events read in total (37390ms).
[12:58:55.078] <TB1> INFO: 1705600 events read in total (72585ms).
[12:58:55.102] <TB1> INFO: Test took 73454ms.
[12:59:09.169] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C0.dat
[12:59:09.169] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C1.dat
[12:59:09.169] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C2.dat
[12:59:09.170] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C3.dat
[12:59:09.170] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C4.dat
[12:59:09.170] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C5.dat
[12:59:09.170] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C6.dat
[12:59:09.171] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C7.dat
[12:59:09.171] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C8.dat
[12:59:09.171] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C9.dat
[12:59:09.171] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C10.dat
[12:59:09.171] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C11.dat
[12:59:09.172] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C12.dat
[12:59:09.172] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C13.dat
[12:59:09.172] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C14.dat
[12:59:09.172] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C15.dat
[12:59:09.172] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C0.dat
[12:59:09.191] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C1.dat
[12:59:09.210] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C2.dat
[12:59:09.228] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C3.dat
[12:59:09.246] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C4.dat
[12:59:09.265] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C5.dat
[12:59:09.281] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C6.dat
[12:59:09.293] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C7.dat
[12:59:09.312] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C8.dat
[12:59:09.330] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C9.dat
[12:59:09.342] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C10.dat
[12:59:09.353] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C11.dat
[12:59:09.364] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C12.dat
[12:59:09.376] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C13.dat
[12:59:09.388] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C14.dat
[12:59:09.399] <TB1> INFO: write trim parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//trimParameters35_C15.dat
[12:59:09.411] <TB1> INFO: PixTestTrim::trimTest() done
[12:59:09.411] <TB1> INFO: vtrim: 100 96 95 93 104 93 88 80 92 95 98 84 83 97 90 93
[12:59:09.411] <TB1> INFO: vthrcomp: 89 83 88 101 96 96 101 84 85 100 86 85 91 90 87 91
[12:59:09.411] <TB1> INFO: vcal mean: 34.96 34.96 34.90 34.93 35.00 34.94 34.93 34.95 34.98 34.99 34.97 34.96 34.98 34.93 34.98 34.97
[12:59:09.411] <TB1> INFO: vcal RMS: 0.92 0.91 0.86 1.10 0.91 0.93 1.09 0.87 0.82 0.85 1.07 1.00 0.79 0.83 0.86 0.80
[12:59:09.411] <TB1> INFO: bits mean: 9.15 9.44 8.87 9.72 9.23 9.36 9.80 9.39 9.19 9.41 9.44 9.65 9.80 9.67 9.13 9.15
[12:59:09.411] <TB1> INFO: bits RMS: 2.63 2.75 2.70 2.59 2.81 2.75 2.65 2.68 2.74 2.69 2.58 2.72 2.55 2.51 2.86 2.75
[12:59:09.417] <TB1> INFO: ----------------------------------------------------------------------
[12:59:09.417] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 5, vtrims = 254 126 63 32
[12:59:09.417] <TB1> INFO: ----------------------------------------------------------------------
[12:59:09.419] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 5 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[12:59:09.427] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[12:59:09.427] <TB1> INFO: run 1 of 1
[12:59:09.696] <TB1> INFO: Expecting 4160000 events.
[12:59:51.864] <TB1> INFO: 932145 events read in total (41612ms).
[13:00:33.298] <TB1> INFO: 1858950 events read in total (83046ms).
[13:01:14.267] <TB1> INFO: 2778570 events read in total (124015ms).
[13:01:55.434] <TB1> INFO: 3695985 events read in total (165182ms).
[13:02:16.545] <TB1> INFO: 4160000 events read in total (186293ms).
[13:02:16.582] <TB1> INFO: Test took 187154ms.
[13:02:46.673] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 5 dacrange: 0 .. 172 (-1/-1) hits flags = 528 (plus default)
[13:02:46.681] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:02:46.681] <TB1> INFO: run 1 of 1
[13:02:46.967] <TB1> INFO: Expecting 3598400 events.
[13:03:29.208] <TB1> INFO: 954180 events read in total (41685ms).
[13:04:11.144] <TB1> INFO: 1901765 events read in total (83621ms).
[13:04:53.020] <TB1> INFO: 2839490 events read in total (125497ms).
[13:05:27.196] <TB1> INFO: 3598400 events read in total (159673ms).
[13:05:27.240] <TB1> INFO: Test took 160559ms.
[13:05:56.476] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 5 dacrange: 0 .. 156 (-1/-1) hits flags = 528 (plus default)
[13:05:56.484] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:05:56.484] <TB1> INFO: run 1 of 1
[13:05:56.748] <TB1> INFO: Expecting 3265600 events.
[13:06:41.231] <TB1> INFO: 997490 events read in total (43927ms).
[13:07:24.138] <TB1> INFO: 1984260 events read in total (86834ms).
[13:08:07.278] <TB1> INFO: 2962575 events read in total (129975ms).
[13:08:20.724] <TB1> INFO: 3265600 events read in total (143421ms).
[13:08:20.757] <TB1> INFO: Test took 144272ms.
[13:08:45.912] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 5 dacrange: 0 .. 157 (-1/-1) hits flags = 528 (plus default)
[13:08:45.920] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:08:45.920] <TB1> INFO: run 1 of 1
[13:08:46.184] <TB1> INFO: Expecting 3286400 events.
[13:09:29.616] <TB1> INFO: 993190 events read in total (42875ms).
[13:10:12.522] <TB1> INFO: 1976375 events read in total (85781ms).
[13:10:54.878] <TB1> INFO: 2950325 events read in total (128138ms).
[13:11:09.692] <TB1> INFO: 3286400 events read in total (142951ms).
[13:11:09.726] <TB1> INFO: Test took 143806ms.
[13:11:33.972] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 5 dacrange: 0 .. 157 (-1/-1) hits flags = 528 (plus default)
[13:11:33.980] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:11:33.980] <TB1> INFO: run 1 of 1
[13:11:34.259] <TB1> INFO: Expecting 3286400 events.
[13:12:18.217] <TB1> INFO: 992435 events read in total (43402ms).
[13:13:02.331] <TB1> INFO: 1974750 events read in total (87516ms).
[13:13:44.826] <TB1> INFO: 2947875 events read in total (130011ms).
[13:13:59.693] <TB1> INFO: 3286400 events read in total (144878ms).
[13:13:59.724] <TB1> INFO: Test took 145743ms.
[13:14:25.707] <TB1> INFO: PixTestTrim::trimBitTest() done
[13:14:25.708] <TB1> INFO: PixTestTrim::doTest() done, duration: 2955 seconds
[13:14:25.708] <TB1> INFO: Decoding statistics:
[13:14:25.708] <TB1> INFO: General information:
[13:14:25.708] <TB1> INFO: 16bit words read: 0
[13:14:25.708] <TB1> INFO: valid events total: 0
[13:14:25.708] <TB1> INFO: empty events: 0
[13:14:25.708] <TB1> INFO: valid events with pixels: 0
[13:14:25.708] <TB1> INFO: valid pixel hits: 0
[13:14:25.708] <TB1> INFO: Event errors: 0
[13:14:25.709] <TB1> INFO: start marker: 0
[13:14:25.709] <TB1> INFO: stop marker: 0
[13:14:25.709] <TB1> INFO: overflow: 0
[13:14:25.709] <TB1> INFO: invalid 5bit words: 0
[13:14:25.709] <TB1> INFO: invalid XOR eye diagram: 0
[13:14:25.709] <TB1> INFO: frame (failed synchr.): 0
[13:14:25.709] <TB1> INFO: idle data (no TBM trl): 0
[13:14:25.709] <TB1> INFO: no data (only TBM hdr): 0
[13:14:25.709] <TB1> INFO: TBM errors: 0
[13:14:25.709] <TB1> INFO: flawed TBM headers: 0
[13:14:25.709] <TB1> INFO: flawed TBM trailers: 0
[13:14:25.709] <TB1> INFO: event ID mismatches: 0
[13:14:25.709] <TB1> INFO: ROC errors: 0
[13:14:25.709] <TB1> INFO: missing ROC header(s): 0
[13:14:25.709] <TB1> INFO: misplaced readback start: 0
[13:14:25.709] <TB1> INFO: Pixel decoding errors: 0
[13:14:25.709] <TB1> INFO: pixel data incomplete: 0
[13:14:25.709] <TB1> INFO: pixel address: 0
[13:14:25.709] <TB1> INFO: pulse height fill bit: 0
[13:14:25.709] <TB1> INFO: buffer corruption: 0
[13:14:26.449] <TB1> INFO: ######################################################################
[13:14:26.449] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[13:14:26.449] <TB1> INFO: ######################################################################
[13:14:26.736] <TB1> INFO: Expecting 41600 events.
[13:14:30.440] <TB1> INFO: 41600 events read in total (3148ms).
[13:14:30.440] <TB1> INFO: Test took 3990ms.
[13:14:30.913] <TB1> INFO: Expecting 41600 events.
[13:14:34.690] <TB1> INFO: 41600 events read in total (3221ms).
[13:14:34.691] <TB1> INFO: Test took 4045ms.
[13:14:34.976] <TB1> INFO: Expecting 41600 events.
[13:14:38.740] <TB1> INFO: 41600 events read in total (3208ms).
[13:14:38.740] <TB1> INFO: Test took 4028ms.
[13:14:38.995] <TB1> INFO: Expecting 2560 events.
[13:14:39.847] <TB1> INFO: 2560 events read in total (296ms).
[13:14:39.848] <TB1> INFO: Test took 1095ms.
[13:14:40.152] <TB1> INFO: Expecting 2560 events.
[13:14:41.005] <TB1> INFO: 2560 events read in total (297ms).
[13:14:41.005] <TB1> INFO: Test took 1157ms.
[13:14:41.313] <TB1> INFO: Expecting 2560 events.
[13:14:42.165] <TB1> INFO: 2560 events read in total (296ms).
[13:14:42.165] <TB1> INFO: Test took 1159ms.
[13:14:42.470] <TB1> INFO: Expecting 2560 events.
[13:14:43.322] <TB1> INFO: 2560 events read in total (296ms).
[13:14:43.322] <TB1> INFO: Test took 1157ms.
[13:14:43.627] <TB1> INFO: Expecting 2560 events.
[13:14:44.479] <TB1> INFO: 2560 events read in total (296ms).
[13:14:44.479] <TB1> INFO: Test took 1157ms.
[13:14:44.784] <TB1> INFO: Expecting 2560 events.
[13:14:45.636] <TB1> INFO: 2560 events read in total (296ms).
[13:14:45.636] <TB1> INFO: Test took 1156ms.
[13:14:45.941] <TB1> INFO: Expecting 2560 events.
[13:14:46.799] <TB1> INFO: 2560 events read in total (302ms).
[13:14:46.799] <TB1> INFO: Test took 1162ms.
[13:14:47.104] <TB1> INFO: Expecting 2560 events.
[13:14:47.956] <TB1> INFO: 2560 events read in total (296ms).
[13:14:47.956] <TB1> INFO: Test took 1157ms.
[13:14:48.261] <TB1> INFO: Expecting 2560 events.
[13:14:49.112] <TB1> INFO: 2560 events read in total (295ms).
[13:14:49.112] <TB1> INFO: Test took 1156ms.
[13:14:49.417] <TB1> INFO: Expecting 2560 events.
[13:14:50.270] <TB1> INFO: 2560 events read in total (297ms).
[13:14:50.270] <TB1> INFO: Test took 1158ms.
[13:14:50.575] <TB1> INFO: Expecting 2560 events.
[13:14:51.430] <TB1> INFO: 2560 events read in total (299ms).
[13:14:51.430] <TB1> INFO: Test took 1160ms.
[13:14:51.735] <TB1> INFO: Expecting 2560 events.
[13:14:52.588] <TB1> INFO: 2560 events read in total (297ms).
[13:14:52.588] <TB1> INFO: Test took 1158ms.
[13:14:52.893] <TB1> INFO: Expecting 2560 events.
[13:14:53.746] <TB1> INFO: 2560 events read in total (297ms).
[13:14:53.746] <TB1> INFO: Test took 1158ms.
[13:14:54.051] <TB1> INFO: Expecting 2560 events.
[13:14:54.904] <TB1> INFO: 2560 events read in total (297ms).
[13:14:54.904] <TB1> INFO: Test took 1158ms.
[13:14:55.209] <TB1> INFO: Expecting 2560 events.
[13:14:56.062] <TB1> INFO: 2560 events read in total (297ms).
[13:14:56.062] <TB1> INFO: Test took 1158ms.
[13:14:56.367] <TB1> INFO: Expecting 2560 events.
[13:14:57.220] <TB1> INFO: 2560 events read in total (297ms).
[13:14:57.220] <TB1> INFO: Test took 1158ms.
[13:14:57.222] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:14:57.526] <TB1> INFO: Expecting 655360 events.
[13:15:14.529] <TB1> INFO: 655360 events read in total (16447ms).
[13:15:14.541] <TB1> INFO: Expecting 655360 events.
[13:15:31.249] <TB1> INFO: 655360 events read in total (16306ms).
[13:15:31.260] <TB1> INFO: Expecting 655360 events.
[13:15:47.810] <TB1> INFO: 655360 events read in total (16148ms).
[13:15:47.833] <TB1> INFO: Expecting 655360 events.
[13:16:04.703] <TB1> INFO: 655360 events read in total (16468ms).
[13:16:04.722] <TB1> INFO: Expecting 655360 events.
[13:16:21.391] <TB1> INFO: 655360 events read in total (16268ms).
[13:16:21.413] <TB1> INFO: Expecting 655360 events.
[13:16:38.223] <TB1> INFO: 655360 events read in total (16409ms).
[13:16:38.248] <TB1> INFO: Expecting 655360 events.
[13:16:55.250] <TB1> INFO: 655360 events read in total (16600ms).
[13:16:55.279] <TB1> INFO: Expecting 655360 events.
[13:17:12.042] <TB1> INFO: 655360 events read in total (16361ms).
[13:17:12.075] <TB1> INFO: Expecting 655360 events.
[13:17:28.922] <TB1> INFO: 655360 events read in total (16445ms).
[13:17:28.958] <TB1> INFO: Expecting 655360 events.
[13:17:45.568] <TB1> INFO: 655360 events read in total (16208ms).
[13:17:45.607] <TB1> INFO: Expecting 655360 events.
[13:18:02.262] <TB1> INFO: 655360 events read in total (16253ms).
[13:18:02.305] <TB1> INFO: Expecting 655360 events.
[13:18:19.002] <TB1> INFO: 655360 events read in total (16295ms).
[13:18:19.055] <TB1> INFO: Expecting 655360 events.
[13:18:35.739] <TB1> INFO: 655360 events read in total (16282ms).
[13:18:35.794] <TB1> INFO: Expecting 655360 events.
[13:18:52.458] <TB1> INFO: 655360 events read in total (16263ms).
[13:18:52.515] <TB1> INFO: Expecting 655360 events.
[13:19:09.128] <TB1> INFO: 655360 events read in total (16211ms).
[13:19:09.186] <TB1> INFO: Expecting 655360 events.
[13:19:25.856] <TB1> INFO: 655360 events read in total (16268ms).
[13:19:25.919] <TB1> INFO: Test took 268697ms.
[13:19:26.001] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:19:26.230] <TB1> INFO: Expecting 655360 events.
[13:19:43.045] <TB1> INFO: 655360 events read in total (16258ms).
[13:19:43.055] <TB1> INFO: Expecting 655360 events.
[13:19:59.843] <TB1> INFO: 655360 events read in total (16386ms).
[13:19:59.855] <TB1> INFO: Expecting 655360 events.
[13:20:16.495] <TB1> INFO: 655360 events read in total (16238ms).
[13:20:16.510] <TB1> INFO: Expecting 655360 events.
[13:20:33.121] <TB1> INFO: 655360 events read in total (16209ms).
[13:20:33.140] <TB1> INFO: Expecting 655360 events.
[13:20:49.869] <TB1> INFO: 655360 events read in total (16328ms).
[13:20:49.891] <TB1> INFO: Expecting 655360 events.
[13:21:06.548] <TB1> INFO: 655360 events read in total (16255ms).
[13:21:06.580] <TB1> INFO: Expecting 655360 events.
[13:21:23.253] <TB1> INFO: 655360 events read in total (16271ms).
[13:21:23.283] <TB1> INFO: Expecting 655360 events.
[13:21:39.808] <TB1> INFO: 655360 events read in total (16123ms).
[13:21:39.843] <TB1> INFO: Expecting 655360 events.
[13:21:56.449] <TB1> INFO: 655360 events read in total (16204ms).
[13:21:56.484] <TB1> INFO: Expecting 655360 events.
[13:22:12.547] <TB1> INFO: 655360 events read in total (15661ms).
[13:22:12.602] <TB1> INFO: Expecting 655360 events.
[13:22:29.241] <TB1> INFO: 655360 events read in total (16237ms).
[13:22:29.288] <TB1> INFO: Expecting 655360 events.
[13:22:45.929] <TB1> INFO: 655360 events read in total (16239ms).
[13:22:45.979] <TB1> INFO: Expecting 655360 events.
[13:23:02.635] <TB1> INFO: 655360 events read in total (16255ms).
[13:23:02.689] <TB1> INFO: Expecting 655360 events.
[13:23:19.313] <TB1> INFO: 655360 events read in total (16223ms).
[13:23:19.369] <TB1> INFO: Expecting 655360 events.
[13:23:35.979] <TB1> INFO: 655360 events read in total (16208ms).
[13:23:36.040] <TB1> INFO: Expecting 655360 events.
[13:23:52.825] <TB1> INFO: 655360 events read in total (16383ms).
[13:23:52.887] <TB1> INFO: Test took 266886ms.
[13:23:53.079] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:53.087] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:53.096] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:53.103] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[13:23:53.110] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[13:23:53.117] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:53.123] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:53.130] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:53.137] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:53.144] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:53.151] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:53.158] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:53.164] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:53.171] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:53.178] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[13:23:53.185] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[13:23:53.192] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[13:23:53.199] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[13:23:53.206] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[13:23:53.213] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[13:23:53.220] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[13:23:53.227] <TB1> INFO: safety margin for low PH: adding 8, margin is now 28
[13:23:53.233] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:53.240] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:53.247] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:53.254] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[13:23:53.284] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C0.dat
[13:23:53.284] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C1.dat
[13:23:53.284] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C2.dat
[13:23:53.284] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C3.dat
[13:23:53.284] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C4.dat
[13:23:53.284] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C5.dat
[13:23:53.284] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C6.dat
[13:23:53.285] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C7.dat
[13:23:53.285] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C8.dat
[13:23:53.285] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C9.dat
[13:23:53.285] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C10.dat
[13:23:53.285] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C11.dat
[13:23:53.285] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C12.dat
[13:23:53.286] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C13.dat
[13:23:53.286] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C14.dat
[13:23:53.286] <TB1> INFO: write dac parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//dacParameters35_C15.dat
[13:23:53.553] <TB1> INFO: Expecting 41600 events.
[13:23:57.024] <TB1> INFO: 41600 events read in total (2915ms).
[13:23:57.025] <TB1> INFO: Test took 3737ms.
[13:23:57.467] <TB1> INFO: Expecting 41600 events.
[13:24:00.909] <TB1> INFO: 41600 events read in total (2886ms).
[13:24:00.910] <TB1> INFO: Test took 3709ms.
[13:24:01.364] <TB1> INFO: Expecting 41600 events.
[13:24:04.833] <TB1> INFO: 41600 events read in total (2913ms).
[13:24:04.833] <TB1> INFO: Test took 3747ms.
[13:24:05.015] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:05.103] <TB1> INFO: Expecting 2560 events.
[13:24:05.956] <TB1> INFO: 2560 events read in total (297ms).
[13:24:05.957] <TB1> INFO: Test took 942ms.
[13:24:05.959] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:06.262] <TB1> INFO: Expecting 2560 events.
[13:24:07.115] <TB1> INFO: 2560 events read in total (297ms).
[13:24:07.115] <TB1> INFO: Test took 1156ms.
[13:24:07.117] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:07.421] <TB1> INFO: Expecting 2560 events.
[13:24:08.276] <TB1> INFO: 2560 events read in total (299ms).
[13:24:08.276] <TB1> INFO: Test took 1159ms.
[13:24:08.279] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:08.581] <TB1> INFO: Expecting 2560 events.
[13:24:09.436] <TB1> INFO: 2560 events read in total (299ms).
[13:24:09.437] <TB1> INFO: Test took 1158ms.
[13:24:09.439] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:09.741] <TB1> INFO: Expecting 2560 events.
[13:24:10.595] <TB1> INFO: 2560 events read in total (297ms).
[13:24:10.595] <TB1> INFO: Test took 1156ms.
[13:24:10.597] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:10.901] <TB1> INFO: Expecting 2560 events.
[13:24:11.756] <TB1> INFO: 2560 events read in total (298ms).
[13:24:11.757] <TB1> INFO: Test took 1160ms.
[13:24:11.758] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:12.063] <TB1> INFO: Expecting 2560 events.
[13:24:12.918] <TB1> INFO: 2560 events read in total (299ms).
[13:24:12.918] <TB1> INFO: Test took 1160ms.
[13:24:12.920] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:13.224] <TB1> INFO: Expecting 2560 events.
[13:24:14.082] <TB1> INFO: 2560 events read in total (302ms).
[13:24:14.083] <TB1> INFO: Test took 1163ms.
[13:24:14.084] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:14.388] <TB1> INFO: Expecting 2560 events.
[13:24:15.242] <TB1> INFO: 2560 events read in total (297ms).
[13:24:15.242] <TB1> INFO: Test took 1158ms.
[13:24:15.244] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:15.548] <TB1> INFO: Expecting 2560 events.
[13:24:16.401] <TB1> INFO: 2560 events read in total (297ms).
[13:24:16.401] <TB1> INFO: Test took 1157ms.
[13:24:16.403] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:16.707] <TB1> INFO: Expecting 2560 events.
[13:24:17.559] <TB1> INFO: 2560 events read in total (296ms).
[13:24:17.560] <TB1> INFO: Test took 1157ms.
[13:24:17.561] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:17.865] <TB1> INFO: Expecting 2560 events.
[13:24:18.724] <TB1> INFO: 2560 events read in total (303ms).
[13:24:18.724] <TB1> INFO: Test took 1163ms.
[13:24:18.725] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:19.029] <TB1> INFO: Expecting 2560 events.
[13:24:19.882] <TB1> INFO: 2560 events read in total (297ms).
[13:24:19.883] <TB1> INFO: Test took 1158ms.
[13:24:19.885] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:20.187] <TB1> INFO: Expecting 2560 events.
[13:24:21.041] <TB1> INFO: 2560 events read in total (297ms).
[13:24:21.041] <TB1> INFO: Test took 1156ms.
[13:24:21.043] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:21.348] <TB1> INFO: Expecting 2560 events.
[13:24:22.201] <TB1> INFO: 2560 events read in total (297ms).
[13:24:22.201] <TB1> INFO: Test took 1158ms.
[13:24:22.203] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:22.507] <TB1> INFO: Expecting 2560 events.
[13:24:23.360] <TB1> INFO: 2560 events read in total (297ms).
[13:24:23.360] <TB1> INFO: Test took 1158ms.
[13:24:23.363] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:23.665] <TB1> INFO: Expecting 2560 events.
[13:24:24.519] <TB1> INFO: 2560 events read in total (298ms).
[13:24:24.520] <TB1> INFO: Test took 1157ms.
[13:24:24.523] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:24.824] <TB1> INFO: Expecting 2560 events.
[13:24:25.683] <TB1> INFO: 2560 events read in total (302ms).
[13:24:25.683] <TB1> INFO: Test took 1160ms.
[13:24:25.685] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:25.988] <TB1> INFO: Expecting 2560 events.
[13:24:26.842] <TB1> INFO: 2560 events read in total (298ms).
[13:24:26.843] <TB1> INFO: Test took 1158ms.
[13:24:26.845] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:27.147] <TB1> INFO: Expecting 2560 events.
[13:24:28.006] <TB1> INFO: 2560 events read in total (302ms).
[13:24:28.007] <TB1> INFO: Test took 1162ms.
[13:24:28.010] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:28.312] <TB1> INFO: Expecting 2560 events.
[13:24:29.165] <TB1> INFO: 2560 events read in total (296ms).
[13:24:29.166] <TB1> INFO: Test took 1157ms.
[13:24:29.167] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:29.471] <TB1> INFO: Expecting 2560 events.
[13:24:30.325] <TB1> INFO: 2560 events read in total (297ms).
[13:24:30.325] <TB1> INFO: Test took 1158ms.
[13:24:30.327] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:30.631] <TB1> INFO: Expecting 2560 events.
[13:24:31.489] <TB1> INFO: 2560 events read in total (301ms).
[13:24:31.489] <TB1> INFO: Test took 1162ms.
[13:24:31.490] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:31.795] <TB1> INFO: Expecting 2560 events.
[13:24:32.649] <TB1> INFO: 2560 events read in total (298ms).
[13:24:32.650] <TB1> INFO: Test took 1160ms.
[13:24:32.653] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:32.954] <TB1> INFO: Expecting 2560 events.
[13:24:33.808] <TB1> INFO: 2560 events read in total (298ms).
[13:24:33.808] <TB1> INFO: Test took 1156ms.
[13:24:33.810] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:34.118] <TB1> INFO: Expecting 2560 events.
[13:24:34.976] <TB1> INFO: 2560 events read in total (302ms).
[13:24:34.977] <TB1> INFO: Test took 1167ms.
[13:24:34.979] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:35.282] <TB1> INFO: Expecting 2560 events.
[13:24:36.135] <TB1> INFO: 2560 events read in total (297ms).
[13:24:36.135] <TB1> INFO: Test took 1156ms.
[13:24:36.137] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:36.441] <TB1> INFO: Expecting 2560 events.
[13:24:37.294] <TB1> INFO: 2560 events read in total (297ms).
[13:24:37.294] <TB1> INFO: Test took 1157ms.
[13:24:37.296] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:37.599] <TB1> INFO: Expecting 2560 events.
[13:24:38.453] <TB1> INFO: 2560 events read in total (297ms).
[13:24:38.453] <TB1> INFO: Test took 1157ms.
[13:24:38.454] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:38.759] <TB1> INFO: Expecting 2560 events.
[13:24:39.616] <TB1> INFO: 2560 events read in total (301ms).
[13:24:39.616] <TB1> INFO: Test took 1162ms.
[13:24:39.617] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:39.922] <TB1> INFO: Expecting 2560 events.
[13:24:40.780] <TB1> INFO: 2560 events read in total (302ms).
[13:24:40.780] <TB1> INFO: Test took 1163ms.
[13:24:40.783] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:24:41.085] <TB1> INFO: Expecting 2560 events.
[13:24:41.939] <TB1> INFO: 2560 events read in total (297ms).
[13:24:41.939] <TB1> INFO: Test took 1156ms.
[13:24:42.397] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 615 seconds
[13:24:42.397] <TB1> INFO: PH scale (per ROC): 77 86 81 77 72 72 74 81 88 86 71 76 82 83 75 83
[13:24:42.397] <TB1> INFO: PH offset (per ROC): 158 147 159 175 181 162 165 177 149 147 175 144 131 150 157 166
[13:24:42.406] <TB1> INFO: Decoding statistics:
[13:24:42.406] <TB1> INFO: General information:
[13:24:42.406] <TB1> INFO: 16bit words read: 91944
[13:24:42.406] <TB1> INFO: valid events total: 10240
[13:24:42.406] <TB1> INFO: empty events: 7734
[13:24:42.406] <TB1> INFO: valid events with pixels: 2506
[13:24:42.406] <TB1> INFO: valid pixel hits: 2506
[13:24:42.406] <TB1> INFO: Event errors: 0
[13:24:42.406] <TB1> INFO: start marker: 0
[13:24:42.406] <TB1> INFO: stop marker: 0
[13:24:42.406] <TB1> INFO: overflow: 0
[13:24:42.406] <TB1> INFO: invalid 5bit words: 0
[13:24:42.406] <TB1> INFO: invalid XOR eye diagram: 0
[13:24:42.406] <TB1> INFO: frame (failed synchr.): 0
[13:24:42.406] <TB1> INFO: idle data (no TBM trl): 0
[13:24:42.406] <TB1> INFO: no data (only TBM hdr): 0
[13:24:42.407] <TB1> INFO: TBM errors: 0
[13:24:42.407] <TB1> INFO: flawed TBM headers: 0
[13:24:42.407] <TB1> INFO: flawed TBM trailers: 0
[13:24:42.407] <TB1> INFO: event ID mismatches: 0
[13:24:42.407] <TB1> INFO: ROC errors: 0
[13:24:42.407] <TB1> INFO: missing ROC header(s): 0
[13:24:42.407] <TB1> INFO: misplaced readback start: 0
[13:24:42.407] <TB1> INFO: Pixel decoding errors: 0
[13:24:42.407] <TB1> INFO: pixel data incomplete: 0
[13:24:42.407] <TB1> INFO: pixel address: 0
[13:24:42.407] <TB1> INFO: pulse height fill bit: 0
[13:24:42.407] <TB1> INFO: buffer corruption: 0
[13:24:42.597] <TB1> INFO: ######################################################################
[13:24:42.597] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[13:24:42.597] <TB1> INFO: ######################################################################
[13:24:42.606] <TB1> INFO: scanning low vcal = 10
[13:24:42.868] <TB1> INFO: Expecting 41600 events.
[13:24:46.402] <TB1> INFO: 41600 events read in total (2978ms).
[13:24:46.402] <TB1> INFO: Test took 3795ms.
[13:24:46.404] <TB1> INFO: scanning low vcal = 20
[13:24:46.707] <TB1> INFO: Expecting 41600 events.
[13:24:50.253] <TB1> INFO: 41600 events read in total (2990ms).
[13:24:50.253] <TB1> INFO: Test took 3849ms.
[13:24:50.254] <TB1> INFO: scanning low vcal = 30
[13:24:50.559] <TB1> INFO: Expecting 41600 events.
[13:24:54.104] <TB1> INFO: 41600 events read in total (2989ms).
[13:24:54.104] <TB1> INFO: Test took 3850ms.
[13:24:54.106] <TB1> INFO: scanning low vcal = 40
[13:24:54.403] <TB1> INFO: Expecting 41600 events.
[13:24:58.570] <TB1> INFO: 41600 events read in total (3610ms).
[13:24:58.571] <TB1> INFO: Test took 4465ms.
[13:24:58.573] <TB1> INFO: scanning low vcal = 50
[13:24:58.837] <TB1> INFO: Expecting 41600 events.
[13:25:03.052] <TB1> INFO: 41600 events read in total (3659ms).
[13:25:03.052] <TB1> INFO: Test took 4479ms.
[13:25:03.055] <TB1> INFO: scanning low vcal = 60
[13:25:03.318] <TB1> INFO: Expecting 41600 events.
[13:25:07.496] <TB1> INFO: 41600 events read in total (3622ms).
[13:25:07.496] <TB1> INFO: Test took 4441ms.
[13:25:07.499] <TB1> INFO: scanning low vcal = 70
[13:25:07.759] <TB1> INFO: Expecting 41600 events.
[13:25:11.970] <TB1> INFO: 41600 events read in total (3655ms).
[13:25:11.971] <TB1> INFO: Test took 4472ms.
[13:25:11.973] <TB1> INFO: scanning low vcal = 80
[13:25:12.237] <TB1> INFO: Expecting 41600 events.
[13:25:16.419] <TB1> INFO: 41600 events read in total (3626ms).
[13:25:16.419] <TB1> INFO: Test took 4446ms.
[13:25:16.422] <TB1> INFO: scanning low vcal = 90
[13:25:16.689] <TB1> INFO: Expecting 41600 events.
[13:25:20.889] <TB1> INFO: 41600 events read in total (3644ms).
[13:25:20.889] <TB1> INFO: Test took 4467ms.
[13:25:20.892] <TB1> INFO: scanning low vcal = 100
[13:25:21.160] <TB1> INFO: Expecting 41600 events.
[13:25:25.355] <TB1> INFO: 41600 events read in total (3638ms).
[13:25:25.356] <TB1> INFO: Test took 4464ms.
[13:25:25.358] <TB1> INFO: scanning low vcal = 110
[13:25:25.617] <TB1> INFO: Expecting 41600 events.
[13:25:29.804] <TB1> INFO: 41600 events read in total (3630ms).
[13:25:29.805] <TB1> INFO: Test took 4447ms.
[13:25:29.807] <TB1> INFO: scanning low vcal = 120
[13:25:30.078] <TB1> INFO: Expecting 41600 events.
[13:25:34.281] <TB1> INFO: 41600 events read in total (3646ms).
[13:25:34.281] <TB1> INFO: Test took 4474ms.
[13:25:34.284] <TB1> INFO: scanning low vcal = 130
[13:25:34.548] <TB1> INFO: Expecting 41600 events.
[13:25:38.752] <TB1> INFO: 41600 events read in total (3648ms).
[13:25:38.753] <TB1> INFO: Test took 4469ms.
[13:25:38.755] <TB1> INFO: scanning low vcal = 140
[13:25:39.018] <TB1> INFO: Expecting 41600 events.
[13:25:43.203] <TB1> INFO: 41600 events read in total (3629ms).
[13:25:43.204] <TB1> INFO: Test took 4449ms.
[13:25:43.206] <TB1> INFO: scanning low vcal = 150
[13:25:43.474] <TB1> INFO: Expecting 41600 events.
[13:25:47.663] <TB1> INFO: 41600 events read in total (3633ms).
[13:25:47.663] <TB1> INFO: Test took 4457ms.
[13:25:47.666] <TB1> INFO: scanning low vcal = 160
[13:25:47.932] <TB1> INFO: Expecting 41600 events.
[13:25:52.145] <TB1> INFO: 41600 events read in total (3657ms).
[13:25:52.145] <TB1> INFO: Test took 4479ms.
[13:25:52.148] <TB1> INFO: scanning low vcal = 170
[13:25:52.432] <TB1> INFO: Expecting 41600 events.
[13:25:56.638] <TB1> INFO: 41600 events read in total (3649ms).
[13:25:56.638] <TB1> INFO: Test took 4490ms.
[13:25:56.642] <TB1> INFO: scanning low vcal = 180
[13:25:56.918] <TB1> INFO: Expecting 41600 events.
[13:26:01.141] <TB1> INFO: 41600 events read in total (3666ms).
[13:26:01.142] <TB1> INFO: Test took 4500ms.
[13:26:01.144] <TB1> INFO: scanning low vcal = 190
[13:26:01.409] <TB1> INFO: Expecting 41600 events.
[13:26:05.628] <TB1> INFO: 41600 events read in total (3662ms).
[13:26:05.629] <TB1> INFO: Test took 4485ms.
[13:26:05.632] <TB1> INFO: scanning low vcal = 200
[13:26:05.892] <TB1> INFO: Expecting 41600 events.
[13:26:10.106] <TB1> INFO: 41600 events read in total (3658ms).
[13:26:10.107] <TB1> INFO: Test took 4475ms.
[13:26:10.109] <TB1> INFO: scanning low vcal = 210
[13:26:10.370] <TB1> INFO: Expecting 41600 events.
[13:26:14.599] <TB1> INFO: 41600 events read in total (3672ms).
[13:26:14.599] <TB1> INFO: Test took 4490ms.
[13:26:14.602] <TB1> INFO: scanning low vcal = 220
[13:26:14.865] <TB1> INFO: Expecting 41600 events.
[13:26:19.073] <TB1> INFO: 41600 events read in total (3652ms).
[13:26:19.073] <TB1> INFO: Test took 4471ms.
[13:26:19.075] <TB1> INFO: scanning low vcal = 230
[13:26:19.339] <TB1> INFO: Expecting 41600 events.
[13:26:23.565] <TB1> INFO: 41600 events read in total (3670ms).
[13:26:23.565] <TB1> INFO: Test took 4489ms.
[13:26:23.568] <TB1> INFO: scanning low vcal = 240
[13:26:23.831] <TB1> INFO: Expecting 41600 events.
[13:26:28.064] <TB1> INFO: 41600 events read in total (3677ms).
[13:26:28.065] <TB1> INFO: Test took 4497ms.
[13:26:28.067] <TB1> INFO: scanning low vcal = 250
[13:26:28.326] <TB1> INFO: Expecting 41600 events.
[13:26:32.544] <TB1> INFO: 41600 events read in total (3661ms).
[13:26:32.544] <TB1> INFO: Test took 4477ms.
[13:26:32.548] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[13:26:32.833] <TB1> INFO: Expecting 41600 events.
[13:26:37.030] <TB1> INFO: 41600 events read in total (3641ms).
[13:26:37.030] <TB1> INFO: Test took 4482ms.
[13:26:37.033] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[13:26:37.296] <TB1> INFO: Expecting 41600 events.
[13:26:41.512] <TB1> INFO: 41600 events read in total (3660ms).
[13:26:41.513] <TB1> INFO: Test took 4480ms.
[13:26:41.515] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[13:26:41.778] <TB1> INFO: Expecting 41600 events.
[13:26:46.002] <TB1> INFO: 41600 events read in total (3668ms).
[13:26:46.002] <TB1> INFO: Test took 4487ms.
[13:26:46.005] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[13:26:46.267] <TB1> INFO: Expecting 41600 events.
[13:26:50.517] <TB1> INFO: 41600 events read in total (3693ms).
[13:26:50.518] <TB1> INFO: Test took 4513ms.
[13:26:50.521] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[13:26:50.806] <TB1> INFO: Expecting 41600 events.
[13:26:55.008] <TB1> INFO: 41600 events read in total (3646ms).
[13:26:55.008] <TB1> INFO: Test took 4487ms.
[13:26:55.535] <TB1> INFO: PixTestGainPedestal::measure() done
[13:27:27.507] <TB1> INFO: PixTestGainPedestal::fit() done
[13:27:27.508] <TB1> INFO: non-linearity mean: 0.959 0.946 0.952 0.955 0.951 0.954 0.955 0.953 0.953 0.964 0.958 0.957 0.961 0.953 0.954 0.949
[13:27:27.508] <TB1> INFO: non-linearity RMS: 0.005 0.006 0.005 0.006 0.006 0.007 0.005 0.005 0.005 0.006 0.008 0.004 0.006 0.006 0.005 0.006
[13:27:27.508] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[13:27:27.551] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[13:27:27.594] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[13:27:27.637] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[13:27:27.680] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[13:27:27.713] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[13:27:27.738] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[13:27:27.763] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[13:27:27.788] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[13:27:27.813] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[13:27:27.839] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[13:27:27.864] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[13:27:27.888] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[13:27:27.914] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[13:27:27.939] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[13:27:27.964] <TB1> INFO: write gain/ped parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[13:27:27.989] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 165 seconds
[13:27:27.989] <TB1> INFO: Decoding statistics:
[13:27:27.989] <TB1> INFO: General information:
[13:27:27.989] <TB1> INFO: 16bit words read: 2662400
[13:27:27.989] <TB1> INFO: valid events total: 166400
[13:27:27.989] <TB1> INFO: empty events: 0
[13:27:27.989] <TB1> INFO: valid events with pixels: 166400
[13:27:27.989] <TB1> INFO: valid pixel hits: 665560
[13:27:27.989] <TB1> INFO: Event errors: 0
[13:27:27.989] <TB1> INFO: start marker: 0
[13:27:27.989] <TB1> INFO: stop marker: 0
[13:27:27.989] <TB1> INFO: overflow: 0
[13:27:27.989] <TB1> INFO: invalid 5bit words: 0
[13:27:27.989] <TB1> INFO: invalid XOR eye diagram: 0
[13:27:27.989] <TB1> INFO: frame (failed synchr.): 0
[13:27:27.989] <TB1> INFO: idle data (no TBM trl): 0
[13:27:27.989] <TB1> INFO: no data (only TBM hdr): 0
[13:27:27.989] <TB1> INFO: TBM errors: 0
[13:27:27.989] <TB1> INFO: flawed TBM headers: 0
[13:27:27.989] <TB1> INFO: flawed TBM trailers: 0
[13:27:27.989] <TB1> INFO: event ID mismatches: 0
[13:27:27.989] <TB1> INFO: ROC errors: 0
[13:27:27.989] <TB1> INFO: missing ROC header(s): 0
[13:27:27.989] <TB1> INFO: misplaced readback start: 0
[13:27:27.989] <TB1> INFO: Pixel decoding errors: 0
[13:27:27.989] <TB1> INFO: pixel data incomplete: 0
[13:27:27.989] <TB1> INFO: pixel address: 0
[13:27:27.989] <TB1> INFO: pulse height fill bit: 0
[13:27:27.989] <TB1> INFO: buffer corruption: 0
[13:27:27.998] <TB1> INFO: readReadbackCal: /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C15.dat
[13:27:27.999] <TB1> INFO: ######################################################################
[13:27:27.999] <TB1> INFO: PixTestReadback::doTest()
[13:27:27.999] <TB1> INFO: ######################################################################
[13:27:27.999] <TB1> INFO: ----------------------------------------------------------------------
[13:27:27.999] <TB1> INFO: PixTestReadback::CalibrateVd()
[13:27:27.999] <TB1> INFO: ----------------------------------------------------------------------
[13:27:37.446] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C0.dat
[13:27:37.446] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C1.dat
[13:27:37.446] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C2.dat
[13:27:37.446] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C3.dat
[13:27:37.446] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C4.dat
[13:27:37.446] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C5.dat
[13:27:37.446] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C6.dat
[13:27:37.447] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C7.dat
[13:27:37.447] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C8.dat
[13:27:37.447] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C9.dat
[13:27:37.447] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C10.dat
[13:27:37.447] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C11.dat
[13:27:37.447] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C12.dat
[13:27:37.447] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C13.dat
[13:27:37.448] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C14.dat
[13:27:37.448] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C15.dat
[13:27:37.475] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[13:27:37.475] <TB1> INFO: ----------------------------------------------------------------------
[13:27:37.475] <TB1> INFO: PixTestReadback::CalibrateVa()
[13:27:37.475] <TB1> INFO: ----------------------------------------------------------------------
[13:27:47.597] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C0.dat
[13:27:47.598] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C1.dat
[13:27:47.598] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C2.dat
[13:27:47.598] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C3.dat
[13:27:47.598] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C4.dat
[13:27:47.598] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C5.dat
[13:27:47.598] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C6.dat
[13:27:47.598] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C7.dat
[13:27:47.598] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C8.dat
[13:27:47.599] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C9.dat
[13:27:47.599] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C10.dat
[13:27:47.599] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C11.dat
[13:27:47.599] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C12.dat
[13:27:47.599] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C13.dat
[13:27:47.599] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C14.dat
[13:27:47.599] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C15.dat
[13:27:47.627] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[13:27:47.627] <TB1> INFO: ----------------------------------------------------------------------
[13:27:47.627] <TB1> INFO: PixTestReadback::readbackVbg()
[13:27:47.627] <TB1> INFO: ----------------------------------------------------------------------
[13:27:54.912] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[13:27:54.912] <TB1> INFO: ----------------------------------------------------------------------
[13:27:54.912] <TB1> INFO: PixTestReadback::getCalibratedVbg()
[13:27:54.912] <TB1> INFO: ----------------------------------------------------------------------
[13:27:54.912] <TB1> INFO: Vbg will be calibrated using Vd calibration
[13:27:54.912] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 145.8calibrated Vbg = 1.1688 :::*/*/*/*/
[13:27:54.912] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 152calibrated Vbg = 1.17279 :::*/*/*/*/
[13:27:54.912] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 153.4calibrated Vbg = 1.17332 :::*/*/*/*/
[13:27:54.912] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 153.4calibrated Vbg = 1.17588 :::*/*/*/*/
[13:27:54.912] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 154.3calibrated Vbg = 1.17006 :::*/*/*/*/
[13:27:54.912] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 164.2calibrated Vbg = 1.18077 :::*/*/*/*/
[13:27:54.912] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 151.9calibrated Vbg = 1.17517 :::*/*/*/*/
[13:27:54.912] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 157.2calibrated Vbg = 1.18229 :::*/*/*/*/
[13:27:54.912] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 147.5calibrated Vbg = 1.18185 :::*/*/*/*/
[13:27:54.912] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 147.4calibrated Vbg = 1.17428 :::*/*/*/*/
[13:27:54.912] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 149.8calibrated Vbg = 1.17566 :::*/*/*/*/
[13:27:54.912] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 149calibrated Vbg = 1.17718 :::*/*/*/*/
[13:27:54.912] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 153calibrated Vbg = 1.16632 :::*/*/*/*/
[13:27:54.912] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 146calibrated Vbg = 1.16742 :::*/*/*/*/
[13:27:54.912] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 158.4calibrated Vbg = 1.17884 :::*/*/*/*/
[13:27:54.913] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 157.5calibrated Vbg = 1.17947 :::*/*/*/*/
[13:27:54.914] <TB1> INFO: ----------------------------------------------------------------------
[13:27:54.914] <TB1> INFO: PixTestReadback::CalibrateIa()
[13:27:54.914] <TB1> INFO: ----------------------------------------------------------------------
[13:30:30.134] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C0.dat
[13:30:30.134] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C1.dat
[13:30:30.134] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C2.dat
[13:30:30.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C3.dat
[13:30:30.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C4.dat
[13:30:30.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C5.dat
[13:30:30.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C6.dat
[13:30:30.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C7.dat
[13:30:30.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C8.dat
[13:30:30.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C9.dat
[13:30:30.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C10.dat
[13:30:30.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C11.dat
[13:30:30.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C12.dat
[13:30:30.135] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C13.dat
[13:30:30.136] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C14.dat
[13:30:30.136] <TB1> INFO: write readback calibration parameters into /usr/local/coldboxDATA/M2267_FullQualification_2016-06-29_10h04m_1467187462//002_FulltestPxar_m20//readbackCal_C15.dat
[13:30:30.164] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[13:30:30.165] <TB1> INFO: PixTestReadback::doTest() done
[13:30:30.165] <TB1> INFO: Decoding statistics:
[13:30:30.166] <TB1> INFO: General information:
[13:30:30.166] <TB1> INFO: 16bit words read: 1024
[13:30:30.166] <TB1> INFO: valid events total: 128
[13:30:30.166] <TB1> INFO: empty events: 128
[13:30:30.166] <TB1> INFO: valid events with pixels: 0
[13:30:30.166] <TB1> INFO: valid pixel hits: 0
[13:30:30.166] <TB1> INFO: Event errors: 0
[13:30:30.166] <TB1> INFO: start marker: 0
[13:30:30.166] <TB1> INFO: stop marker: 0
[13:30:30.166] <TB1> INFO: overflow: 0
[13:30:30.166] <TB1> INFO: invalid 5bit words: 0
[13:30:30.166] <TB1> INFO: invalid XOR eye diagram: 0
[13:30:30.166] <TB1> INFO: frame (failed synchr.): 0
[13:30:30.166] <TB1> INFO: idle data (no TBM trl): 0
[13:30:30.166] <TB1> INFO: no data (only TBM hdr): 0
[13:30:30.166] <TB1> INFO: TBM errors: 0
[13:30:30.166] <TB1> INFO: flawed TBM headers: 0
[13:30:30.166] <TB1> INFO: flawed TBM trailers: 0
[13:30:30.166] <TB1> INFO: event ID mismatches: 0
[13:30:30.166] <TB1> INFO: ROC errors: 0
[13:30:30.166] <TB1> INFO: missing ROC header(s): 0
[13:30:30.166] <TB1> INFO: misplaced readback start: 0
[13:30:30.166] <TB1> INFO: Pixel decoding errors: 0
[13:30:30.166] <TB1> INFO: pixel data incomplete: 0
[13:30:30.166] <TB1> INFO: pixel address: 0
[13:30:30.166] <TB1> INFO: pulse height fill bit: 0
[13:30:30.166] <TB1> INFO: buffer corruption: 0
[13:30:30.189] <TB1> INFO: Decoding statistics:
[13:30:30.189] <TB1> INFO: General information:
[13:30:30.189] <TB1> INFO: 16bit words read: 2755368
[13:30:30.189] <TB1> INFO: valid events total: 176768
[13:30:30.189] <TB1> INFO: empty events: 7862
[13:30:30.189] <TB1> INFO: valid events with pixels: 168906
[13:30:30.189] <TB1> INFO: valid pixel hits: 668066
[13:30:30.189] <TB1> INFO: Event errors: 0
[13:30:30.189] <TB1> INFO: start marker: 0
[13:30:30.189] <TB1> INFO: stop marker: 0
[13:30:30.189] <TB1> INFO: overflow: 0
[13:30:30.189] <TB1> INFO: invalid 5bit words: 0
[13:30:30.189] <TB1> INFO: invalid XOR eye diagram: 0
[13:30:30.189] <TB1> INFO: frame (failed synchr.): 0
[13:30:30.189] <TB1> INFO: idle data (no TBM trl): 0
[13:30:30.189] <TB1> INFO: no data (only TBM hdr): 0
[13:30:30.189] <TB1> INFO: TBM errors: 0
[13:30:30.189] <TB1> INFO: flawed TBM headers: 0
[13:30:30.189] <TB1> INFO: flawed TBM trailers: 0
[13:30:30.189] <TB1> INFO: event ID mismatches: 0
[13:30:30.189] <TB1> INFO: ROC errors: 0
[13:30:30.189] <TB1> INFO: missing ROC header(s): 0
[13:30:30.189] <TB1> INFO: misplaced readback start: 0
[13:30:30.189] <TB1> INFO: Pixel decoding errors: 0
[13:30:30.189] <TB1> INFO: pixel data incomplete: 0
[13:30:30.189] <TB1> INFO: pixel address: 0
[13:30:30.189] <TB1> INFO: pulse height fill bit: 0
[13:30:30.189] <TB1> INFO: buffer corruption: 0
[13:30:30.189] <TB1> INFO: enter test to run
[13:30:30.189] <TB1> INFO: test: exit no parameter change
[13:30:30.312] <TB1> QUIET: Connection to board 153 closed.
[13:30:30.391] <TB1> INFO: pXar: this is the end, my friend