[13:00:53.027] <TB1> INFO: *** Welcome to pxar ***
[13:00:53.027] <TB1> INFO: *** Today: 2015/10/29
[13:00:53.059] <TB1> INFO: *** Version: 9da6
[13:00:53.059] <TB1> INFO: readRocDacs: /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters_C0.dat .. /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters_C15.dat
[13:00:53.060] <TB1> INFO: readTbmDacs: /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//tbmParameters_C0a.dat .. /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//tbmParameters_C0b.dat
[13:00:53.060] <TB1> INFO: readMaskFile: /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//defaultMaskFile.dat
[13:00:53.060] <TB1> INFO: readTrimFile: /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//trimParameters_C0.dat .. /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//trimParameters_C15.dat
[13:00:53.129] <TB1> INFO: clk: 4
[13:00:53.129] <TB1> INFO: ctr: 4
[13:00:53.129] <TB1> INFO: sda: 19
[13:00:53.129] <TB1> INFO: tin: 9
[13:00:53.129] <TB1> INFO: level: 15
[13:00:53.129] <TB1> INFO: triggerdelay: 0
[13:00:53.129] <TB1> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[13:00:53.129] <TB1> INFO: Log level: INFO
[13:00:53.139] <TB1> INFO: Found DTB DTB_WXC03A
[13:00:53.149] <TB1> QUIET: Connection to board DTB_WXC03A opened.
[13:00:53.152] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 154
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WXC03A
MAC address: 40D85511809A
Hostname: pixelDTB154
Comment:
------------------------------------------------------
[13:00:53.155] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[13:00:54.705] <TB1> INFO: DUT info:
[13:00:54.705] <TB1> INFO: The DUT currently contains the following objects:
[13:00:54.705] <TB1> INFO: 2 TBM Cores tbm08c (2 ON)
[13:00:54.705] <TB1> INFO: TBM Core alpha (0): 7 registers set
[13:00:54.705] <TB1> INFO: TBM Core beta (1): 7 registers set
[13:00:54.705] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:00:54.705] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:54.705] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:54.705] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:54.705] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:54.705] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:54.705] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:54.705] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:54.705] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:54.705] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:54.705] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:54.705] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:54.705] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:54.705] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:54.705] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:54.705] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:54.705] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:00:55.106] <TB1> INFO: enter 'restricted' command line mode
[13:00:55.106] <TB1> INFO: enter test to run
[13:00:55.106] <TB1> INFO: test: pretest no parameter change
[13:00:55.106] <TB1> INFO: running: pretest
[13:00:55.113] <TB1> INFO: ######################################################################
[13:00:55.113] <TB1> INFO: PixTestPretest::doTest()
[13:00:55.113] <TB1> INFO: ######################################################################
[13:00:55.115] <TB1> INFO: ----------------------------------------------------------------------
[13:00:55.115] <TB1> INFO: PixTestPretest::programROC()
[13:00:55.115] <TB1> INFO: ----------------------------------------------------------------------
[13:01:13.133] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[13:01:13.133] <TB1> INFO: IA differences per ROC: 18.5 17.7 16.9 18.5 19.3 15.3 19.3 20.1 16.9 16.9 19.3 17.7 20.1 16.9 17.7 19.3
[13:01:13.198] <TB1> INFO: ----------------------------------------------------------------------
[13:01:13.198] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[13:01:13.198] <TB1> INFO: ----------------------------------------------------------------------
[13:01:34.476] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 386.7 mA = 24.1687 mA/ROC
[13:01:34.476] <TB1> INFO: i(loss) [mA/ROC]: 19.3 18.5 19.3 19.3 19.3 19.3 20.1 19.3 18.5 19.3 19.3 19.3 19.3 19.3 19.3 20.1
[13:01:34.509] <TB1> INFO: ----------------------------------------------------------------------
[13:01:34.509] <TB1> INFO: PixTestPretest::findTiming()
[13:01:34.509] <TB1> INFO: ----------------------------------------------------------------------
[13:01:34.509] <TB1> INFO: PixTestCmd::init()

[13:03:08.048] <TB1> INFO: TBM phases: 160MHz: 1, 400MHz: 2, TBM delays: ROC(0/1):5, header/trailer: 1, token: 1
[13:03:08.048] <TB1> INFO: (success/tries = 100/100), width = 3
[13:03:08.050] <TB1> INFO: ----------------------------------------------------------------------
[13:03:08.050] <TB1> INFO: PixTestPretest::findWorkingPixel()
[13:03:08.050] <TB1> INFO: ----------------------------------------------------------------------
[13:03:08.187] <TB1> INFO: Expecting 231680 events.


[13:03:16.125] <TB1> INFO: 231680 events read in total (7223ms).
[13:03:16.129] <TB1> INFO: Test took 8076ms.
[13:03:16.541] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[13:03:16.577] <TB1> INFO: ----------------------------------------------------------------------
[13:03:16.577] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[13:03:16.577] <TB1> INFO: ----------------------------------------------------------------------
[13:03:16.714] <TB1> INFO: Expecting 231680 events.
[13:03:25.584] <TB1> INFO: 231680 events read in total (8155ms).
[13:03:25.587] <TB1> INFO: Test took 9005ms.
[13:03:26.002] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[13:03:26.002] <TB1> INFO: CalDel: 144 107 113 125 136 141 143 132 140 160 149 140 144 134 146 115
[13:03:26.002] <TB1> INFO: VthrComp: 51 51 53 51 51 51 51 51 51 51 51 51 51 51 51 51
[13:03:26.005] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters_C0.dat
[13:03:26.006] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters_C1.dat
[13:03:26.006] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters_C2.dat
[13:03:26.006] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters_C3.dat
[13:03:26.006] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters_C4.dat
[13:03:26.006] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters_C5.dat
[13:03:26.006] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters_C6.dat
[13:03:26.006] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters_C7.dat
[13:03:26.006] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters_C8.dat
[13:03:26.006] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters_C9.dat
[13:03:26.007] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters_C10.dat
[13:03:26.007] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters_C11.dat
[13:03:26.007] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters_C12.dat
[13:03:26.007] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters_C13.dat
[13:03:26.007] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters_C14.dat
[13:03:26.007] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters_C15.dat
[13:03:26.007] <TB1> INFO: write tbm parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//tbmParameters_C0a.dat
[13:03:26.007] <TB1> INFO: write tbm parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//tbmParameters_C0b.dat
[13:03:26.007] <TB1> INFO: PixTestPretest::doTest() done, duration: 150 seconds
[13:03:26.071] <TB1> INFO: enter test to run
[13:03:26.071] <TB1> INFO: test: fulltest no parameter change
[13:03:26.071] <TB1> INFO: running: fulltest
[13:03:26.071] <TB1> INFO: ######################################################################
[13:03:26.071] <TB1> INFO: PixTestFullTest::doTest()
[13:03:26.071] <TB1> INFO: ######################################################################
[13:03:26.073] <TB1> INFO: ######################################################################
[13:03:26.073] <TB1> INFO: PixTestAlive::doTest()
[13:03:26.073] <TB1> INFO: ######################################################################
[13:03:26.074] <TB1> INFO: ----------------------------------------------------------------------
[13:03:26.074] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:03:26.074] <TB1> INFO: ----------------------------------------------------------------------
[13:03:26.380] <TB1> INFO: Expecting 41600 events.
[13:03:30.657] <TB1> INFO: 41600 events read in total (3563ms).
[13:03:30.657] <TB1> INFO: Test took 4582ms.
[13:03:30.663] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:31.043] <TB1> INFO: PixTestAlive::aliveTest() done
[13:03:31.043] <TB1> INFO: number of dead pixels (per ROC): 4 4 1 0 0 0 0 0 1 0 0 0 2 4 1 2
[13:03:31.045] <TB1> INFO: ----------------------------------------------------------------------
[13:03:31.045] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:03:31.045] <TB1> INFO: ----------------------------------------------------------------------
[13:03:31.356] <TB1> INFO: Expecting 41600 events.
[13:03:34.431] <TB1> INFO: 41600 events read in total (2360ms).
[13:03:34.431] <TB1> INFO: Test took 3385ms.
[13:03:34.431] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:34.431] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[13:03:34.831] <TB1> INFO: PixTestAlive::maskTest() done
[13:03:34.831] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:03:34.833] <TB1> INFO: ----------------------------------------------------------------------
[13:03:34.833] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:03:34.833] <TB1> INFO: ----------------------------------------------------------------------
[13:03:35.146] <TB1> INFO: Expecting 41600 events.
[13:03:39.378] <TB1> INFO: 41600 events read in total (3517ms).
[13:03:39.379] <TB1> INFO: Test took 4544ms.
[13:03:39.385] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:39.770] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[13:03:39.770] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:03:39.771] <TB1> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[13:03:39.771] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:03:39.771] <TB1> INFO: Decoding statistics:
[13:03:39.771] <TB1> INFO: General information:
[13:03:39.771] <TB1> INFO: 16bit words read: 0
[13:03:39.771] <TB1> INFO: valid events total: 0
[13:03:39.771] <TB1> INFO: empty events: 0
[13:03:39.771] <TB1> INFO: valid events with pixels: 0
[13:03:39.771] <TB1> INFO: valid pixel hits: 0
[13:03:39.771] <TB1> INFO: Event errors: 0
[13:03:39.771] <TB1> INFO: start marker: 0
[13:03:39.771] <TB1> INFO: stop marker: 0
[13:03:39.771] <TB1> INFO: overflow: 0
[13:03:39.771] <TB1> INFO: invalid 5bit words: 0
[13:03:39.771] <TB1> INFO: invalid XOR eye diagram: 0
[13:03:39.772] <TB1> INFO: TBM errors: 0
[13:03:39.772] <TB1> INFO: flawed TBM headers: 0
[13:03:39.772] <TB1> INFO: flawed TBM trailers: 0
[13:03:39.772] <TB1> INFO: event ID mismatches: 0
[13:03:39.772] <TB1> INFO: ROC errors: 0
[13:03:39.772] <TB1> INFO: missing ROC header(s): 0
[13:03:39.772] <TB1> INFO: misplaced readback start: 0
[13:03:39.772] <TB1> INFO: Pixel decoding errors: 0
[13:03:39.772] <TB1> INFO: pixel data incomplete: 0
[13:03:39.772] <TB1> INFO: pixel address: 0
[13:03:39.772] <TB1> INFO: pulse height fill bit: 0
[13:03:39.772] <TB1> INFO: buffer corruption: 0
[13:03:39.782] <TB1> INFO: ######################################################################
[13:03:39.782] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[13:03:39.782] <TB1> INFO: ######################################################################
[13:03:39.784] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[13:03:39.795] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[13:03:39.795] <TB1> INFO: run 1 of 1
[13:03:40.101] <TB1> INFO: Expecting 3120000 events.
[13:04:28.418] <TB1> INFO: 1045090 events read in total (47602ms).
[13:05:13.623] <TB1> INFO: 2070355 events read in total (92808ms).
[13:05:59.571] <TB1> INFO: 3120000 events read in total (138756ms).
[13:05:59.628] <TB1> INFO: Test took 139833ms.
[13:05:59.738] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:24.154] <TB1> INFO: PixTestBBMap::doTest() done, duration: 164 seconds
[13:06:24.154] <TB1> INFO: number of dead bumps (per ROC): 5 2 2 2 0 2 0 1 2 2 2 5 1 1 3 2
[13:06:24.154] <TB1> INFO: separation cut (per ROC): 101 91 106 103 99 99 100 106 88 76 90 97 81 80 84 111
[13:06:24.154] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:24.154] <TB1> INFO: Decoding statistics:
[13:06:24.154] <TB1> INFO: General information:
[13:06:24.154] <TB1> INFO: 16bit words read: 0
[13:06:24.154] <TB1> INFO: valid events total: 0
[13:06:24.154] <TB1> INFO: empty events: 0
[13:06:24.154] <TB1> INFO: valid events with pixels: 0
[13:06:24.154] <TB1> INFO: valid pixel hits: 0
[13:06:24.154] <TB1> INFO: Event errors: 0
[13:06:24.154] <TB1> INFO: start marker: 0
[13:06:24.154] <TB1> INFO: stop marker: 0
[13:06:24.154] <TB1> INFO: overflow: 0
[13:06:24.154] <TB1> INFO: invalid 5bit words: 0
[13:06:24.154] <TB1> INFO: invalid XOR eye diagram: 0
[13:06:24.154] <TB1> INFO: TBM errors: 0
[13:06:24.154] <TB1> INFO: flawed TBM headers: 0
[13:06:24.154] <TB1> INFO: flawed TBM trailers: 0
[13:06:24.154] <TB1> INFO: event ID mismatches: 0
[13:06:24.154] <TB1> INFO: ROC errors: 0
[13:06:24.154] <TB1> INFO: missing ROC header(s): 0
[13:06:24.154] <TB1> INFO: misplaced readback start: 0
[13:06:24.154] <TB1> INFO: Pixel decoding errors: 0
[13:06:24.154] <TB1> INFO: pixel data incomplete: 0
[13:06:24.154] <TB1> INFO: pixel address: 0
[13:06:24.154] <TB1> INFO: pulse height fill bit: 0
[13:06:24.154] <TB1> INFO: buffer corruption: 0
[13:06:24.239] <TB1> INFO: ######################################################################
[13:06:24.239] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[13:06:24.239] <TB1> INFO: ######################################################################
[13:06:24.239] <TB1> INFO: ----------------------------------------------------------------------
[13:06:24.239] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[13:06:24.239] <TB1> INFO: ----------------------------------------------------------------------
[13:06:24.239] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[13:06:24.247] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[13:06:24.247] <TB1> INFO: run 1 of 1
[13:06:24.550] <TB1> INFO: Expecting 26208000 events.
[13:06:56.981] <TB1> INFO: 1099450 events read in total (31716ms).
[13:07:27.535] <TB1> INFO: 2178450 events read in total (62270ms).
[13:07:58.853] <TB1> INFO: 3253950 events read in total (93588ms).
[13:08:29.810] <TB1> INFO: 4325700 events read in total (124545ms).
[13:09:01.645] <TB1> INFO: 5397400 events read in total (156380ms).
[13:09:33.763] <TB1> INFO: 6469850 events read in total (188498ms).
[13:10:04.592] <TB1> INFO: 7542050 events read in total (219327ms).
[13:10:35.736] <TB1> INFO: 8612750 events read in total (250471ms).
[13:11:06.833] <TB1> INFO: 9686200 events read in total (281568ms).
[13:11:38.504] <TB1> INFO: 10754100 events read in total (313239ms).
[13:12:10.078] <TB1> INFO: 11823850 events read in total (344813ms).
[13:12:41.951] <TB1> INFO: 12891150 events read in total (376686ms).
[13:13:12.795] <TB1> INFO: 13947100 events read in total (407530ms).
[13:13:43.610] <TB1> INFO: 14999550 events read in total (438345ms).
[13:14:13.798] <TB1> INFO: 16052700 events read in total (468533ms).
[13:14:44.570] <TB1> INFO: 17102650 events read in total (499305ms).
[13:15:15.160] <TB1> INFO: 18149950 events read in total (529895ms).
[13:15:45.885] <TB1> INFO: 19198300 events read in total (560620ms).
[13:16:16.436] <TB1> INFO: 20245250 events read in total (591171ms).
[13:16:47.604] <TB1> INFO: 21290400 events read in total (622339ms).
[13:17:19.385] <TB1> INFO: 22335850 events read in total (654120ms).
[13:17:51.029] <TB1> INFO: 23383300 events read in total (685764ms).
[13:18:21.571] <TB1> INFO: 24431150 events read in total (716306ms).
[13:18:53.165] <TB1> INFO: 25480300 events read in total (747900ms).
[13:19:14.328] <TB1> INFO: 26208000 events read in total (769063ms).
[13:19:14.364] <TB1> INFO: Test took 770117ms.
[13:19:14.445] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:14.610] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:19:16.206] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:19:17.801] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:19:19.399] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:19:20.870] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:19:22.339] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:19:23.796] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:19:25.184] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:19:26.659] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:19:28.194] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:19:30.087] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:19:31.747] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:19:33.290] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:19:35.012] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:19:36.517] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:19:37.911] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:19:39.290] <TB1> INFO: PixTestScurves::scurves() done
[13:19:39.290] <TB1> INFO: Vcal mean: 98.52 95.03 108.51 97.90 88.49 101.27 92.83 89.99 82.76 78.25 91.25 90.13 78.79 81.47 85.49 107.14
[13:19:39.290] <TB1> INFO: Vcal RMS: 6.98 7.07 6.48 6.43 5.49 6.60 5.28 6.05 5.47 4.36 6.17 5.43 4.96 4.96 5.38 5.88
[13:19:39.290] <TB1> INFO: PixTestScurves::fullTest() done, duration: 795 seconds
[13:19:39.290] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:39.290] <TB1> INFO: Decoding statistics:
[13:19:39.290] <TB1> INFO: General information:
[13:19:39.290] <TB1> INFO: 16bit words read: 0
[13:19:39.290] <TB1> INFO: valid events total: 0
[13:19:39.290] <TB1> INFO: empty events: 0
[13:19:39.290] <TB1> INFO: valid events with pixels: 0
[13:19:39.290] <TB1> INFO: valid pixel hits: 0
[13:19:39.290] <TB1> INFO: Event errors: 0
[13:19:39.290] <TB1> INFO: start marker: 0
[13:19:39.290] <TB1> INFO: stop marker: 0
[13:19:39.290] <TB1> INFO: overflow: 0
[13:19:39.290] <TB1> INFO: invalid 5bit words: 0
[13:19:39.290] <TB1> INFO: invalid XOR eye diagram: 0
[13:19:39.290] <TB1> INFO: TBM errors: 0
[13:19:39.290] <TB1> INFO: flawed TBM headers: 0
[13:19:39.290] <TB1> INFO: flawed TBM trailers: 0
[13:19:39.290] <TB1> INFO: event ID mismatches: 0
[13:19:39.290] <TB1> INFO: ROC errors: 0
[13:19:39.290] <TB1> INFO: missing ROC header(s): 0
[13:19:39.290] <TB1> INFO: misplaced readback start: 0
[13:19:39.291] <TB1> INFO: Pixel decoding errors: 0
[13:19:39.291] <TB1> INFO: pixel data incomplete: 0
[13:19:39.291] <TB1> INFO: pixel address: 0
[13:19:39.291] <TB1> INFO: pulse height fill bit: 0
[13:19:39.291] <TB1> INFO: buffer corruption: 0
[13:19:39.372] <TB1> INFO: ######################################################################
[13:19:39.372] <TB1> INFO: PixTestTrim::doTest()
[13:19:39.372] <TB1> INFO: ######################################################################
[13:19:39.373] <TB1> INFO: ----------------------------------------------------------------------
[13:19:39.374] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[13:19:39.374] <TB1> INFO: ----------------------------------------------------------------------
[13:19:39.467] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[13:19:39.467] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:19:39.476] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:19:39.476] <TB1> INFO: run 1 of 1
[13:19:39.786] <TB1> INFO: Expecting 6281600 events.
[13:20:23.544] <TB1> INFO: 1423180 events read in total (43042ms).
[13:21:07.320] <TB1> INFO: 2830540 events read in total (86818ms).
[13:21:50.351] <TB1> INFO: 4239590 events read in total (129850ms).
[13:22:34.844] <TB1> INFO: 5658620 events read in total (174342ms).
[13:22:53.950] <TB1> INFO: 6281600 events read in total (193448ms).
[13:22:53.975] <TB1> INFO: Test took 194499ms.
[13:22:54.037] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:14.621] <TB1> INFO: ROC 0 VthrComp = 97
[13:23:14.621] <TB1> INFO: ROC 1 VthrComp = 95
[13:23:14.621] <TB1> INFO: ROC 2 VthrComp = 103
[13:23:14.621] <TB1> INFO: ROC 3 VthrComp = 96
[13:23:14.621] <TB1> INFO: ROC 4 VthrComp = 93
[13:23:14.621] <TB1> INFO: ROC 5 VthrComp = 95
[13:23:14.621] <TB1> INFO: ROC 6 VthrComp = 94
[13:23:14.621] <TB1> INFO: ROC 7 VthrComp = 97
[13:23:14.622] <TB1> INFO: ROC 8 VthrComp = 84
[13:23:14.622] <TB1> INFO: ROC 9 VthrComp = 79
[13:23:14.622] <TB1> INFO: ROC 10 VthrComp = 88
[13:23:14.622] <TB1> INFO: ROC 11 VthrComp = 92
[13:23:14.622] <TB1> INFO: ROC 12 VthrComp = 83
[13:23:14.622] <TB1> INFO: ROC 13 VthrComp = 86
[13:23:14.622] <TB1> INFO: ROC 14 VthrComp = 90
[13:23:14.622] <TB1> INFO: ROC 15 VthrComp = 104
[13:23:14.622] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[13:23:14.622] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:23:14.631] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:23:14.631] <TB1> INFO: run 1 of 1
[13:23:14.936] <TB1> INFO: Expecting 6281600 events.
[13:23:50.096] <TB1> INFO: 893910 events read in total (34445ms).
[13:24:26.193] <TB1> INFO: 1784170 events read in total (70542ms).
[13:25:02.793] <TB1> INFO: 2675820 events read in total (107142ms).
[13:25:39.271] <TB1> INFO: 3562850 events read in total (143620ms).
[13:26:15.728] <TB1> INFO: 4442350 events read in total (180077ms).
[13:26:52.187] <TB1> INFO: 5318750 events read in total (216536ms).
[13:27:28.376] <TB1> INFO: 6195030 events read in total (252725ms).
[13:27:32.616] <TB1> INFO: 6281600 events read in total (256965ms).
[13:27:32.676] <TB1> INFO: Test took 258045ms.
[13:27:32.827] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:27:58.967] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 62.9264 for pixel 0/19 mean/min/max = 47.1201/31.2138/63.0263
[13:27:58.967] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 62.5707 for pixel 0/72 mean/min/max = 47.1461/31.478/62.8141
[13:27:58.967] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 66.4737 for pixel 12/5 mean/min/max = 49.5837/32.591/66.5765
[13:27:58.968] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 63.948 for pixel 0/68 mean/min/max = 47.5177/30.8885/64.1468
[13:27:58.968] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 58.3869 for pixel 30/2 mean/min/max = 45.4751/32.3321/58.6181
[13:27:58.968] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 65.433 for pixel 0/7 mean/min/max = 48.5844/31.5111/65.6578
[13:27:58.968] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 58.9266 for pixel 10/1 mean/min/max = 45.5817/32.1341/59.0294
[13:27:58.969] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 60.145 for pixel 2/45 mean/min/max = 45.9778/31.5203/60.4353
[13:27:58.969] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 59.8949 for pixel 11/44 mean/min/max = 45.8449/31.5337/60.156
[13:27:58.969] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 60.01 for pixel 10/79 mean/min/max = 47.313/34.5677/60.0583
[13:27:58.970] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 63.2571 for pixel 0/69 mean/min/max = 47.8353/32.1467/63.5238
[13:27:58.970] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 59.185 for pixel 8/2 mean/min/max = 46.2239/33.2148/59.233
[13:27:58.970] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 58.4814 for pixel 22/1 mean/min/max = 45.3624/31.9212/58.8037
[13:27:58.970] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 57.1237 for pixel 23/5 mean/min/max = 44.7541/32.3274/57.1808
[13:27:58.971] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 59.0392 for pixel 0/44 mean/min/max = 45.8163/32.5336/59.0991
[13:27:58.971] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 65.1587 for pixel 5/8 mean/min/max = 49.6503/33.9658/65.3349
[13:27:58.971] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:27:59.102] <TB1> INFO: Expecting 514560 events.
[13:28:09.190] <TB1> INFO: 514560 events read in total (9374ms).
[13:28:09.195] <TB1> INFO: Expecting 514560 events.
[13:28:19.189] <TB1> INFO: 514560 events read in total (9322ms).
[13:28:19.196] <TB1> INFO: Expecting 514560 events.
[13:28:28.984] <TB1> INFO: 514560 events read in total (9113ms).
[13:28:28.993] <TB1> INFO: Expecting 514560 events.
[13:28:39.099] <TB1> INFO: 514560 events read in total (9436ms).
[13:28:39.111] <TB1> INFO: Expecting 514560 events.
[13:28:49.515] <TB1> INFO: 514560 events read in total (9736ms).
[13:28:49.528] <TB1> INFO: Expecting 514560 events.
[13:28:59.314] <TB1> INFO: 514560 events read in total (9125ms).
[13:28:59.327] <TB1> INFO: Expecting 514560 events.
[13:29:09.166] <TB1> INFO: 514560 events read in total (9169ms).
[13:29:09.181] <TB1> INFO: Expecting 514560 events.
[13:29:19.262] <TB1> INFO: 514560 events read in total (9418ms).
[13:29:19.279] <TB1> INFO: Expecting 514560 events.
[13:29:29.137] <TB1> INFO: 514560 events read in total (9201ms).
[13:29:29.156] <TB1> INFO: Expecting 514560 events.
[13:29:39.171] <TB1> INFO: 514560 events read in total (9356ms).
[13:29:39.191] <TB1> INFO: Expecting 514560 events.
[13:29:49.048] <TB1> INFO: 514560 events read in total (9196ms).
[13:29:49.082] <TB1> INFO: Expecting 514560 events.
[13:29:59.437] <TB1> INFO: 514560 events read in total (9723ms).
[13:29:59.462] <TB1> INFO: Expecting 514560 events.
[13:30:09.824] <TB1> INFO: 514560 events read in total (9714ms).
[13:30:09.852] <TB1> INFO: Expecting 514560 events.
[13:30:20.377] <TB1> INFO: 514560 events read in total (9880ms).
[13:30:20.407] <TB1> INFO: Expecting 514560 events.
[13:30:30.846] <TB1> INFO: 514560 events read in total (9804ms).
[13:30:30.876] <TB1> INFO: Expecting 514560 events.
[13:30:41.185] <TB1> INFO: 514560 events read in total (9659ms).
[13:30:41.220] <TB1> INFO: Test took 162249ms.
[13:30:42.254] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[13:30:42.261] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:30:42.262] <TB1> INFO: run 1 of 1
[13:30:42.573] <TB1> INFO: Expecting 6281600 events.
[13:31:20.121] <TB1> INFO: 873330 events read in total (36833ms).
[13:31:57.153] <TB1> INFO: 1743320 events read in total (73865ms).
[13:32:33.730] <TB1> INFO: 2613230 events read in total (110442ms).
[13:33:09.077] <TB1> INFO: 3479260 events read in total (145789ms).
[13:33:43.992] <TB1> INFO: 4337330 events read in total (180704ms).
[13:34:18.991] <TB1> INFO: 5193120 events read in total (215703ms).
[13:34:54.232] <TB1> INFO: 6047370 events read in total (250944ms).
[13:35:04.345] <TB1> INFO: 6281600 events read in total (261057ms).
[13:35:04.416] <TB1> INFO: Test took 262154ms.
[13:35:04.573] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:35:29.241] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.022136 .. 255.000000
[13:35:29.321] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[13:35:29.328] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:35:29.329] <TB1> INFO: run 1 of 1
[13:35:29.632] <TB1> INFO: Expecting 10649600 events.
[13:36:04.856] <TB1> INFO: 826740 events read in total (34509ms).
[13:36:40.287] <TB1> INFO: 1653390 events read in total (69940ms).
[13:37:15.134] <TB1> INFO: 2480120 events read in total (104787ms).
[13:37:49.840] <TB1> INFO: 3306860 events read in total (139493ms).
[13:38:24.269] <TB1> INFO: 4134180 events read in total (173922ms).
[13:38:58.850] <TB1> INFO: 4961110 events read in total (208503ms).
[13:39:33.897] <TB1> INFO: 5787930 events read in total (243550ms).
[13:40:09.146] <TB1> INFO: 6614590 events read in total (278799ms).
[13:40:44.545] <TB1> INFO: 7440600 events read in total (314199ms).
[13:41:18.957] <TB1> INFO: 8265700 events read in total (348610ms).
[13:41:53.832] <TB1> INFO: 9090060 events read in total (383485ms).
[13:42:28.620] <TB1> INFO: 9914600 events read in total (418273ms).
[13:42:59.458] <TB1> INFO: 10649600 events read in total (449111ms).
[13:42:59.556] <TB1> INFO: Test took 450227ms.
[13:42:59.838] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:30.895] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 14.271790 .. 84.853032
[13:43:30.984] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 4 .. 94 (-1/-1) hits flags = 528 (plus default)
[13:43:30.993] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:43:30.993] <TB1> INFO: run 1 of 1
[13:43:31.320] <TB1> INFO: Expecting 3785600 events.
[13:44:08.738] <TB1> INFO: 950700 events read in total (36703ms).
[13:44:46.063] <TB1> INFO: 1901550 events read in total (74028ms).
[13:45:22.187] <TB1> INFO: 2851150 events read in total (110152ms).
[13:45:58.920] <TB1> INFO: 3785600 events read in total (146885ms).
[13:45:58.969] <TB1> INFO: Test took 147976ms.
[13:45:59.061] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:19.609] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 15.500000 .. 74.184825
[13:46:19.703] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 5 .. 84 (-1/-1) hits flags = 528 (plus default)
[13:46:19.712] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:46:19.712] <TB1> INFO: run 1 of 1
[13:46:20.044] <TB1> INFO: Expecting 3328000 events.
[13:46:58.677] <TB1> INFO: 975990 events read in total (37918ms).
[13:47:36.479] <TB1> INFO: 1951860 events read in total (75720ms).
[13:48:13.668] <TB1> INFO: 2926770 events read in total (112909ms).
[13:48:29.387] <TB1> INFO: 3328000 events read in total (128628ms).
[13:48:29.424] <TB1> INFO: Test took 129712ms.
[13:48:29.508] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:48.219] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 1.369426 .. 73.723319
[13:48:48.323] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 1 .. 83 (-1/-1) hits flags = 528 (plus default)
[13:48:48.332] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:48:48.332] <TB1> INFO: run 1 of 1
[13:48:48.659] <TB1> INFO: Expecting 3452800 events.
[13:49:26.690] <TB1> INFO: 1001410 events read in total (37316ms).
[13:50:04.897] <TB1> INFO: 2002770 events read in total (75523ms).
[13:50:41.984] <TB1> INFO: 3004240 events read in total (112610ms).
[13:50:58.520] <TB1> INFO: 3452800 events read in total (129146ms).
[13:50:58.544] <TB1> INFO: Test took 130212ms.
[13:50:58.604] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:15.244] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[13:51:15.244] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:51:15.252] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:51:15.252] <TB1> INFO: run 1 of 1
[13:51:15.568] <TB1> INFO: Expecting 1705600 events.
[13:51:55.688] <TB1> INFO: 1075790 events read in total (39405ms).
[13:52:18.240] <TB1> INFO: 1705600 events read in total (61957ms).
[13:52:18.251] <TB1> INFO: Test took 62999ms.
[13:52:18.279] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:32.369] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C0.dat
[13:52:32.369] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C1.dat
[13:52:32.369] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C2.dat
[13:52:32.369] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C3.dat
[13:52:32.369] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C4.dat
[13:52:32.369] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C5.dat
[13:52:32.370] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C6.dat
[13:52:32.370] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C7.dat
[13:52:32.370] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C8.dat
[13:52:32.370] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C9.dat
[13:52:32.370] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C10.dat
[13:52:32.370] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C11.dat
[13:52:32.370] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C12.dat
[13:52:32.370] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C13.dat
[13:52:32.370] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C14.dat
[13:52:32.371] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C15.dat
[13:52:32.371] <TB1> INFO: write trim parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//trimParameters35_C0.dat
[13:52:32.376] <TB1> INFO: write trim parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//trimParameters35_C1.dat
[13:52:32.382] <TB1> INFO: write trim parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//trimParameters35_C2.dat
[13:52:32.387] <TB1> INFO: write trim parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//trimParameters35_C3.dat
[13:52:32.393] <TB1> INFO: write trim parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//trimParameters35_C4.dat
[13:52:32.398] <TB1> INFO: write trim parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//trimParameters35_C5.dat
[13:52:32.404] <TB1> INFO: write trim parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//trimParameters35_C6.dat
[13:52:32.409] <TB1> INFO: write trim parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//trimParameters35_C7.dat
[13:52:32.415] <TB1> INFO: write trim parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//trimParameters35_C8.dat
[13:52:32.420] <TB1> INFO: write trim parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//trimParameters35_C9.dat
[13:52:32.426] <TB1> INFO: write trim parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//trimParameters35_C10.dat
[13:52:32.431] <TB1> INFO: write trim parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//trimParameters35_C11.dat
[13:52:32.437] <TB1> INFO: write trim parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//trimParameters35_C12.dat
[13:52:32.446] <TB1> INFO: write trim parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//trimParameters35_C13.dat
[13:52:32.454] <TB1> INFO: write trim parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//trimParameters35_C14.dat
[13:52:32.460] <TB1> INFO: write trim parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//trimParameters35_C15.dat
[13:52:32.465] <TB1> INFO: PixTestTrim::trimTest() done
[13:52:32.465] <TB1> INFO: vtrim: 113 110 107 111 97 109 101 121 108 95 104 98 104 98 90 142
[13:52:32.465] <TB1> INFO: vthrcomp: 97 95 103 96 93 95 94 97 84 79 88 92 83 86 90 104
[13:52:32.465] <TB1> INFO: vcal mean: 34.98 34.96 34.98 34.99 34.97 34.94 34.98 34.97 34.98 34.98 34.99 35.02 34.94 34.92 35.01 34.96
[13:52:32.465] <TB1> INFO: vcal RMS: 1.40 1.54 1.10 1.15 0.86 1.04 0.87 0.87 1.03 0.82 0.88 0.83 1.12 1.38 1.00 1.20
[13:52:32.465] <TB1> INFO: bits mean: 9.09 9.46 8.82 9.06 9.20 8.69 9.72 9.55 9.39 8.50 8.77 9.22 9.55 9.48 9.04 8.68
[13:52:32.465] <TB1> INFO: bits RMS: 2.81 2.67 2.57 2.84 2.79 2.88 2.57 2.72 2.74 2.60 2.81 2.59 2.67 2.72 2.79 2.54
[13:52:32.472] <TB1> INFO: ----------------------------------------------------------------------
[13:52:32.472] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[13:52:32.472] <TB1> INFO: ----------------------------------------------------------------------
[13:52:32.474] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[13:52:32.483] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:52:32.483] <TB1> INFO: run 1 of 1
[13:52:32.797] <TB1> INFO: Expecting 8320000 events.
[13:53:12.055] <TB1> INFO: 1145000 events read in total (38543ms).
[13:53:50.747] <TB1> INFO: 2278280 events read in total (77235ms).
[13:54:30.249] <TB1> INFO: 3409850 events read in total (116738ms).
[13:55:09.976] <TB1> INFO: 4535250 events read in total (156464ms).
[13:55:49.383] <TB1> INFO: 5651550 events read in total (195871ms).
[13:56:28.470] <TB1> INFO: 6765600 events read in total (234958ms).
[13:57:08.032] <TB1> INFO: 7881560 events read in total (274520ms).
[13:57:22.859] <TB1> INFO: 8320000 events read in total (289347ms).
[13:57:22.911] <TB1> INFO: Test took 290428ms.
[13:57:23.013] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:49.210] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 184 (-1/-1) hits flags = 528 (plus default)
[13:57:49.218] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:57:49.218] <TB1> INFO: run 1 of 1
[13:57:49.526] <TB1> INFO: Expecting 7696000 events.
[13:58:29.382] <TB1> INFO: 1139360 events read in total (39141ms).
[13:59:07.596] <TB1> INFO: 2269350 events read in total (77355ms).
[13:59:46.231] <TB1> INFO: 3396380 events read in total (115990ms).
[14:00:25.892] <TB1> INFO: 4513910 events read in total (155651ms).
[14:01:05.090] <TB1> INFO: 5624240 events read in total (194849ms).
[14:01:43.945] <TB1> INFO: 6733330 events read in total (233704ms).
[14:02:17.799] <TB1> INFO: 7696000 events read in total (267558ms).
[14:02:17.847] <TB1> INFO: Test took 268628ms.
[14:02:17.942] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:02:44.953] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 170 (-1/-1) hits flags = 528 (plus default)
[14:02:44.961] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[14:02:44.961] <TB1> INFO: run 1 of 1
[14:02:45.265] <TB1> INFO: Expecting 7113600 events.
[14:03:24.639] <TB1> INFO: 1186670 events read in total (38656ms).
[14:04:04.074] <TB1> INFO: 2363120 events read in total (78091ms).
[14:04:43.678] <TB1> INFO: 3535310 events read in total (117696ms).
[14:05:22.363] <TB1> INFO: 4691540 events read in total (156380ms).
[14:06:01.404] <TB1> INFO: 5843440 events read in total (195421ms).
[14:06:40.384] <TB1> INFO: 6998970 events read in total (234401ms).
[14:06:44.482] <TB1> INFO: 7113600 events read in total (238499ms).
[14:06:44.523] <TB1> INFO: Test took 239562ms.
[14:06:44.607] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:07:10.625] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 169 (-1/-1) hits flags = 528 (plus default)
[14:07:10.633] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[14:07:10.634] <TB1> INFO: run 1 of 1
[14:07:10.941] <TB1> INFO: Expecting 7072000 events.
[14:07:52.413] <TB1> INFO: 1189790 events read in total (40757ms).
[14:08:31.498] <TB1> INFO: 2369200 events read in total (79842ms).
[14:09:10.759] <TB1> INFO: 3544260 events read in total (119103ms).
[14:09:50.712] <TB1> INFO: 4702210 events read in total (159056ms).
[14:10:30.815] <TB1> INFO: 5857900 events read in total (199159ms).
[14:11:09.886] <TB1> INFO: 7018700 events read in total (238230ms).
[14:11:12.131] <TB1> INFO: 7072000 events read in total (240475ms).
[14:11:12.171] <TB1> INFO: Test took 241537ms.
[14:11:12.254] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:11:37.301] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 169 (-1/-1) hits flags = 528 (plus default)
[14:11:37.309] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[14:11:37.309] <TB1> INFO: run 1 of 1
[14:11:37.614] <TB1> INFO: Expecting 7072000 events.
[14:12:18.998] <TB1> INFO: 1189330 events read in total (40669ms).
[14:12:57.718] <TB1> INFO: 2367750 events read in total (79389ms).
[14:13:37.183] <TB1> INFO: 3542180 events read in total (118854ms).
[14:14:15.529] <TB1> INFO: 4699500 events read in total (157200ms).
[14:14:55.193] <TB1> INFO: 5854040 events read in total (196864ms).
[14:15:35.833] <TB1> INFO: 7013590 events read in total (237504ms).
[14:15:38.088] <TB1> INFO: 7072000 events read in total (239759ms).
[14:15:38.135] <TB1> INFO: Test took 240827ms.
[14:15:38.222] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:02.251] <TB1> INFO: PixTestTrim::trimBitTest() done
[14:16:02.253] <TB1> INFO: PixTestTrim::doTest() done, duration: 3382 seconds
[14:16:02.253] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:02.253] <TB1> INFO: Decoding statistics:
[14:16:02.253] <TB1> INFO: General information:
[14:16:02.253] <TB1> INFO: 16bit words read: 0
[14:16:02.253] <TB1> INFO: valid events total: 0
[14:16:02.253] <TB1> INFO: empty events: 0
[14:16:02.253] <TB1> INFO: valid events with pixels: 0
[14:16:02.253] <TB1> INFO: valid pixel hits: 0
[14:16:02.253] <TB1> INFO: Event errors: 0
[14:16:02.253] <TB1> INFO: start marker: 0
[14:16:02.253] <TB1> INFO: stop marker: 0
[14:16:02.253] <TB1> INFO: overflow: 0
[14:16:02.253] <TB1> INFO: invalid 5bit words: 0
[14:16:02.253] <TB1> INFO: invalid XOR eye diagram: 0
[14:16:02.253] <TB1> INFO: TBM errors: 0
[14:16:02.253] <TB1> INFO: flawed TBM headers: 0
[14:16:02.253] <TB1> INFO: flawed TBM trailers: 0
[14:16:02.253] <TB1> INFO: event ID mismatches: 0
[14:16:02.253] <TB1> INFO: ROC errors: 0
[14:16:02.253] <TB1> INFO: missing ROC header(s): 0
[14:16:02.253] <TB1> INFO: misplaced readback start: 0
[14:16:02.253] <TB1> INFO: Pixel decoding errors: 0
[14:16:02.253] <TB1> INFO: pixel data incomplete: 0
[14:16:02.253] <TB1> INFO: pixel address: 0
[14:16:02.253] <TB1> INFO: pulse height fill bit: 0
[14:16:02.253] <TB1> INFO: buffer corruption: 0
[14:16:02.994] <TB1> INFO: ######################################################################
[14:16:02.994] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[14:16:02.994] <TB1> INFO: ######################################################################
[14:16:03.313] <TB1> INFO: Expecting 41600 events.
[14:16:07.449] <TB1> INFO: 41600 events read in total (3422ms).
[14:16:07.450] <TB1> INFO: Test took 4454ms.
[14:16:07.456] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:08.123] <TB1> INFO: Expecting 41600 events.
[14:16:12.420] <TB1> INFO: 41600 events read in total (3582ms).
[14:16:12.421] <TB1> INFO: Test took 4607ms.
[14:16:12.427] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:12.875] <TB1> INFO: Expecting 41600 events.
[14:16:17.041] <TB1> INFO: 41600 events read in total (3451ms).
[14:16:17.041] <TB1> INFO: Test took 4489ms.
[14:16:17.048] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:17.493] <TB1> INFO: Expecting 2560 events.
[14:16:18.449] <TB1> INFO: 2560 events read in total (241ms).
[14:16:18.449] <TB1> INFO: Test took 1392ms.
[14:16:18.957] <TB1> INFO: Expecting 2560 events.
[14:16:19.913] <TB1> INFO: 2560 events read in total (241ms).
[14:16:19.913] <TB1> INFO: Test took 1463ms.
[14:16:20.421] <TB1> INFO: Expecting 2560 events.
[14:16:21.382] <TB1> INFO: 2560 events read in total (246ms).
[14:16:21.382] <TB1> INFO: Test took 1468ms.
[14:16:21.890] <TB1> INFO: Expecting 2560 events.
[14:16:22.846] <TB1> INFO: 2560 events read in total (241ms).
[14:16:22.846] <TB1> INFO: Test took 1463ms.
[14:16:23.355] <TB1> INFO: Expecting 2560 events.
[14:16:24.316] <TB1> INFO: 2560 events read in total (246ms).
[14:16:24.316] <TB1> INFO: Test took 1469ms.
[14:16:24.824] <TB1> INFO: Expecting 2560 events.
[14:16:25.780] <TB1> INFO: 2560 events read in total (241ms).
[14:16:25.780] <TB1> INFO: Test took 1464ms.
[14:16:26.288] <TB1> INFO: Expecting 2560 events.
[14:16:27.244] <TB1> INFO: 2560 events read in total (241ms).
[14:16:27.245] <TB1> INFO: Test took 1464ms.
[14:16:27.752] <TB1> INFO: Expecting 2560 events.
[14:16:28.710] <TB1> INFO: 2560 events read in total (243ms).
[14:16:28.711] <TB1> INFO: Test took 1466ms.
[14:16:29.218] <TB1> INFO: Expecting 2560 events.
[14:16:30.193] <TB1> INFO: 2560 events read in total (260ms).
[14:16:30.194] <TB1> INFO: Test took 1483ms.
[14:16:30.701] <TB1> INFO: Expecting 2560 events.
[14:16:31.674] <TB1> INFO: 2560 events read in total (258ms).
[14:16:31.674] <TB1> INFO: Test took 1480ms.
[14:16:32.182] <TB1> INFO: Expecting 2560 events.
[14:16:33.142] <TB1> INFO: 2560 events read in total (245ms).
[14:16:33.142] <TB1> INFO: Test took 1467ms.
[14:16:33.649] <TB1> INFO: Expecting 2560 events.
[14:16:34.607] <TB1> INFO: 2560 events read in total (243ms).
[14:16:34.607] <TB1> INFO: Test took 1465ms.
[14:16:35.115] <TB1> INFO: Expecting 2560 events.
[14:16:36.076] <TB1> INFO: 2560 events read in total (246ms).
[14:16:36.076] <TB1> INFO: Test took 1468ms.
[14:16:36.584] <TB1> INFO: Expecting 2560 events.
[14:16:37.543] <TB1> INFO: 2560 events read in total (244ms).
[14:16:37.544] <TB1> INFO: Test took 1467ms.
[14:16:38.051] <TB1> INFO: Expecting 2560 events.
[14:16:39.007] <TB1> INFO: 2560 events read in total (241ms).
[14:16:39.007] <TB1> INFO: Test took 1463ms.
[14:16:39.515] <TB1> INFO: Expecting 2560 events.
[14:16:40.475] <TB1> INFO: 2560 events read in total (245ms).
[14:16:40.475] <TB1> INFO: Test took 1468ms.
[14:16:40.478] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:16:40.985] <TB1> INFO: Expecting 655360 events.
[14:16:54.391] <TB1> INFO: 655360 events read in total (12692ms).
[14:16:54.400] <TB1> INFO: Expecting 655360 events.
[14:17:07.232] <TB1> INFO: 655360 events read in total (12229ms).
[14:17:07.245] <TB1> INFO: Expecting 655360 events.
[14:17:20.248] <TB1> INFO: 655360 events read in total (12409ms).
[14:17:20.264] <TB1> INFO: Expecting 655360 events.
[14:17:33.388] <TB1> INFO: 655360 events read in total (12536ms).
[14:17:33.409] <TB1> INFO: Expecting 655360 events.
[14:17:46.297] <TB1> INFO: 655360 events read in total (12323ms).
[14:17:46.319] <TB1> INFO: Expecting 655360 events.
[14:17:59.716] <TB1> INFO: 655360 events read in total (12806ms).
[14:17:59.744] <TB1> INFO: Expecting 655360 events.
[14:18:12.962] <TB1> INFO: 655360 events read in total (12645ms).
[14:18:12.991] <TB1> INFO: Expecting 655360 events.
[14:18:26.240] <TB1> INFO: 655360 events read in total (12664ms).
[14:18:26.272] <TB1> INFO: Expecting 655360 events.
[14:18:39.787] <TB1> INFO: 655360 events read in total (12934ms).
[14:18:39.823] <TB1> INFO: Expecting 655360 events.
[14:18:53.241] <TB1> INFO: 655360 events read in total (12851ms).
[14:18:53.289] <TB1> INFO: Expecting 655360 events.
[14:19:06.585] <TB1> INFO: 655360 events read in total (12740ms).
[14:19:06.634] <TB1> INFO: Expecting 655360 events.
[14:19:20.004] <TB1> INFO: 655360 events read in total (12841ms).
[14:19:20.051] <TB1> INFO: Expecting 655360 events.
[14:19:33.177] <TB1> INFO: 655360 events read in total (12566ms).
[14:19:33.227] <TB1> INFO: Expecting 655360 events.
[14:19:46.282] <TB1> INFO: 655360 events read in total (12500ms).
[14:19:46.334] <TB1> INFO: Expecting 655360 events.
[14:19:59.360] <TB1> INFO: 655360 events read in total (12470ms).
[14:19:59.419] <TB1> INFO: Expecting 655360 events.
[14:20:12.573] <TB1> INFO: 655360 events read in total (12608ms).
[14:20:12.633] <TB1> INFO: Test took 212155ms.
[14:20:12.708] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:20:13.019] <TB1> INFO: Expecting 655360 events.
[14:20:26.866] <TB1> INFO: 655360 events read in total (13132ms).
[14:20:26.876] <TB1> INFO: Expecting 655360 events.
[14:20:40.293] <TB1> INFO: 655360 events read in total (12817ms).
[14:20:40.306] <TB1> INFO: Expecting 655360 events.
[14:20:53.667] <TB1> INFO: 655360 events read in total (12763ms).
[14:20:53.682] <TB1> INFO: Expecting 655360 events.
[14:21:07.227] <TB1> INFO: 655360 events read in total (12944ms).
[14:21:07.247] <TB1> INFO: Expecting 655360 events.
[14:21:20.704] <TB1> INFO: 655360 events read in total (12874ms).
[14:21:20.727] <TB1> INFO: Expecting 655360 events.
[14:21:33.844] <TB1> INFO: 655360 events read in total (12537ms).
[14:21:33.871] <TB1> INFO: Expecting 655360 events.
[14:21:47.141] <TB1> INFO: 655360 events read in total (12683ms).
[14:21:47.171] <TB1> INFO: Expecting 655360 events.
[14:22:00.524] <TB1> INFO: 655360 events read in total (12766ms).
[14:22:00.557] <TB1> INFO: Expecting 655360 events.
[14:22:13.385] <TB1> INFO: 655360 events read in total (12250ms).
[14:22:13.423] <TB1> INFO: Expecting 655360 events.
[14:22:26.743] <TB1> INFO: 655360 events read in total (12750ms).
[14:22:26.786] <TB1> INFO: Expecting 655360 events.
[14:22:40.091] <TB1> INFO: 655360 events read in total (12740ms).
[14:22:40.133] <TB1> INFO: Expecting 655360 events.
[14:22:53.330] <TB1> INFO: 655360 events read in total (12627ms).
[14:22:53.376] <TB1> INFO: Expecting 655360 events.
[14:23:06.367] <TB1> INFO: 655360 events read in total (12420ms).
[14:23:06.420] <TB1> INFO: Expecting 655360 events.
[14:23:20.033] <TB1> INFO: 655360 events read in total (13062ms).
[14:23:20.088] <TB1> INFO: Expecting 655360 events.
[14:23:33.663] <TB1> INFO: 655360 events read in total (13019ms).
[14:23:33.726] <TB1> INFO: Expecting 655360 events.
[14:23:46.910] <TB1> INFO: 655360 events read in total (12636ms).
[14:23:46.971] <TB1> INFO: Test took 214263ms.
[14:23:47.163] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:23:47.171] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:23:47.179] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:23:47.186] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:23:47.194] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:23:47.202] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:23:47.210] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:23:47.217] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:23:47.224] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:23:47.231] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:23:47.239] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:23:47.246] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:23:47.253] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:23:47.260] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:23:47.267] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:23:47.274] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[14:23:47.281] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[14:23:47.287] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[14:23:47.294] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:23:47.322] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C0.dat
[14:23:47.322] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C1.dat
[14:23:47.322] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C2.dat
[14:23:47.322] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C3.dat
[14:23:47.322] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C4.dat
[14:23:47.323] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C5.dat
[14:23:47.323] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C6.dat
[14:23:47.323] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C7.dat
[14:23:47.323] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C8.dat
[14:23:47.323] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C9.dat
[14:23:47.323] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C10.dat
[14:23:47.323] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C11.dat
[14:23:47.323] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C12.dat
[14:23:47.323] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C13.dat
[14:23:47.323] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C14.dat
[14:23:47.323] <TB1> INFO: write dac parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//dacParameters35_C15.dat
[14:23:47.631] <TB1> INFO: Expecting 41600 events.
[14:23:51.609] <TB1> INFO: 41600 events read in total (3263ms).
[14:23:51.610] <TB1> INFO: Test took 4284ms.
[14:23:52.267] <TB1> INFO: Expecting 41600 events.
[14:23:56.225] <TB1> INFO: 41600 events read in total (3243ms).
[14:23:56.226] <TB1> INFO: Test took 4290ms.
[14:23:56.864] <TB1> INFO: Expecting 41600 events.
[14:24:00.945] <TB1> INFO: 41600 events read in total (3366ms).
[14:24:00.946] <TB1> INFO: Test took 4391ms.
[14:24:01.269] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:01.401] <TB1> INFO: Expecting 2560 events.
[14:24:02.358] <TB1> INFO: 2560 events read in total (242ms).
[14:24:02.359] <TB1> INFO: Test took 1090ms.
[14:24:02.360] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:02.868] <TB1> INFO: Expecting 2560 events.
[14:24:03.845] <TB1> INFO: 2560 events read in total (262ms).
[14:24:03.845] <TB1> INFO: Test took 1485ms.
[14:24:03.847] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:04.354] <TB1> INFO: Expecting 2560 events.
[14:24:05.315] <TB1> INFO: 2560 events read in total (247ms).
[14:24:05.316] <TB1> INFO: Test took 1469ms.
[14:24:05.318] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:05.825] <TB1> INFO: Expecting 2560 events.
[14:24:06.787] <TB1> INFO: 2560 events read in total (247ms).
[14:24:06.787] <TB1> INFO: Test took 1469ms.
[14:24:06.790] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:07.296] <TB1> INFO: Expecting 2560 events.
[14:24:08.287] <TB1> INFO: 2560 events read in total (276ms).
[14:24:08.288] <TB1> INFO: Test took 1498ms.
[14:24:08.290] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:08.797] <TB1> INFO: Expecting 2560 events.
[14:24:09.758] <TB1> INFO: 2560 events read in total (246ms).
[14:24:09.758] <TB1> INFO: Test took 1468ms.
[14:24:09.761] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:10.267] <TB1> INFO: Expecting 2560 events.
[14:24:11.239] <TB1> INFO: 2560 events read in total (256ms).
[14:24:11.240] <TB1> INFO: Test took 1479ms.
[14:24:11.241] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:11.750] <TB1> INFO: Expecting 2560 events.
[14:24:12.712] <TB1> INFO: 2560 events read in total (247ms).
[14:24:12.713] <TB1> INFO: Test took 1472ms.
[14:24:12.715] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:13.221] <TB1> INFO: Expecting 2560 events.
[14:24:14.195] <TB1> INFO: 2560 events read in total (260ms).
[14:24:14.195] <TB1> INFO: Test took 1480ms.
[14:24:14.197] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:14.704] <TB1> INFO: Expecting 2560 events.
[14:24:15.680] <TB1> INFO: 2560 events read in total (261ms).
[14:24:15.680] <TB1> INFO: Test took 1483ms.
[14:24:15.682] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:16.190] <TB1> INFO: Expecting 2560 events.
[14:24:17.176] <TB1> INFO: 2560 events read in total (271ms).
[14:24:17.176] <TB1> INFO: Test took 1494ms.
[14:24:17.178] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:17.685] <TB1> INFO: Expecting 2560 events.
[14:24:18.642] <TB1> INFO: 2560 events read in total (242ms).
[14:24:18.642] <TB1> INFO: Test took 1464ms.
[14:24:18.644] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:19.151] <TB1> INFO: Expecting 2560 events.
[14:24:20.112] <TB1> INFO: 2560 events read in total (246ms).
[14:24:20.113] <TB1> INFO: Test took 1469ms.
[14:24:20.114] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:20.622] <TB1> INFO: Expecting 2560 events.
[14:24:21.584] <TB1> INFO: 2560 events read in total (247ms).
[14:24:21.584] <TB1> INFO: Test took 1470ms.
[14:24:21.587] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:22.094] <TB1> INFO: Expecting 2560 events.
[14:24:23.055] <TB1> INFO: 2560 events read in total (246ms).
[14:24:23.055] <TB1> INFO: Test took 1468ms.
[14:24:23.057] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:23.564] <TB1> INFO: Expecting 2560 events.
[14:24:24.525] <TB1> INFO: 2560 events read in total (246ms).
[14:24:24.525] <TB1> INFO: Test took 1468ms.
[14:24:24.527] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:25.034] <TB1> INFO: Expecting 2560 events.
[14:24:25.996] <TB1> INFO: 2560 events read in total (247ms).
[14:24:25.996] <TB1> INFO: Test took 1469ms.
[14:24:25.998] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:26.505] <TB1> INFO: Expecting 2560 events.
[14:24:27.462] <TB1> INFO: 2560 events read in total (242ms).
[14:24:27.462] <TB1> INFO: Test took 1464ms.
[14:24:27.464] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:27.972] <TB1> INFO: Expecting 2560 events.
[14:24:28.935] <TB1> INFO: 2560 events read in total (248ms).
[14:24:28.936] <TB1> INFO: Test took 1472ms.
[14:24:28.938] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:29.445] <TB1> INFO: Expecting 2560 events.
[14:24:30.407] <TB1> INFO: 2560 events read in total (247ms).
[14:24:30.407] <TB1> INFO: Test took 1469ms.
[14:24:30.410] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:30.916] <TB1> INFO: Expecting 2560 events.
[14:24:31.892] <TB1> INFO: 2560 events read in total (261ms).
[14:24:31.893] <TB1> INFO: Test took 1484ms.
[14:24:31.901] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:32.402] <TB1> INFO: Expecting 2560 events.
[14:24:33.364] <TB1> INFO: 2560 events read in total (248ms).
[14:24:33.365] <TB1> INFO: Test took 1464ms.
[14:24:33.367] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:33.873] <TB1> INFO: Expecting 2560 events.
[14:24:34.837] <TB1> INFO: 2560 events read in total (249ms).
[14:24:34.837] <TB1> INFO: Test took 1470ms.
[14:24:34.839] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:35.346] <TB1> INFO: Expecting 2560 events.
[14:24:36.322] <TB1> INFO: 2560 events read in total (261ms).
[14:24:36.323] <TB1> INFO: Test took 1484ms.
[14:24:36.325] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:36.833] <TB1> INFO: Expecting 2560 events.
[14:24:37.791] <TB1> INFO: 2560 events read in total (243ms).
[14:24:37.791] <TB1> INFO: Test took 1466ms.
[14:24:37.794] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:38.300] <TB1> INFO: Expecting 2560 events.
[14:24:39.257] <TB1> INFO: 2560 events read in total (242ms).
[14:24:39.257] <TB1> INFO: Test took 1463ms.
[14:24:39.260] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:39.767] <TB1> INFO: Expecting 2560 events.
[14:24:40.731] <TB1> INFO: 2560 events read in total (249ms).
[14:24:40.731] <TB1> INFO: Test took 1472ms.
[14:24:40.734] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:41.239] <TB1> INFO: Expecting 2560 events.
[14:24:42.202] <TB1> INFO: 2560 events read in total (248ms).
[14:24:42.202] <TB1> INFO: Test took 1468ms.
[14:24:42.205] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:42.710] <TB1> INFO: Expecting 2560 events.
[14:24:43.673] <TB1> INFO: 2560 events read in total (248ms).
[14:24:43.673] <TB1> INFO: Test took 1469ms.
[14:24:43.676] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:44.182] <TB1> INFO: Expecting 2560 events.
[14:24:45.144] <TB1> INFO: 2560 events read in total (248ms).
[14:24:45.144] <TB1> INFO: Test took 1469ms.
[14:24:45.147] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:45.652] <TB1> INFO: Expecting 2560 events.
[14:24:46.614] <TB1> INFO: 2560 events read in total (247ms).
[14:24:46.614] <TB1> INFO: Test took 1468ms.
[14:24:46.616] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:24:47.122] <TB1> INFO: Expecting 2560 events.
[14:24:48.094] <TB1> INFO: 2560 events read in total (257ms).
[14:24:48.094] <TB1> INFO: Test took 1478ms.
[14:24:48.804] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 525 seconds
[14:24:48.804] <TB1> INFO: PH scale (per ROC): 74 69 73 79 89 78 84 81 80 71 84 89 86 84 80 79
[14:24:48.804] <TB1> INFO: PH offset (per ROC): 163 160 173 160 165 167 151 158 162 169 159 147 157 162 169 170
[14:24:48.808] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:24:48.808] <TB1> INFO: Decoding statistics:
[14:24:48.808] <TB1> INFO: General information:
[14:24:48.808] <TB1> INFO: 16bit words read: 66438
[14:24:48.808] <TB1> INFO: valid events total: 5120
[14:24:48.808] <TB1> INFO: empty events: 2621
[14:24:48.808] <TB1> INFO: valid events with pixels: 2499
[14:24:48.808] <TB1> INFO: valid pixel hits: 2499
[14:24:48.808] <TB1> INFO: Event errors: 0
[14:24:48.808] <TB1> INFO: start marker: 0
[14:24:48.808] <TB1> INFO: stop marker: 0
[14:24:48.808] <TB1> INFO: overflow: 0
[14:24:48.808] <TB1> INFO: invalid 5bit words: 0
[14:24:48.808] <TB1> INFO: invalid XOR eye diagram: 0
[14:24:48.808] <TB1> INFO: TBM errors: 0
[14:24:48.808] <TB1> INFO: flawed TBM headers: 0
[14:24:48.808] <TB1> INFO: flawed TBM trailers: 0
[14:24:48.808] <TB1> INFO: event ID mismatches: 0
[14:24:48.808] <TB1> INFO: ROC errors: 0
[14:24:48.808] <TB1> INFO: missing ROC header(s): 0
[14:24:48.808] <TB1> INFO: misplaced readback start: 0
[14:24:48.808] <TB1> INFO: Pixel decoding errors: 0
[14:24:48.808] <TB1> INFO: pixel data incomplete: 0
[14:24:48.808] <TB1> INFO: pixel address: 0
[14:24:48.808] <TB1> INFO: pulse height fill bit: 0
[14:24:48.808] <TB1> INFO: buffer corruption: 0
[14:24:48.979] <TB1> INFO: ######################################################################
[14:24:48.979] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[14:24:48.979] <TB1> INFO: ######################################################################
[14:24:48.988] <TB1> INFO: scanning low vcal = 10
[14:24:49.293] <TB1> INFO: Expecting 41600 events.
[14:24:53.147] <TB1> INFO: 41600 events read in total (3139ms).
[14:24:53.147] <TB1> INFO: Test took 4159ms.
[14:24:53.148] <TB1> INFO: scanning low vcal = 20
[14:24:53.655] <TB1> INFO: Expecting 41600 events.
[14:24:57.477] <TB1> INFO: 41600 events read in total (3107ms).
[14:24:57.477] <TB1> INFO: Test took 4329ms.
[14:24:57.479] <TB1> INFO: scanning low vcal = 30
[14:24:57.984] <TB1> INFO: Expecting 41600 events.
[14:25:01.837] <TB1> INFO: 41600 events read in total (3128ms).
[14:25:01.837] <TB1> INFO: Test took 4358ms.
[14:25:01.839] <TB1> INFO: scanning low vcal = 40
[14:25:02.331] <TB1> INFO: Expecting 41600 events.
[14:25:06.720] <TB1> INFO: 41600 events read in total (3674ms).
[14:25:06.721] <TB1> INFO: Test took 4882ms.
[14:25:06.723] <TB1> INFO: scanning low vcal = 50
[14:25:07.166] <TB1> INFO: Expecting 41600 events.
[14:25:11.602] <TB1> INFO: 41600 events read in total (3721ms).
[14:25:11.602] <TB1> INFO: Test took 4879ms.
[14:25:11.605] <TB1> INFO: scanning low vcal = 60
[14:25:12.050] <TB1> INFO: Expecting 41600 events.
[14:25:16.534] <TB1> INFO: 41600 events read in total (3769ms).
[14:25:16.535] <TB1> INFO: Test took 4930ms.
[14:25:16.537] <TB1> INFO: scanning low vcal = 70
[14:25:16.983] <TB1> INFO: Expecting 41600 events.
[14:25:21.465] <TB1> INFO: 41600 events read in total (3767ms).
[14:25:21.465] <TB1> INFO: Test took 4928ms.
[14:25:21.468] <TB1> INFO: scanning low vcal = 80
[14:25:21.914] <TB1> INFO: Expecting 41600 events.
[14:25:26.534] <TB1> INFO: 41600 events read in total (3905ms).
[14:25:26.534] <TB1> INFO: Test took 5066ms.
[14:25:26.537] <TB1> INFO: scanning low vcal = 90
[14:25:26.968] <TB1> INFO: Expecting 41600 events.
[14:25:31.486] <TB1> INFO: 41600 events read in total (3803ms).
[14:25:31.487] <TB1> INFO: Test took 4950ms.
[14:25:31.490] <TB1> INFO: scanning low vcal = 100
[14:25:31.930] <TB1> INFO: Expecting 41600 events.
[14:25:36.564] <TB1> INFO: 41600 events read in total (3919ms).
[14:25:36.564] <TB1> INFO: Test took 5074ms.
[14:25:36.567] <TB1> INFO: scanning low vcal = 110
[14:25:37.015] <TB1> INFO: Expecting 41600 events.
[14:25:41.378] <TB1> INFO: 41600 events read in total (3648ms).
[14:25:41.379] <TB1> INFO: Test took 4812ms.
[14:25:41.381] <TB1> INFO: scanning low vcal = 120
[14:25:41.820] <TB1> INFO: Expecting 41600 events.
[14:25:46.304] <TB1> INFO: 41600 events read in total (3769ms).
[14:25:46.305] <TB1> INFO: Test took 4924ms.
[14:25:46.307] <TB1> INFO: scanning low vcal = 130
[14:25:46.746] <TB1> INFO: Expecting 41600 events.
[14:25:51.231] <TB1> INFO: 41600 events read in total (3770ms).
[14:25:51.231] <TB1> INFO: Test took 4924ms.
[14:25:51.234] <TB1> INFO: scanning low vcal = 140
[14:25:51.677] <TB1> INFO: Expecting 41600 events.
[14:25:56.151] <TB1> INFO: 41600 events read in total (3759ms).
[14:25:56.152] <TB1> INFO: Test took 4918ms.
[14:25:56.154] <TB1> INFO: scanning low vcal = 150
[14:25:56.600] <TB1> INFO: Expecting 41600 events.
[14:26:01.050] <TB1> INFO: 41600 events read in total (3735ms).
[14:26:01.051] <TB1> INFO: Test took 4897ms.
[14:26:01.053] <TB1> INFO: scanning low vcal = 160
[14:26:01.486] <TB1> INFO: Expecting 41600 events.
[14:26:05.867] <TB1> INFO: 41600 events read in total (3666ms).
[14:26:05.868] <TB1> INFO: Test took 4815ms.
[14:26:05.870] <TB1> INFO: scanning low vcal = 170
[14:26:06.316] <TB1> INFO: Expecting 41600 events.
[14:26:10.709] <TB1> INFO: 41600 events read in total (3678ms).
[14:26:10.710] <TB1> INFO: Test took 4840ms.
[14:26:10.713] <TB1> INFO: scanning low vcal = 180
[14:26:11.160] <TB1> INFO: Expecting 41600 events.
[14:26:15.558] <TB1> INFO: 41600 events read in total (3683ms).
[14:26:15.558] <TB1> INFO: Test took 4845ms.
[14:26:15.561] <TB1> INFO: scanning low vcal = 190
[14:26:16.008] <TB1> INFO: Expecting 41600 events.
[14:26:20.291] <TB1> INFO: 41600 events read in total (3568ms).
[14:26:20.291] <TB1> INFO: Test took 4730ms.
[14:26:20.293] <TB1> INFO: scanning low vcal = 200
[14:26:20.742] <TB1> INFO: Expecting 41600 events.
[14:26:25.255] <TB1> INFO: 41600 events read in total (3798ms).
[14:26:25.256] <TB1> INFO: Test took 4963ms.
[14:26:25.259] <TB1> INFO: scanning low vcal = 210
[14:26:25.694] <TB1> INFO: Expecting 41600 events.
[14:26:30.183] <TB1> INFO: 41600 events read in total (3774ms).
[14:26:30.183] <TB1> INFO: Test took 4924ms.
[14:26:30.186] <TB1> INFO: scanning low vcal = 220
[14:26:30.624] <TB1> INFO: Expecting 41600 events.
[14:26:34.996] <TB1> INFO: 41600 events read in total (3657ms).
[14:26:34.997] <TB1> INFO: Test took 4811ms.
[14:26:34.999] <TB1> INFO: scanning low vcal = 230
[14:26:35.441] <TB1> INFO: Expecting 41600 events.
[14:26:39.969] <TB1> INFO: 41600 events read in total (3813ms).
[14:26:39.970] <TB1> INFO: Test took 4971ms.
[14:26:39.975] <TB1> INFO: scanning low vcal = 240
[14:26:40.398] <TB1> INFO: Expecting 41600 events.
[14:26:44.843] <TB1> INFO: 41600 events read in total (3730ms).
[14:26:44.844] <TB1> INFO: Test took 4869ms.
[14:26:44.846] <TB1> INFO: scanning low vcal = 250
[14:26:45.290] <TB1> INFO: Expecting 41600 events.
[14:26:49.800] <TB1> INFO: 41600 events read in total (3795ms).
[14:26:49.801] <TB1> INFO: Test took 4955ms.
[14:26:49.804] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[14:26:50.253] <TB1> INFO: Expecting 41600 events.
[14:26:54.802] <TB1> INFO: 41600 events read in total (3835ms).
[14:26:54.802] <TB1> INFO: Test took 4998ms.
[14:26:54.805] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[14:26:55.248] <TB1> INFO: Expecting 41600 events.
[14:26:59.679] <TB1> INFO: 41600 events read in total (3717ms).
[14:26:59.680] <TB1> INFO: Test took 4875ms.
[14:26:59.683] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[14:27:00.117] <TB1> INFO: Expecting 41600 events.
[14:27:04.650] <TB1> INFO: 41600 events read in total (3818ms).
[14:27:04.650] <TB1> INFO: Test took 4967ms.
[14:27:04.653] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[14:27:05.094] <TB1> INFO: Expecting 41600 events.
[14:27:09.630] <TB1> INFO: 41600 events read in total (3821ms).
[14:27:09.630] <TB1> INFO: Test took 4977ms.
[14:27:09.633] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[14:27:10.076] <TB1> INFO: Expecting 41600 events.
[14:27:14.527] <TB1> INFO: 41600 events read in total (3737ms).
[14:27:14.527] <TB1> INFO: Test took 4894ms.
[14:27:15.115] <TB1> INFO: PixTestGainPedestal::measure() done
[14:27:50.080] <TB1> INFO: PixTestGainPedestal::fit() done
[14:27:50.080] <TB1> INFO: non-linearity mean: 0.954 0.953 0.962 0.962 0.962 0.958 0.957 0.953 0.955 0.955 0.955 0.955 0.952 0.962 0.961 0.953
[14:27:50.081] <TB1> INFO: non-linearity RMS: 0.007 0.006 0.006 0.006 0.005 0.007 0.005 0.006 0.006 0.006 0.006 0.005 0.006 0.006 0.005 0.007
[14:27:50.081] <TB1> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//phCalibrationFitErr35_C0.dat
[14:27:50.103] <TB1> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//phCalibrationFitErr35_C1.dat
[14:27:50.123] <TB1> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//phCalibrationFitErr35_C2.dat
[14:27:50.145] <TB1> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//phCalibrationFitErr35_C3.dat
[14:27:50.166] <TB1> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//phCalibrationFitErr35_C4.dat
[14:27:50.187] <TB1> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//phCalibrationFitErr35_C5.dat
[14:27:50.209] <TB1> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//phCalibrationFitErr35_C6.dat
[14:27:50.230] <TB1> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//phCalibrationFitErr35_C7.dat
[14:27:50.251] <TB1> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//phCalibrationFitErr35_C8.dat
[14:27:50.272] <TB1> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//phCalibrationFitErr35_C9.dat
[14:27:50.293] <TB1> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//phCalibrationFitErr35_C10.dat
[14:27:50.314] <TB1> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//phCalibrationFitErr35_C11.dat
[14:27:50.334] <TB1> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//phCalibrationFitErr35_C12.dat
[14:27:50.355] <TB1> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//phCalibrationFitErr35_C13.dat
[14:27:50.374] <TB1> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//phCalibrationFitErr35_C14.dat
[14:27:50.394] <TB1> INFO: write gain/ped parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//phCalibrationFitErr35_C15.dat
[14:27:50.413] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 181 seconds
[14:27:50.413] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:27:50.413] <TB1> INFO: Decoding statistics:
[14:27:50.413] <TB1> INFO: General information:
[14:27:50.413] <TB1> INFO: 16bit words read: 2329160
[14:27:50.413] <TB1> INFO: valid events total: 83200
[14:27:50.413] <TB1> INFO: empty events: 0
[14:27:50.413] <TB1> INFO: valid events with pixels: 83200
[14:27:50.413] <TB1> INFO: valid pixel hits: 665380
[14:27:50.413] <TB1> INFO: Event errors: 0
[14:27:50.413] <TB1> INFO: start marker: 0
[14:27:50.413] <TB1> INFO: stop marker: 0
[14:27:50.413] <TB1> INFO: overflow: 0
[14:27:50.413] <TB1> INFO: invalid 5bit words: 0
[14:27:50.413] <TB1> INFO: invalid XOR eye diagram: 0
[14:27:50.413] <TB1> INFO: TBM errors: 0
[14:27:50.413] <TB1> INFO: flawed TBM headers: 0
[14:27:50.413] <TB1> INFO: flawed TBM trailers: 0
[14:27:50.413] <TB1> INFO: event ID mismatches: 0
[14:27:50.413] <TB1> INFO: ROC errors: 0
[14:27:50.413] <TB1> INFO: missing ROC header(s): 0
[14:27:50.413] <TB1> INFO: misplaced readback start: 0
[14:27:50.413] <TB1> INFO: Pixel decoding errors: 0
[14:27:50.413] <TB1> INFO: pixel data incomplete: 0
[14:27:50.413] <TB1> INFO: pixel address: 0
[14:27:50.413] <TB1> INFO: pulse height fill bit: 0
[14:27:50.413] <TB1> INFO: buffer corruption: 0
[14:27:50.420] <TB1> INFO: readReadbackCal: /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C0.dat .. /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C15.dat
[14:27:50.421] <TB1> INFO: ######################################################################
[14:27:50.421] <TB1> INFO: PixTestReadback::doTest()
[14:27:50.421] <TB1> INFO: ######################################################################
[14:27:50.422] <TB1> INFO: PixTestReadback::RES sent once
[14:28:01.611] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C0.dat
[14:28:01.611] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C1.dat
[14:28:01.611] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C2.dat
[14:28:01.612] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C3.dat
[14:28:01.612] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C4.dat
[14:28:01.612] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C5.dat
[14:28:01.612] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C6.dat
[14:28:01.612] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C7.dat
[14:28:01.612] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C8.dat
[14:28:01.612] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C9.dat
[14:28:01.612] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C10.dat
[14:28:01.612] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C11.dat
[14:28:01.612] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C12.dat
[14:28:01.612] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C13.dat
[14:28:01.612] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C14.dat
[14:28:01.612] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C15.dat
[14:28:01.637] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:28:01.638] <TB1> INFO: PixTestReadback::RES sent once
[14:28:12.782] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C0.dat
[14:28:12.782] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C1.dat
[14:28:12.783] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C2.dat
[14:28:12.783] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C3.dat
[14:28:12.783] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C4.dat
[14:28:12.783] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C5.dat
[14:28:12.783] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C6.dat
[14:28:12.783] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C7.dat
[14:28:12.783] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C8.dat
[14:28:12.783] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C9.dat
[14:28:12.783] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C10.dat
[14:28:12.783] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C11.dat
[14:28:12.783] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C12.dat
[14:28:12.784] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C13.dat
[14:28:12.784] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C14.dat
[14:28:12.784] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C15.dat
[14:28:12.811] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:28:12.811] <TB1> INFO: PixTestReadback::RES sent once
[14:28:21.375] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:28:21.375] <TB1> INFO: Vbg will be calibrated using Vd calibration
[14:28:21.375] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 152.5calibrated Vbg = 1.21557 :::*/*/*/*/
[14:28:21.376] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 158.1calibrated Vbg = 1.21763 :::*/*/*/*/
[14:28:21.376] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 157.6calibrated Vbg = 1.21961 :::*/*/*/*/
[14:28:21.376] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 161.5calibrated Vbg = 1.22068 :::*/*/*/*/
[14:28:21.376] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 146.2calibrated Vbg = 1.22599 :::*/*/*/*/
[14:28:21.376] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 162.9calibrated Vbg = 1.23233 :::*/*/*/*/
[14:28:21.376] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 146.9calibrated Vbg = 1.22149 :::*/*/*/*/
[14:28:21.376] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 145.7calibrated Vbg = 1.22467 :::*/*/*/*/
[14:28:21.376] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 156calibrated Vbg = 1.23098 :::*/*/*/*/
[14:28:21.376] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 144.2calibrated Vbg = 1.22007 :::*/*/*/*/
[14:28:21.376] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 162.4calibrated Vbg = 1.22624 :::*/*/*/*/
[14:28:21.376] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 151.1calibrated Vbg = 1.2272 :::*/*/*/*/
[14:28:21.376] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 157.4calibrated Vbg = 1.21931 :::*/*/*/*/
[14:28:21.376] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 147.2calibrated Vbg = 1.2176 :::*/*/*/*/
[14:28:21.376] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 155.8calibrated Vbg = 1.22265 :::*/*/*/*/
[14:28:21.376] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 162.2calibrated Vbg = 1.20806 :::*/*/*/*/
[14:28:21.378] <TB1> INFO: PixTestReadback::RES sent once
[14:31:15.258] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C0.dat
[14:31:15.258] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C1.dat
[14:31:15.258] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C2.dat
[14:31:15.259] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C3.dat
[14:31:15.259] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C4.dat
[14:31:15.259] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C5.dat
[14:31:15.259] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C6.dat
[14:31:15.259] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C7.dat
[14:31:15.259] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C8.dat
[14:31:15.259] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C9.dat
[14:31:15.259] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C10.dat
[14:31:15.259] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C11.dat
[14:31:15.259] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C12.dat
[14:31:15.260] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C13.dat
[14:31:15.260] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C14.dat
[14:31:15.260] <TB1> INFO: write readback calibration parameters into /usr/local/receptionDATA/M3017_FullQualification_2015-10-29_13h48m_1446122896//000_FulltestPxar_m20//readbackCal_C15.dat
[14:31:15.286] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:31:15.286] <TB1> INFO: PixTestReadback::doTest() done
[14:31:15.286] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:31:15.286] <TB1> INFO: Decoding statistics:
[14:31:15.286] <TB1> INFO: General information:
[14:31:15.286] <TB1> INFO: 16bit words read: 768
[14:31:15.286] <TB1> INFO: valid events total: 64
[14:31:15.286] <TB1> INFO: empty events: 64
[14:31:15.286] <TB1> INFO: valid events with pixels: 0
[14:31:15.286] <TB1> INFO: valid pixel hits: 0
[14:31:15.286] <TB1> INFO: Event errors: 0
[14:31:15.286] <TB1> INFO: start marker: 0
[14:31:15.287] <TB1> INFO: stop marker: 0
[14:31:15.287] <TB1> INFO: overflow: 0
[14:31:15.287] <TB1> INFO: invalid 5bit words: 0
[14:31:15.287] <TB1> INFO: invalid XOR eye diagram: 0
[14:31:15.287] <TB1> INFO: TBM errors: 0
[14:31:15.287] <TB1> INFO: flawed TBM headers: 0
[14:31:15.287] <TB1> INFO: flawed TBM trailers: 0
[14:31:15.287] <TB1> INFO: event ID mismatches: 0
[14:31:15.287] <TB1> INFO: ROC errors: 0
[14:31:15.287] <TB1> INFO: missing ROC header(s): 0
[14:31:15.287] <TB1> INFO: misplaced readback start: 0
[14:31:15.287] <TB1> INFO: Pixel decoding errors: 0
[14:31:15.287] <TB1> INFO: pixel data incomplete: 0
[14:31:15.287] <TB1> INFO: pixel address: 0
[14:31:15.287] <TB1> INFO: pulse height fill bit: 0
[14:31:15.287] <TB1> INFO: buffer corruption: 0
[14:31:15.300] <TB1> INFO: Decoding statistics:
[14:31:15.300] <TB1> INFO: General information:
[14:31:15.300] <TB1> INFO: 16bit words read: 2396366
[14:31:15.300] <TB1> INFO: valid events total: 88384
[14:31:15.300] <TB1> INFO: empty events: 2685
[14:31:15.300] <TB1> INFO: valid events with pixels: 85699
[14:31:15.300] <TB1> INFO: valid pixel hits: 667879
[14:31:15.300] <TB1> INFO: Event errors: 0
[14:31:15.300] <TB1> INFO: start marker: 0
[14:31:15.300] <TB1> INFO: stop marker: 0
[14:31:15.300] <TB1> INFO: overflow: 0
[14:31:15.300] <TB1> INFO: invalid 5bit words: 0
[14:31:15.300] <TB1> INFO: invalid XOR eye diagram: 0
[14:31:15.300] <TB1> INFO: TBM errors: 0
[14:31:15.300] <TB1> INFO: flawed TBM headers: 0
[14:31:15.300] <TB1> INFO: flawed TBM trailers: 0
[14:31:15.300] <TB1> INFO: event ID mismatches: 0
[14:31:15.300] <TB1> INFO: ROC errors: 0
[14:31:15.300] <TB1> INFO: missing ROC header(s): 0
[14:31:15.300] <TB1> INFO: misplaced readback start: 0
[14:31:15.300] <TB1> INFO: Pixel decoding errors: 0
[14:31:15.300] <TB1> INFO: pixel data incomplete: 0
[14:31:15.300] <TB1> INFO: pixel address: 0
[14:31:15.300] <TB1> INFO: pulse height fill bit: 0
[14:31:15.300] <TB1> INFO: buffer corruption: 0
[14:31:15.300] <TB1> INFO: enter test to run
[14:31:15.300] <TB1> INFO: test: exit no parameter change
[14:31:15.542] <TB1> QUIET: Connection to board 154 closed.
[14:31:15.621] <TB1> INFO: pXar: this is the end, my friend