[12:47:49.174] <TB1> INFO: *** Welcome to pxar ***
[12:47:49.174] <TB1> INFO: *** Today: 2015/10/26
[12:47:49.547] <TB1> INFO: *** Version: 7db0-dirty
[12:47:49.547] <TB1> INFO: readRocDacs: /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters_C0.dat .. /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters_C15.dat
[12:47:49.547] <TB1> INFO: readTbmDacs: /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//tbmParameters_C0a.dat .. /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//tbmParameters_C0b.dat
[12:47:49.547] <TB1> INFO: readMaskFile: /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//defaultMaskFile.dat
[12:47:49.547] <TB1> INFO: readTrimFile: /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//trimParameters_C0.dat .. /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//trimParameters_C15.dat
[12:47:49.613] <TB1> INFO: clk: 4
[12:47:49.613] <TB1> INFO: ctr: 4
[12:47:49.613] <TB1> INFO: sda: 19
[12:47:49.613] <TB1> INFO: tin: 9
[12:47:49.613] <TB1> INFO: level: 15
[12:47:49.613] <TB1> INFO: triggerdelay: 0
[12:47:49.613] <TB1> QUIET: Instanciating API for pxar v2.6.0+26~g89693ff
[12:47:49.613] <TB1> INFO: Log level: INFO
[12:47:49.625] <TB1> INFO: Found DTB DTB_WWXTQT
[12:47:49.635] <TB1> QUIET: Connection to board DTB_WWXTQT opened.
[12:47:49.638] <TB1> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[12:47:49.641] <TB1> INFO: RPC call hashes of host and DTB match: 398089610
[12:47:51.175] <TB1> INFO: DUT info:
[12:47:51.175] <TB1> INFO: The DUT currently contains the following objects:
[12:47:51.175] <TB1> INFO: 2 TBM Cores tbm08c (2 ON)
[12:47:51.175] <TB1> INFO: TBM Core alpha (0): 7 registers set
[12:47:51.175] <TB1> INFO: TBM Core beta (1): 7 registers set
[12:47:51.175] <TB1> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:47:51.175] <TB1> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:51.175] <TB1> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:51.175] <TB1> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:51.175] <TB1> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:51.175] <TB1> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:51.175] <TB1> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:51.175] <TB1> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:51.175] <TB1> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:51.175] <TB1> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:51.175] <TB1> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:51.175] <TB1> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:51.175] <TB1> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:51.175] <TB1> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:51.175] <TB1> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:51.175] <TB1> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:51.175] <TB1> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:47:51.576] <TB1> INFO: enter 'restricted' command line mode
[12:47:51.576] <TB1> INFO: enter test to run
[12:47:51.576] <TB1> INFO: test: pretest no parameter change
[12:47:51.576] <TB1> INFO: running: pretest
[12:47:51.580] <TB1> INFO: ######################################################################
[12:47:51.580] <TB1> INFO: PixTestPretest::doTest()
[12:47:51.580] <TB1> INFO: ######################################################################
[12:47:51.582] <TB1> INFO: ----------------------------------------------------------------------
[12:47:51.582] <TB1> INFO: PixTestPretest::programROC()
[12:47:51.582] <TB1> INFO: ----------------------------------------------------------------------
[12:48:09.600] <TB1> INFO: PixTestPretest::programROC() done: ROCs are all programmable
[12:48:09.600] <TB1> INFO: IA differences per ROC: 17.7 16.1 17.7 19.3 20.1 19.3 19.3 18.5 15.3 18.5 17.7 18.5 20.1 18.5 18.5 20.1
[12:48:09.672] <TB1> INFO: ----------------------------------------------------------------------
[12:48:09.672] <TB1> INFO: PixTestPretest::setVana() target Ia = 24 mA/ROC
[12:48:09.672] <TB1> INFO: ----------------------------------------------------------------------
[12:48:16.068] <TB1> INFO: PixTestPretest::setVana() done, Module Ia 393.9 mA = 24.6187 mA/ROC
[12:48:16.068] <TB1> INFO: i(loss) [mA/ROC]: 19.3 20.1 20.1 19.3 19.3 19.3 19.3 19.3 20.1 19.3 19.3 19.3 19.3 19.3 19.3 17.7
[12:48:16.102] <TB1> INFO: ----------------------------------------------------------------------
[12:48:16.102] <TB1> INFO: PixTestPretest::findTiming()
[12:48:16.102] <TB1> INFO: ----------------------------------------------------------------------
[12:48:16.102] <TB1> INFO: PixTestCmd::init()

[12:50:03.081] <TB1> INFO: TBM phases: 160MHz: 7, 400MHz: 2, TBM delays: ROC(0/1):4, header/trailer: 1, token: 1
[12:50:03.081] <TB1> INFO: (success/tries = 100/100), width = 2
[12:50:03.082] <TB1> INFO: ----------------------------------------------------------------------
[12:50:03.082] <TB1> INFO: PixTestPretest::findWorkingPixel()
[12:50:03.082] <TB1> INFO: ----------------------------------------------------------------------
[12:50:03.219] <TB1> INFO: Expecting 231680 events.


[12:50:10.678] <TB1> INFO: 231680 events read in total (6744ms).
[12:50:10.681] <TB1> INFO: Test took 7596ms.
[12:50:11.092] <TB1> INFO: Found working pixel in all ROCs: col/row = 12/22
[12:50:11.129] <TB1> INFO: ----------------------------------------------------------------------
[12:50:11.129] <TB1> INFO: PixTestPretest::setVthrCompCalDel()
[12:50:11.129] <TB1> INFO: ----------------------------------------------------------------------
[12:50:11.266] <TB1> INFO: Expecting 231680 events.
[12:50:19.648] <TB1> INFO: 231680 events read in total (7666ms).
[12:50:19.651] <TB1> INFO: Test took 8517ms.
[12:50:20.070] <TB1> INFO: PixTestPretest::setVthrCompCalDel() done
[12:50:20.070] <TB1> INFO: CalDel: 109 109 104 109 125 110 115 113 113 115 104 113 120 111 109 115
[12:50:20.070] <TB1> INFO: VthrComp: 51 51 51 51 51 54 51 51 51 51 51 53 51 51 51 51
[12:50:20.073] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters_C0.dat
[12:50:20.073] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters_C1.dat
[12:50:20.073] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters_C2.dat
[12:50:20.073] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters_C3.dat
[12:50:20.073] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters_C4.dat
[12:50:20.073] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters_C5.dat
[12:50:20.073] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters_C6.dat
[12:50:20.073] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters_C7.dat
[12:50:20.073] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters_C8.dat
[12:50:20.073] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters_C9.dat
[12:50:20.074] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters_C10.dat
[12:50:20.074] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters_C11.dat
[12:50:20.074] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters_C12.dat
[12:50:20.074] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters_C13.dat
[12:50:20.074] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters_C14.dat
[12:50:20.074] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters_C15.dat
[12:50:20.074] <TB1> INFO: write tbm parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//tbmParameters_C0a.dat
[12:50:20.074] <TB1> INFO: write tbm parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//tbmParameters_C0b.dat
[12:50:20.074] <TB1> INFO: PixTestPretest::doTest() done, duration: 148 seconds
[12:50:20.193] <TB1> INFO: enter test to run
[12:50:20.193] <TB1> INFO: test: fulltest no parameter change
[12:50:20.193] <TB1> INFO: running: fulltest
[12:50:20.193] <TB1> INFO: ######################################################################
[12:50:20.193] <TB1> INFO: PixTestFullTest::doTest()
[12:50:20.193] <TB1> INFO: ######################################################################
[12:50:20.194] <TB1> INFO: ######################################################################
[12:50:20.194] <TB1> INFO: PixTestAlive::doTest()
[12:50:20.194] <TB1> INFO: ######################################################################
[12:50:20.196] <TB1> INFO: ----------------------------------------------------------------------
[12:50:20.196] <TB1> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:50:20.196] <TB1> INFO: ----------------------------------------------------------------------
[12:50:20.499] <TB1> INFO: Expecting 41600 events.
[12:50:24.774] <TB1> INFO: 41600 events read in total (3560ms).
[12:50:24.775] <TB1> INFO: Test took 4578ms.
[12:50:24.781] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:50:25.163] <TB1> INFO: PixTestAlive::aliveTest() done
[12:50:25.163] <TB1> INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 1 4 6 2 1 0 0 4 0 8
[12:50:25.165] <TB1> INFO: ----------------------------------------------------------------------
[12:50:25.165] <TB1> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:50:25.165] <TB1> INFO: ----------------------------------------------------------------------
[12:50:25.470] <TB1> INFO: Expecting 41600 events.
[12:50:28.494] <TB1> INFO: 41600 events read in total (2309ms).
[12:50:28.495] <TB1> INFO: Test took 3329ms.
[12:50:28.495] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:50:28.496] <TB1> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[12:50:28.901] <TB1> INFO: PixTestAlive::maskTest() done
[12:50:28.901] <TB1> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:50:28.902] <TB1> INFO: ----------------------------------------------------------------------
[12:50:28.902] <TB1> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:50:28.902] <TB1> INFO: ----------------------------------------------------------------------
[12:50:29.230] <TB1> INFO: Expecting 41600 events.
[12:50:33.462] <TB1> INFO: 41600 events read in total (3517ms).
[12:50:33.463] <TB1> INFO: Test took 4560ms.
[12:50:33.470] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:50:33.850] <TB1> INFO: PixTestAlive::addressDecodingTest() done
[12:50:33.850] <TB1> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:50:33.850] <TB1> INFO: PixTestAlive::doTest() done, duration: 13 seconds
[12:50:33.850] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:50:33.850] <TB1> INFO: Decoding statistics:
[12:50:33.850] <TB1> INFO: General information:
[12:50:33.850] <TB1> INFO: 16bit words read: 0
[12:50:33.850] <TB1> INFO: valid events total: 0
[12:50:33.850] <TB1> INFO: empty events: 0
[12:50:33.850] <TB1> INFO: valid events with pixels: 0
[12:50:33.850] <TB1> INFO: valid pixel hits: 0
[12:50:33.850] <TB1> INFO: Event errors: 0
[12:50:33.850] <TB1> INFO: start marker: 0
[12:50:33.850] <TB1> INFO: stop marker: 0
[12:50:33.850] <TB1> INFO: overflow: 0
[12:50:33.850] <TB1> INFO: invalid 5bit words: 0
[12:50:33.850] <TB1> INFO: invalid XOR eye diagram: 0
[12:50:33.850] <TB1> INFO: TBM errors: 0
[12:50:33.850] <TB1> INFO: flawed TBM headers: 0
[12:50:33.850] <TB1> INFO: flawed TBM trailers: 0
[12:50:33.850] <TB1> INFO: event ID mismatches: 0
[12:50:33.850] <TB1> INFO: ROC errors: 0
[12:50:33.850] <TB1> INFO: missing ROC header(s): 0
[12:50:33.850] <TB1> INFO: misplaced readback start: 0
[12:50:33.850] <TB1> INFO: Pixel decoding errors: 0
[12:50:33.850] <TB1> INFO: pixel data incomplete: 0
[12:50:33.850] <TB1> INFO: pixel address: 0
[12:50:33.850] <TB1> INFO: pulse height fill bit: 0
[12:50:33.850] <TB1> INFO: buffer corruption: 0
[12:50:33.858] <TB1> INFO: ######################################################################
[12:50:33.858] <TB1> INFO: PixTestBBMap::doTest() Ntrig = 5, VcalS = 250 (high range)
[12:50:33.858] <TB1> INFO: ######################################################################
[12:50:33.861] <TB1> INFO: ---> dac: VthrComp name: calSMap ntrig: 5 dacrange: 0 .. 149 (-1/-1) hits flags = 514 (plus default)
[12:50:33.877] <TB1> INFO: dacScan split into 1 runs with ntrig = 5
[12:50:33.877] <TB1> INFO: run 1 of 1
[12:50:34.190] <TB1> INFO: Expecting 3120000 events.
[12:51:20.797] <TB1> INFO: 993305 events read in total (45892ms).
[12:52:05.769] <TB1> INFO: 1966895 events read in total (90864ms).
[12:52:52.110] <TB1> INFO: 2968370 events read in total (137205ms).
[12:52:59.118] <TB1> INFO: 3120000 events read in total (144213ms).
[12:52:59.168] <TB1> INFO: Test took 145291ms.
[12:52:59.293] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:53:21.589] <TB1> INFO: PixTestBBMap::doTest() done, duration: 167 seconds
[12:53:21.589] <TB1> INFO: number of dead bumps (per ROC): 1 1 1 0 0 1 2 1 1 0 0 0 0 1 0 1
[12:53:21.590] <TB1> INFO: separation cut (per ROC): 89 80 84 86 88 99 74 91 81 89 90 80 88 81 81 84
[12:53:21.590] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[12:53:21.590] <TB1> INFO: Decoding statistics:
[12:53:21.590] <TB1> INFO: General information:
[12:53:21.590] <TB1> INFO: 16bit words read: 0
[12:53:21.590] <TB1> INFO: valid events total: 0
[12:53:21.590] <TB1> INFO: empty events: 0
[12:53:21.590] <TB1> INFO: valid events with pixels: 0
[12:53:21.590] <TB1> INFO: valid pixel hits: 0
[12:53:21.590] <TB1> INFO: Event errors: 0
[12:53:21.590] <TB1> INFO: start marker: 0
[12:53:21.590] <TB1> INFO: stop marker: 0
[12:53:21.590] <TB1> INFO: overflow: 0
[12:53:21.590] <TB1> INFO: invalid 5bit words: 0
[12:53:21.590] <TB1> INFO: invalid XOR eye diagram: 0
[12:53:21.590] <TB1> INFO: TBM errors: 0
[12:53:21.590] <TB1> INFO: flawed TBM headers: 0
[12:53:21.590] <TB1> INFO: flawed TBM trailers: 0
[12:53:21.590] <TB1> INFO: event ID mismatches: 0
[12:53:21.590] <TB1> INFO: ROC errors: 0
[12:53:21.590] <TB1> INFO: missing ROC header(s): 0
[12:53:21.590] <TB1> INFO: misplaced readback start: 0
[12:53:21.590] <TB1> INFO: Pixel decoding errors: 0
[12:53:21.590] <TB1> INFO: pixel data incomplete: 0
[12:53:21.590] <TB1> INFO: pixel address: 0
[12:53:21.590] <TB1> INFO: pulse height fill bit: 0
[12:53:21.590] <TB1> INFO: buffer corruption: 0
[12:53:21.643] <TB1> INFO: ######################################################################
[12:53:21.643] <TB1> INFO: PixTestScurves::fullTest() ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:53:21.643] <TB1> INFO: ######################################################################
[12:53:21.644] <TB1> INFO: ----------------------------------------------------------------------
[12:53:21.644] <TB1> INFO: PixTestScurves::scurves(Vcal), ntrig = 50, dacs/step = -1, ntrig/step = -1
[12:53:21.644] <TB1> INFO: ----------------------------------------------------------------------
[12:53:21.644] <TB1> INFO: ---> dac: Vcal name: scurveVcal ntrig: 50 dacrange: 25 .. 150 (-1/-1) hits flags = 528 (plus default)
[12:53:21.651] <TB1> INFO: dacScan split into 1 runs with ntrig = 50
[12:53:21.651] <TB1> INFO: run 1 of 1
[12:53:21.953] <TB1> INFO: Expecting 26208000 events.
[12:53:55.781] <TB1> INFO: 1214400 events read in total (33113ms).
[12:54:28.914] <TB1> INFO: 2404600 events read in total (66246ms).
[12:55:02.500] <TB1> INFO: 3593250 events read in total (99832ms).
[12:55:36.312] <TB1> INFO: 4775350 events read in total (133644ms).
[12:56:09.097] <TB1> INFO: 5958200 events read in total (166429ms).
[12:56:41.294] <TB1> INFO: 7139450 events read in total (198626ms).
[12:57:14.607] <TB1> INFO: 8316500 events read in total (231939ms).
[12:57:47.531] <TB1> INFO: 9494300 events read in total (264863ms).
[12:58:20.472] <TB1> INFO: 10669350 events read in total (297804ms).
[12:58:54.190] <TB1> INFO: 11836850 events read in total (331522ms).
[12:59:27.149] <TB1> INFO: 13002950 events read in total (364481ms).
[13:00:00.113] <TB1> INFO: 14153150 events read in total (397445ms).
[13:00:32.787] <TB1> INFO: 15296250 events read in total (430119ms).
[13:01:05.790] <TB1> INFO: 16434800 events read in total (463122ms).
[13:01:39.921] <TB1> INFO: 17571000 events read in total (497253ms).
[13:02:12.027] <TB1> INFO: 18710050 events read in total (529359ms).
[13:02:45.609] <TB1> INFO: 19839950 events read in total (562941ms).
[13:03:18.188] <TB1> INFO: 20970650 events read in total (595520ms).
[13:03:50.750] <TB1> INFO: 22099850 events read in total (628082ms).
[13:04:22.912] <TB1> INFO: 23228550 events read in total (660244ms).
[13:04:55.322] <TB1> INFO: 24359850 events read in total (692654ms).
[13:05:27.844] <TB1> INFO: 25487850 events read in total (725176ms).
[13:05:48.070] <TB1> INFO: 26208000 events read in total (745402ms).
[13:05:48.106] <TB1> INFO: Test took 746455ms.
[13:05:48.188] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:05:48.346] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:05:49.839] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:05:51.433] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:05:52.943] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:05:54.269] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:05:55.583] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:05:56.903] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:05:58.291] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:05:59.635] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:06:01.019] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:06:02.355] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:06:03.751] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:06:05.165] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:06:06.559] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:06:07.911] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:06:09.289] <TB1> INFO: dumping ASCII scurve output file: SCurveData
[13:06:10.676] <TB1> INFO: PixTestScurves::scurves() done
[13:06:10.676] <TB1> INFO: Vcal mean: 102.34 92.72 92.75 100.78 108.56 111.07 90.61 101.55 100.93 103.79 107.03 104.40 96.90 91.56 99.44 108.85
[13:06:10.676] <TB1> INFO: Vcal RMS: 6.38 4.95 4.79 5.45 9.93 5.33 5.10 6.75 6.93 5.52 5.57 5.53 5.13 6.02 5.47 8.37
[13:06:10.676] <TB1> INFO: PixTestScurves::fullTest() done, duration: 769 seconds
[13:06:10.676] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:06:10.676] <TB1> INFO: Decoding statistics:
[13:06:10.676] <TB1> INFO: General information:
[13:06:10.676] <TB1> INFO: 16bit words read: 0
[13:06:10.676] <TB1> INFO: valid events total: 0
[13:06:10.676] <TB1> INFO: empty events: 0
[13:06:10.676] <TB1> INFO: valid events with pixels: 0
[13:06:10.676] <TB1> INFO: valid pixel hits: 0
[13:06:10.676] <TB1> INFO: Event errors: 0
[13:06:10.676] <TB1> INFO: start marker: 0
[13:06:10.676] <TB1> INFO: stop marker: 0
[13:06:10.676] <TB1> INFO: overflow: 0
[13:06:10.676] <TB1> INFO: invalid 5bit words: 0
[13:06:10.676] <TB1> INFO: invalid XOR eye diagram: 0
[13:06:10.676] <TB1> INFO: TBM errors: 0
[13:06:10.676] <TB1> INFO: flawed TBM headers: 0
[13:06:10.676] <TB1> INFO: flawed TBM trailers: 0
[13:06:10.676] <TB1> INFO: event ID mismatches: 0
[13:06:10.676] <TB1> INFO: ROC errors: 0
[13:06:10.676] <TB1> INFO: missing ROC header(s): 0
[13:06:10.676] <TB1> INFO: misplaced readback start: 0
[13:06:10.676] <TB1> INFO: Pixel decoding errors: 0
[13:06:10.676] <TB1> INFO: pixel data incomplete: 0
[13:06:10.676] <TB1> INFO: pixel address: 0
[13:06:10.676] <TB1> INFO: pulse height fill bit: 0
[13:06:10.676] <TB1> INFO: buffer corruption: 0
[13:06:10.744] <TB1> INFO: ######################################################################
[13:06:10.745] <TB1> INFO: PixTestTrim::doTest()
[13:06:10.745] <TB1> INFO: ######################################################################
[13:06:10.746] <TB1> INFO: ----------------------------------------------------------------------
[13:06:10.746] <TB1> INFO: PixTestTrim::trimTest() ntrig = 10, vcal = 35
[13:06:10.746] <TB1> INFO: ----------------------------------------------------------------------
[13:06:10.826] <TB1> INFO: ---> VthrComp thr map (minimal VthrComp)
[13:06:10.826] <TB1> INFO: ---> dac: vthrcomp name: TrimThr0 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:06:10.834] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:06:10.834] <TB1> INFO: run 1 of 1
[13:06:11.136] <TB1> INFO: Expecting 6281600 events.
[13:06:55.257] <TB1> INFO: 1435570 events read in total (43406ms).
[13:07:38.449] <TB1> INFO: 2857790 events read in total (86598ms).
[13:08:22.429] <TB1> INFO: 4283620 events read in total (130579ms).
[13:09:06.549] <TB1> INFO: 5719500 events read in total (174698ms).
[13:09:24.192] <TB1> INFO: 6281600 events read in total (192341ms).
[13:09:24.221] <TB1> INFO: Test took 193387ms.
[13:09:24.266] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:09:42.893] <TB1> INFO: ROC 0 VthrComp = 100
[13:09:42.893] <TB1> INFO: ROC 1 VthrComp = 93
[13:09:42.893] <TB1> INFO: ROC 2 VthrComp = 94
[13:09:42.893] <TB1> INFO: ROC 3 VthrComp = 101
[13:09:42.893] <TB1> INFO: ROC 4 VthrComp = 92
[13:09:42.894] <TB1> INFO: ROC 5 VthrComp = 108
[13:09:42.894] <TB1> INFO: ROC 6 VthrComp = 90
[13:09:42.894] <TB1> INFO: ROC 7 VthrComp = 99
[13:09:42.894] <TB1> INFO: ROC 8 VthrComp = 97
[13:09:42.894] <TB1> INFO: ROC 9 VthrComp = 102
[13:09:42.894] <TB1> INFO: ROC 10 VthrComp = 103
[13:09:42.894] <TB1> INFO: ROC 11 VthrComp = 103
[13:09:42.894] <TB1> INFO: ROC 12 VthrComp = 100
[13:09:42.895] <TB1> INFO: ROC 13 VthrComp = 95
[13:09:42.895] <TB1> INFO: ROC 14 VthrComp = 99
[13:09:42.895] <TB1> INFO: ROC 15 VthrComp = 100
[13:09:42.895] <TB1> INFO: ---> Vcal thr map (pixel with maximum Vcal thr)
[13:09:42.895] <TB1> INFO: ---> dac: vcal name: TrimThr1 ntrig: 10 dacrange: 10 .. 160 (-1/-1) hits flags = 528 (plus default)
[13:09:42.903] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:09:42.903] <TB1> INFO: run 1 of 1
[13:09:43.221] <TB1> INFO: Expecting 6281600 events.
[13:10:20.653] <TB1> INFO: 896100 events read in total (36717ms).
[13:10:57.246] <TB1> INFO: 1787200 events read in total (73310ms).
[13:11:34.148] <TB1> INFO: 2679170 events read in total (110212ms).
[13:12:10.657] <TB1> INFO: 3565660 events read in total (146721ms).
[13:12:46.630] <TB1> INFO: 4443770 events read in total (182694ms).
[13:13:22.589] <TB1> INFO: 5317280 events read in total (218653ms).
[13:13:59.043] <TB1> INFO: 6191400 events read in total (255107ms).
[13:14:03.184] <TB1> INFO: 6281600 events read in total (259248ms).
[13:14:03.243] <TB1> INFO: Test took 260340ms.
[13:14:03.415] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:26.817] <TB1> INFO: roc 0 with ID = 0 has maximal Vcal 60.9379 for pixel 0/77 mean/min/max = 46.6283/32.2081/61.0485
[13:14:26.817] <TB1> INFO: roc 1 with ID = 1 has maximal Vcal 57.6778 for pixel 12/76 mean/min/max = 45.501/33.3099/57.6921
[13:14:26.818] <TB1> INFO: roc 2 with ID = 2 has maximal Vcal 56.5785 for pixel 22/3 mean/min/max = 45.0485/33.5091/56.5879
[13:14:26.818] <TB1> INFO: roc 3 with ID = 3 has maximal Vcal 58.1092 for pixel 7/79 mean/min/max = 44.9829/31.7745/58.1914
[13:14:26.818] <TB1> INFO: roc 4 with ID = 4 has maximal Vcal 74.9286 for pixel 20/79 mean/min/max = 55.0896/35.0384/75.1408
[13:14:26.819] <TB1> INFO: roc 5 with ID = 5 has maximal Vcal 66.5679 for pixel 22/10 mean/min/max = 50.9049/34.6874/67.1223
[13:14:26.819] <TB1> INFO: roc 6 with ID = 6 has maximal Vcal 58.7268 for pixel 22/55 mean/min/max = 46.8249/34.7947/58.8551
[13:14:26.819] <TB1> INFO: roc 7 with ID = 7 has maximal Vcal 61.4094 for pixel 0/5 mean/min/max = 46.903/32.3931/61.4129
[13:14:26.819] <TB1> INFO: roc 8 with ID = 8 has maximal Vcal 60.8042 for pixel 21/7 mean/min/max = 46.6692/32.299/61.0394
[13:14:26.819] <TB1> INFO: roc 9 with ID = 9 has maximal Vcal 59.4163 for pixel 31/4 mean/min/max = 46.4758/33.2942/59.6574
[13:14:26.820] <TB1> INFO: roc 10 with ID = 10 has maximal Vcal 60.9558 for pixel 0/5 mean/min/max = 47.098/32.792/61.404
[13:14:26.820] <TB1> INFO: roc 11 with ID = 11 has maximal Vcal 60.8301 for pixel 3/11 mean/min/max = 46.8423/32.6041/61.0806
[13:14:26.820] <TB1> INFO: roc 12 with ID = 12 has maximal Vcal 57.4775 for pixel 14/76 mean/min/max = 44.9099/32.3169/57.5029
[13:14:26.821] <TB1> INFO: roc 13 with ID = 13 has maximal Vcal 58.4785 for pixel 5/8 mean/min/max = 45.6547/32.7072/58.6023
[13:14:26.821] <TB1> INFO: roc 14 with ID = 14 has maximal Vcal 58.4916 for pixel 27/10 mean/min/max = 45.1354/31.7245/58.5464
[13:14:26.821] <TB1> INFO: roc 15 with ID = 15 has maximal Vcal 64.2295 for pixel 18/71 mean/min/max = 48.303/31.9849/64.621
[13:14:26.821] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[13:14:26.953] <TB1> INFO: Expecting 514560 events.
[13:14:36.647] <TB1> INFO: 514560 events read in total (8979ms).
[13:14:36.653] <TB1> INFO: Expecting 514560 events.
[13:14:46.353] <TB1> INFO: 514560 events read in total (9046ms).
[13:14:46.359] <TB1> INFO: Expecting 514560 events.
[13:14:55.970] <TB1> INFO: 514560 events read in total (8940ms).
[13:14:55.978] <TB1> INFO: Expecting 514560 events.
[13:15:05.604] <TB1> INFO: 514560 events read in total (8954ms).
[13:15:05.617] <TB1> INFO: Expecting 514560 events.
[13:15:15.197] <TB1> INFO: 514560 events read in total (8917ms).
[13:15:15.210] <TB1> INFO: Expecting 514560 events.
[13:15:24.866] <TB1> INFO: 514560 events read in total (8989ms).
[13:15:24.880] <TB1> INFO: Expecting 514560 events.
[13:15:34.395] <TB1> INFO: 514560 events read in total (8856ms).
[13:15:34.411] <TB1> INFO: Expecting 514560 events.
[13:15:43.891] <TB1> INFO: 514560 events read in total (8816ms).
[13:15:43.909] <TB1> INFO: Expecting 514560 events.
[13:15:53.635] <TB1> INFO: 514560 events read in total (9064ms).
[13:15:53.662] <TB1> INFO: Expecting 514560 events.
[13:16:03.353] <TB1> INFO: 514560 events read in total (9062ms).
[13:16:03.375] <TB1> INFO: Expecting 514560 events.
[13:16:12.986] <TB1> INFO: 514560 events read in total (8960ms).
[13:16:13.008] <TB1> INFO: Expecting 514560 events.
[13:16:22.596] <TB1> INFO: 514560 events read in total (8934ms).
[13:16:22.622] <TB1> INFO: Expecting 514560 events.
[13:16:32.538] <TB1> INFO: 514560 events read in total (9271ms).
[13:16:32.566] <TB1> INFO: Expecting 514560 events.
[13:16:42.238] <TB1> INFO: 514560 events read in total (9026ms).
[13:16:42.268] <TB1> INFO: Expecting 514560 events.
[13:16:51.941] <TB1> INFO: 514560 events read in total (9026ms).
[13:16:51.982] <TB1> INFO: Expecting 514560 events.
[13:17:01.790] <TB1> INFO: 514560 events read in total (9180ms).
[13:17:01.834] <TB1> INFO: Test took 155013ms.
[13:17:02.797] <TB1> INFO: ---> dac: vcal name: TrimThr2 ntrig: 10 dacrange: 0 .. 150 (-1/-1) hits flags = 528 (plus default)
[13:17:02.806] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:17:02.806] <TB1> INFO: run 1 of 1
[13:17:03.114] <TB1> INFO: Expecting 6281600 events.
[13:17:40.437] <TB1> INFO: 875190 events read in total (36608ms).
[13:18:17.817] <TB1> INFO: 1746050 events read in total (73988ms).
[13:18:54.503] <TB1> INFO: 2617360 events read in total (110674ms).
[13:19:30.699] <TB1> INFO: 3483980 events read in total (146870ms).
[13:20:07.243] <TB1> INFO: 4341740 events read in total (183414ms).
[13:20:43.273] <TB1> INFO: 5195570 events read in total (219444ms).
[13:21:19.486] <TB1> INFO: 6048520 events read in total (255657ms).
[13:21:29.463] <TB1> INFO: 6281600 events read in total (265634ms).
[13:21:29.520] <TB1> INFO: Test took 266714ms.
[13:21:29.661] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:52.411] <TB1> INFO: ---> TrimStepCorr4 extremal thresholds: 0.015338 .. 255.000000
[13:21:52.490] <TB1> INFO: ---> dac: vcal name: trimStepCorr4 ntrig: 10 dacrange: 0 .. 255 (-1/-1) hits flags = 528 (plus default)
[13:21:52.499] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:21:52.499] <TB1> INFO: run 1 of 1
[13:21:52.804] <TB1> INFO: Expecting 10649600 events.
[13:22:29.131] <TB1> INFO: 827840 events read in total (35606ms).
[13:23:05.204] <TB1> INFO: 1655160 events read in total (71679ms).
[13:23:41.029] <TB1> INFO: 2482480 events read in total (107504ms).
[13:24:15.708] <TB1> INFO: 3309340 events read in total (142183ms).
[13:24:52.154] <TB1> INFO: 4136620 events read in total (178629ms).
[13:25:27.551] <TB1> INFO: 4963740 events read in total (214026ms).
[13:26:02.618] <TB1> INFO: 5790720 events read in total (249093ms).
[13:26:37.874] <TB1> INFO: 6617580 events read in total (284349ms).
[13:27:13.592] <TB1> INFO: 7443630 events read in total (320067ms).
[13:27:48.566] <TB1> INFO: 8268620 events read in total (355041ms).
[13:28:24.693] <TB1> INFO: 9092960 events read in total (391168ms).
[13:29:00.548] <TB1> INFO: 9918020 events read in total (427023ms).
[13:29:32.101] <TB1> INFO: 10649600 events read in total (458576ms).
[13:29:32.219] <TB1> INFO: Test took 459721ms.
[13:29:32.499] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:30:00.270] <TB1> INFO: ---> TrimStepCorr2 extremal thresholds: 1.996453 .. 71.571838
[13:30:00.349] <TB1> INFO: ---> dac: vcal name: trimStepCorr2 ntrig: 10 dacrange: 1 .. 81 (-1/-1) hits flags = 528 (plus default)
[13:30:00.357] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:30:00.357] <TB1> INFO: run 1 of 1
[13:30:00.659] <TB1> INFO: Expecting 3369600 events.
[13:30:40.195] <TB1> INFO: 1009810 events read in total (38819ms).
[13:31:18.624] <TB1> INFO: 2019190 events read in total (77249ms).
[13:31:53.811] <TB1> INFO: 3027420 events read in total (112435ms).
[13:32:07.516] <TB1> INFO: 3369600 events read in total (126140ms).
[13:32:07.546] <TB1> INFO: Test took 127190ms.
[13:32:07.611] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:32:23.483] <TB1> INFO: ---> TrimStepCorr1a extremal thresholds: 14.788076 .. 55.956067
[13:32:23.562] <TB1> INFO: ---> dac: vcal name: trimStepCorr1a ntrig: 10 dacrange: 4 .. 65 (-1/-1) hits flags = 528 (plus default)
[13:32:23.570] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:32:23.570] <TB1> INFO: run 1 of 1
[13:32:23.875] <TB1> INFO: Expecting 2579200 events.
[13:33:05.479] <TB1> INFO: 1079530 events read in total (40890ms).
[13:33:42.754] <TB1> INFO: 2157990 events read in total (78164ms).
[13:33:59.063] <TB1> INFO: 2579200 events read in total (94474ms).
[13:33:59.083] <TB1> INFO: Test took 95513ms.
[13:33:59.126] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:34:13.340] <TB1> INFO: ---> TrimStepCorr1b extremal thresholds: 14.788076 .. 55.956067
[13:34:13.422] <TB1> INFO: ---> dac: vcal name: trimStepCorr1b ntrig: 10 dacrange: 4 .. 65 (-1/-1) hits flags = 528 (plus default)
[13:34:13.430] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:34:13.430] <TB1> INFO: run 1 of 1
[13:34:13.732] <TB1> INFO: Expecting 2579200 events.
[13:34:54.236] <TB1> INFO: 1080810 events read in total (39789ms).
[13:35:33.398] <TB1> INFO: 2160650 events read in total (78951ms).
[13:35:48.586] <TB1> INFO: 2579200 events read in total (94140ms).
[13:35:48.607] <TB1> INFO: Test took 95178ms.
[13:35:48.650] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:36:03.406] <TB1> INFO: ---> TrimThrFinal extremal thresholds: 15 .. 55
[13:36:03.406] <TB1> INFO: ---> dac: vcal name: TrimThrFinal ntrig: 10 dacrange: 15 .. 55 (-1/-1) hits flags = 528 (plus default)
[13:36:03.415] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:36:03.415] <TB1> INFO: run 1 of 1
[13:36:03.726] <TB1> INFO: Expecting 1705600 events.
[13:36:41.594] <TB1> INFO: 1077350 events read in total (37153ms).
[13:37:04.484] <TB1> INFO: 1705600 events read in total (60043ms).
[13:37:04.504] <TB1> INFO: Test took 61089ms.
[13:37:04.537] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:37:18.302] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C0.dat
[13:37:18.302] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C1.dat
[13:37:18.302] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C2.dat
[13:37:18.303] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C3.dat
[13:37:18.303] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C4.dat
[13:37:18.303] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C5.dat
[13:37:18.303] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C6.dat
[13:37:18.303] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C7.dat
[13:37:18.303] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C8.dat
[13:37:18.303] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C9.dat
[13:37:18.303] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C10.dat
[13:37:18.303] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C11.dat
[13:37:18.304] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C12.dat
[13:37:18.304] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C13.dat
[13:37:18.304] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C14.dat
[13:37:18.304] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C15.dat
[13:37:18.304] <TB1> INFO: write trim parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//trimParameters35_C0.dat
[13:37:18.312] <TB1> INFO: write trim parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//trimParameters35_C1.dat
[13:37:18.319] <TB1> INFO: write trim parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//trimParameters35_C2.dat
[13:37:18.324] <TB1> INFO: write trim parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//trimParameters35_C3.dat
[13:37:18.331] <TB1> INFO: write trim parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//trimParameters35_C4.dat
[13:37:18.336] <TB1> INFO: write trim parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//trimParameters35_C5.dat
[13:37:18.342] <TB1> INFO: write trim parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//trimParameters35_C6.dat
[13:37:18.348] <TB1> INFO: write trim parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//trimParameters35_C7.dat
[13:37:18.354] <TB1> INFO: write trim parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//trimParameters35_C8.dat
[13:37:18.360] <TB1> INFO: write trim parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//trimParameters35_C9.dat
[13:37:18.366] <TB1> INFO: write trim parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//trimParameters35_C10.dat
[13:37:18.372] <TB1> INFO: write trim parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//trimParameters35_C11.dat
[13:37:18.378] <TB1> INFO: write trim parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//trimParameters35_C12.dat
[13:37:18.385] <TB1> INFO: write trim parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//trimParameters35_C13.dat
[13:37:18.398] <TB1> INFO: write trim parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//trimParameters35_C14.dat
[13:37:18.411] <TB1> INFO: write trim parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//trimParameters35_C15.dat
[13:37:18.423] <TB1> INFO: PixTestTrim::trimTest() done
[13:37:18.423] <TB1> INFO: vtrim: 100 96 90 104 105 123 93 102 108 112 103 102 102 100 97 103
[13:37:18.423] <TB1> INFO: vthrcomp: 100 93 94 101 92 108 90 99 97 102 103 103 100 95 99 100
[13:37:18.423] <TB1> INFO: vcal mean: 34.91 34.98 34.97 34.96 35.01 35.01 34.93 34.93 34.93 34.98 35.03 35.03 34.97 34.94 34.98 34.91
[13:37:18.423] <TB1> INFO: vcal RMS: 1.10 0.85 0.84 0.99 1.28 0.95 1.01 1.51 1.61 1.18 0.94 0.90 0.83 1.26 0.94 1.87
[13:37:18.423] <TB1> INFO: bits mean: 9.25 9.71 9.58 9.85 7.04 8.42 8.72 9.07 9.38 9.41 8.65 9.21 9.68 9.49 9.77 8.93
[13:37:18.423] <TB1> INFO: bits RMS: 2.78 2.48 2.53 2.66 2.53 2.42 2.62 2.79 2.71 2.55 2.88 2.70 2.67 2.61 2.68 2.73
[13:37:18.431] <TB1> INFO: ----------------------------------------------------------------------
[13:37:18.431] <TB1> INFO: PixTestTrim::trimBitTest() ntrig = 10, vtrims = 254 126 63 32
[13:37:18.431] <TB1> INFO: ----------------------------------------------------------------------
[13:37:18.434] <TB1> INFO: ---> dac: Vcal name: TrimBitsThr0 ntrig: 10 dacrange: 0 .. 199 (-1/-1) hits flags = 528 (plus default)
[13:37:18.444] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:37:18.444] <TB1> INFO: run 1 of 1
[13:37:18.747] <TB1> INFO: Expecting 8320000 events.
[13:38:01.512] <TB1> INFO: 1239250 events read in total (42050ms).
[13:38:42.726] <TB1> INFO: 2464370 events read in total (83264ms).
[13:39:23.231] <TB1> INFO: 3681930 events read in total (123769ms).
[13:40:03.716] <TB1> INFO: 4882880 events read in total (164254ms).
[13:40:43.628] <TB1> INFO: 6072050 events read in total (204166ms).
[13:41:22.298] <TB1> INFO: 7257080 events read in total (242836ms).
[13:41:58.568] <TB1> INFO: 8320000 events read in total (279106ms).
[13:41:58.604] <TB1> INFO: Test took 280160ms.
[13:41:58.692] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:24.101] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim14 ntrig: 10 dacrange: 0 .. 196 (-1/-1) hits flags = 528 (plus default)
[13:42:24.109] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:42:24.109] <TB1> INFO: run 1 of 1
[13:42:24.418] <TB1> INFO: Expecting 8195200 events.
[13:43:05.966] <TB1> INFO: 1188380 events read in total (40832ms).
[13:43:46.384] <TB1> INFO: 2365660 events read in total (81250ms).
[13:44:26.504] <TB1> INFO: 3535840 events read in total (121370ms).
[13:45:07.096] <TB1> INFO: 4693120 events read in total (161962ms).
[13:45:47.353] <TB1> INFO: 5840380 events read in total (202219ms).
[13:46:22.077] <TB1> INFO: 6982080 events read in total (236943ms).
[13:47:01.758] <TB1> INFO: 8127120 events read in total (276624ms).
[13:47:04.410] <TB1> INFO: 8195200 events read in total (279276ms).
[13:47:04.473] <TB1> INFO: Test took 280364ms.
[13:47:04.579] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:30.905] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim13 ntrig: 10 dacrange: 0 .. 185 (-1/-1) hits flags = 528 (plus default)
[13:47:30.913] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:47:30.913] <TB1> INFO: run 1 of 1
[13:47:31.217] <TB1> INFO: Expecting 7737600 events.
[13:48:14.158] <TB1> INFO: 1227660 events read in total (42226ms).
[13:48:56.767] <TB1> INFO: 2442770 events read in total (84835ms).
[13:49:38.507] <TB1> INFO: 3649150 events read in total (126575ms).
[13:50:20.886] <TB1> INFO: 4836180 events read in total (168955ms).
[13:50:58.028] <TB1> INFO: 6015390 events read in total (206096ms).
[13:51:38.899] <TB1> INFO: 7192110 events read in total (246967ms).
[13:51:59.113] <TB1> INFO: 7737600 events read in total (267181ms).
[13:51:59.145] <TB1> INFO: Test took 268232ms.
[13:51:59.226] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:23.627] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim11 ntrig: 10 dacrange: 0 .. 184 (-1/-1) hits flags = 528 (plus default)
[13:52:23.635] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:52:23.635] <TB1> INFO: run 1 of 1
[13:52:23.937] <TB1> INFO: Expecting 7696000 events.
[13:53:04.911] <TB1> INFO: 1230070 events read in total (40259ms).
[13:53:45.571] <TB1> INFO: 2447260 events read in total (80919ms).
[13:54:25.676] <TB1> INFO: 3655930 events read in total (121024ms).
[13:55:04.159] <TB1> INFO: 4844930 events read in total (159507ms).
[13:55:45.438] <TB1> INFO: 6026580 events read in total (200786ms).
[13:56:26.694] <TB1> INFO: 7205160 events read in total (242042ms).
[13:56:43.022] <TB1> INFO: 7696000 events read in total (258370ms).
[13:56:43.061] <TB1> INFO: Test took 259426ms.
[13:56:43.143] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:07.061] <TB1> INFO: ---> dac: Vcal name: TrimThr_trim7 ntrig: 10 dacrange: 0 .. 175 (-1/-1) hits flags = 528 (plus default)
[13:57:07.069] <TB1> INFO: dacScan split into 1 runs with ntrig = 10
[13:57:07.069] <TB1> INFO: run 1 of 1
[13:57:07.374] <TB1> INFO: Expecting 7321600 events.
[13:57:51.107] <TB1> INFO: 1268920 events read in total (43018ms).
[13:58:32.826] <TB1> INFO: 2523040 events read in total (84737ms).
[13:59:15.101] <TB1> INFO: 3764180 events read in total (127012ms).
[13:59:53.400] <TB1> INFO: 4983970 events read in total (165311ms).
[14:00:36.397] <TB1> INFO: 6197490 events read in total (208308ms).
[14:01:14.657] <TB1> INFO: 7321600 events read in total (246568ms).
[14:01:14.690] <TB1> INFO: Test took 247621ms.
[14:01:14.772] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:38.334] <TB1> INFO: PixTestTrim::trimBitTest() done
[14:01:38.336] <TB1> INFO: PixTestTrim::doTest() done, duration: 3327 seconds
[14:01:38.336] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:38.336] <TB1> INFO: Decoding statistics:
[14:01:38.336] <TB1> INFO: General information:
[14:01:38.336] <TB1> INFO: 16bit words read: 0
[14:01:38.336] <TB1> INFO: valid events total: 0
[14:01:38.336] <TB1> INFO: empty events: 0
[14:01:38.336] <TB1> INFO: valid events with pixels: 0
[14:01:38.336] <TB1> INFO: valid pixel hits: 0
[14:01:38.336] <TB1> INFO: Event errors: 0
[14:01:38.336] <TB1> INFO: start marker: 0
[14:01:38.336] <TB1> INFO: stop marker: 0
[14:01:38.336] <TB1> INFO: overflow: 0
[14:01:38.336] <TB1> INFO: invalid 5bit words: 0
[14:01:38.336] <TB1> INFO: invalid XOR eye diagram: 0
[14:01:38.336] <TB1> INFO: TBM errors: 0
[14:01:38.336] <TB1> INFO: flawed TBM headers: 0
[14:01:38.336] <TB1> INFO: flawed TBM trailers: 0
[14:01:38.336] <TB1> INFO: event ID mismatches: 0
[14:01:38.336] <TB1> INFO: ROC errors: 0
[14:01:38.336] <TB1> INFO: missing ROC header(s): 0
[14:01:38.336] <TB1> INFO: misplaced readback start: 0
[14:01:38.336] <TB1> INFO: Pixel decoding errors: 0
[14:01:38.336] <TB1> INFO: pixel data incomplete: 0
[14:01:38.336] <TB1> INFO: pixel address: 0
[14:01:38.336] <TB1> INFO: pulse height fill bit: 0
[14:01:38.336] <TB1> INFO: buffer corruption: 0
[14:01:38.985] <TB1> INFO: ######################################################################
[14:01:38.985] <TB1> INFO: PixTestPhOptimization::doTest() Ntrig = 10
[14:01:38.985] <TB1> INFO: ######################################################################
[14:01:39.293] <TB1> INFO: Expecting 41600 events.
[14:01:43.623] <TB1> INFO: 41600 events read in total (3615ms).
[14:01:43.624] <TB1> INFO: Test took 4637ms.
[14:01:43.632] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:44.285] <TB1> INFO: Expecting 41600 events.
[14:01:48.630] <TB1> INFO: 41600 events read in total (3630ms).
[14:01:48.631] <TB1> INFO: Test took 4659ms.
[14:01:48.637] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:49.090] <TB1> INFO: Expecting 41600 events.
[14:01:53.407] <TB1> INFO: 41600 events read in total (3602ms).
[14:01:53.407] <TB1> INFO: Test took 4647ms.
[14:01:53.413] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:53.860] <TB1> INFO: Expecting 2560 events.
[14:01:54.826] <TB1> INFO: 2560 events read in total (251ms).
[14:01:54.826] <TB1> INFO: Test took 1406ms.
[14:01:55.334] <TB1> INFO: Expecting 2560 events.
[14:01:56.299] <TB1> INFO: 2560 events read in total (250ms).
[14:01:56.299] <TB1> INFO: Test took 1472ms.
[14:01:56.807] <TB1> INFO: Expecting 2560 events.
[14:01:57.768] <TB1> INFO: 2560 events read in total (246ms).
[14:01:57.768] <TB1> INFO: Test took 1469ms.
[14:01:58.276] <TB1> INFO: Expecting 2560 events.
[14:01:59.239] <TB1> INFO: 2560 events read in total (248ms).
[14:01:59.239] <TB1> INFO: Test took 1470ms.
[14:01:59.747] <TB1> INFO: Expecting 2560 events.
[14:02:00.711] <TB1> INFO: 2560 events read in total (249ms).
[14:02:00.711] <TB1> INFO: Test took 1471ms.
[14:02:01.219] <TB1> INFO: Expecting 2560 events.
[14:02:02.180] <TB1> INFO: 2560 events read in total (246ms).
[14:02:02.181] <TB1> INFO: Test took 1469ms.
[14:02:02.688] <TB1> INFO: Expecting 2560 events.
[14:02:03.652] <TB1> INFO: 2560 events read in total (249ms).
[14:02:03.652] <TB1> INFO: Test took 1470ms.
[14:02:04.160] <TB1> INFO: Expecting 2560 events.
[14:02:05.123] <TB1> INFO: 2560 events read in total (248ms).
[14:02:05.123] <TB1> INFO: Test took 1470ms.
[14:02:05.631] <TB1> INFO: Expecting 2560 events.
[14:02:06.593] <TB1> INFO: 2560 events read in total (247ms).
[14:02:06.594] <TB1> INFO: Test took 1470ms.
[14:02:07.102] <TB1> INFO: Expecting 2560 events.
[14:02:08.063] <TB1> INFO: 2560 events read in total (246ms).
[14:02:08.063] <TB1> INFO: Test took 1469ms.
[14:02:08.571] <TB1> INFO: Expecting 2560 events.
[14:02:09.534] <TB1> INFO: 2560 events read in total (248ms).
[14:02:09.534] <TB1> INFO: Test took 1470ms.
[14:02:10.042] <TB1> INFO: Expecting 2560 events.
[14:02:11.007] <TB1> INFO: 2560 events read in total (250ms).
[14:02:11.007] <TB1> INFO: Test took 1473ms.
[14:02:11.515] <TB1> INFO: Expecting 2560 events.
[14:02:12.480] <TB1> INFO: 2560 events read in total (250ms).
[14:02:12.480] <TB1> INFO: Test took 1473ms.
[14:02:12.988] <TB1> INFO: Expecting 2560 events.
[14:02:13.951] <TB1> INFO: 2560 events read in total (248ms).
[14:02:13.952] <TB1> INFO: Test took 1471ms.
[14:02:14.460] <TB1> INFO: Expecting 2560 events.
[14:02:15.422] <TB1> INFO: 2560 events read in total (247ms).
[14:02:15.422] <TB1> INFO: Test took 1470ms.
[14:02:15.931] <TB1> INFO: Expecting 2560 events.
[14:02:16.893] <TB1> INFO: 2560 events read in total (247ms).
[14:02:16.893] <TB1> INFO: Test took 1470ms.
[14:02:16.898] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:02:17.403] <TB1> INFO: Expecting 655360 events.
[14:02:29.837] <TB1> INFO: 655360 events read in total (11719ms).
[14:02:29.849] <TB1> INFO: Expecting 655360 events.
[14:02:42.237] <TB1> INFO: 655360 events read in total (11802ms).
[14:02:42.250] <TB1> INFO: Expecting 655360 events.
[14:02:54.677] <TB1> INFO: 655360 events read in total (11847ms).
[14:02:54.699] <TB1> INFO: Expecting 655360 events.
[14:03:06.889] <TB1> INFO: 655360 events read in total (11650ms).
[14:03:06.908] <TB1> INFO: Expecting 655360 events.
[14:03:19.347] <TB1> INFO: 655360 events read in total (11848ms).
[14:03:19.370] <TB1> INFO: Expecting 655360 events.
[14:03:31.697] <TB1> INFO: 655360 events read in total (11770ms).
[14:03:31.731] <TB1> INFO: Expecting 655360 events.
[14:03:43.955] <TB1> INFO: 655360 events read in total (11683ms).
[14:03:43.998] <TB1> INFO: Expecting 655360 events.
[14:03:56.192] <TB1> INFO: 655360 events read in total (11645ms).
[14:03:56.224] <TB1> INFO: Expecting 655360 events.
[14:04:08.628] <TB1> INFO: 655360 events read in total (11826ms).
[14:04:08.664] <TB1> INFO: Expecting 655360 events.
[14:04:20.918] <TB1> INFO: 655360 events read in total (11718ms).
[14:04:20.958] <TB1> INFO: Expecting 655360 events.
[14:04:33.309] <TB1> INFO: 655360 events read in total (11780ms).
[14:04:33.350] <TB1> INFO: Expecting 655360 events.
[14:04:45.551] <TB1> INFO: 655360 events read in total (11632ms).
[14:04:45.596] <TB1> INFO: Expecting 655360 events.
[14:04:58.244] <TB1> INFO: 655360 events read in total (12079ms).
[14:04:58.309] <TB1> INFO: Expecting 655360 events.
[14:05:10.595] <TB1> INFO: 655360 events read in total (11755ms).
[14:05:10.646] <TB1> INFO: Expecting 655360 events.
[14:05:22.969] <TB1> INFO: 655360 events read in total (11758ms).
[14:05:23.025] <TB1> INFO: Expecting 655360 events.
[14:05:35.275] <TB1> INFO: 655360 events read in total (11722ms).
[14:05:35.333] <TB1> INFO: Test took 198435ms.
[14:05:35.408] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:05:35.718] <TB1> INFO: Expecting 655360 events.
[14:05:48.131] <TB1> INFO: 655360 events read in total (11698ms).
[14:05:48.140] <TB1> INFO: Expecting 655360 events.
[14:06:00.446] <TB1> INFO: 655360 events read in total (11699ms).
[14:06:00.462] <TB1> INFO: Expecting 655360 events.
[14:06:12.674] <TB1> INFO: 655360 events read in total (11647ms).
[14:06:12.689] <TB1> INFO: Expecting 655360 events.
[14:06:24.879] <TB1> INFO: 655360 events read in total (11589ms).
[14:06:24.898] <TB1> INFO: Expecting 655360 events.
[14:06:37.251] <TB1> INFO: 655360 events read in total (11792ms).
[14:06:37.272] <TB1> INFO: Expecting 655360 events.
[14:06:49.695] <TB1> INFO: 655360 events read in total (11843ms).
[14:06:49.719] <TB1> INFO: Expecting 655360 events.
[14:07:02.090] <TB1> INFO: 655360 events read in total (11779ms).
[14:07:02.131] <TB1> INFO: Expecting 655360 events.
[14:07:14.541] <TB1> INFO: 655360 events read in total (11849ms).
[14:07:14.587] <TB1> INFO: Expecting 655360 events.
[14:07:26.971] <TB1> INFO: 655360 events read in total (11857ms).
[14:07:27.007] <TB1> INFO: Expecting 655360 events.
[14:07:39.497] <TB1> INFO: 655360 events read in total (11917ms).
[14:07:39.536] <TB1> INFO: Expecting 655360 events.
[14:07:52.152] <TB1> INFO: 655360 events read in total (12041ms).
[14:07:52.194] <TB1> INFO: Expecting 655360 events.
[14:08:04.470] <TB1> INFO: 655360 events read in total (11702ms).
[14:08:04.538] <TB1> INFO: Expecting 655360 events.
[14:08:17.033] <TB1> INFO: 655360 events read in total (11955ms).
[14:08:17.087] <TB1> INFO: Expecting 655360 events.
[14:08:29.349] <TB1> INFO: 655360 events read in total (11735ms).
[14:08:29.402] <TB1> INFO: Expecting 655360 events.
[14:08:41.700] <TB1> INFO: 655360 events read in total (11739ms).
[14:08:41.754] <TB1> INFO: Expecting 655360 events.
[14:08:53.686] <TB1> INFO: 655360 events read in total (11379ms).
[14:08:53.773] <TB1> INFO: Test took 198365ms.
[14:08:54.045] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:08:54.055] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:08:54.062] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:08:54.068] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:08:54.075] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:08:54.081] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:08:54.088] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[14:08:54.094] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:08:54.101] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[14:08:54.107] <TB1> INFO: safety margin for low PH: adding 2, margin is now 22
[14:08:54.113] <TB1> INFO: safety margin for low PH: adding 3, margin is now 23
[14:08:54.120] <TB1> INFO: safety margin for low PH: adding 4, margin is now 24
[14:08:54.126] <TB1> INFO: safety margin for low PH: adding 5, margin is now 25
[14:08:54.133] <TB1> INFO: safety margin for low PH: adding 6, margin is now 26
[14:08:54.139] <TB1> INFO: safety margin for low PH: adding 7, margin is now 27
[14:08:54.146] <TB1> INFO: safety margin for low PH: adding 8, margin is now 28
[14:08:54.152] <TB1> INFO: safety margin for low PH: adding 9, margin is now 29
[14:08:54.159] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:08:54.165] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:08:54.172] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:08:54.178] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:08:54.184] <TB1> INFO: safety margin for low PH: adding 1, margin is now 21
[14:08:54.191] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:08:54.197] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:08:54.203] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:08:54.210] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:08:54.216] <TB1> INFO: safety margin for low PH: adding 0, margin is now 20
[14:08:54.256] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C0.dat
[14:08:54.256] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C1.dat
[14:08:54.256] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C2.dat
[14:08:54.256] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C3.dat
[14:08:54.256] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C4.dat
[14:08:54.256] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C5.dat
[14:08:54.257] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C6.dat
[14:08:54.257] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C7.dat
[14:08:54.257] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C8.dat
[14:08:54.257] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C9.dat
[14:08:54.257] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C10.dat
[14:08:54.258] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C11.dat
[14:08:54.258] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C12.dat
[14:08:54.258] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C13.dat
[14:08:54.258] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C14.dat
[14:08:54.258] <TB1> INFO: write dac parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//dacParameters35_C15.dat
[14:08:54.574] <TB1> INFO: Expecting 41600 events.
[14:08:58.563] <TB1> INFO: 41600 events read in total (3274ms).
[14:08:58.564] <TB1> INFO: Test took 4302ms.
[14:08:59.177] <TB1> INFO: Expecting 41600 events.
[14:09:03.038] <TB1> INFO: 41600 events read in total (3146ms).
[14:09:03.038] <TB1> INFO: Test took 4169ms.
[14:09:03.678] <TB1> INFO: Expecting 41600 events.
[14:09:07.633] <TB1> INFO: 41600 events read in total (3240ms).
[14:09:07.634] <TB1> INFO: Test took 4261ms.
[14:09:07.967] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:08.099] <TB1> INFO: Expecting 2560 events.
[14:09:09.059] <TB1> INFO: 2560 events read in total (245ms).
[14:09:09.059] <TB1> INFO: Test took 1092ms.
[14:09:09.062] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:09.568] <TB1> INFO: Expecting 2560 events.
[14:09:10.530] <TB1> INFO: 2560 events read in total (247ms).
[14:09:10.530] <TB1> INFO: Test took 1468ms.
[14:09:10.533] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:11.038] <TB1> INFO: Expecting 2560 events.
[14:09:12.007] <TB1> INFO: 2560 events read in total (249ms).
[14:09:12.007] <TB1> INFO: Test took 1474ms.
[14:09:12.010] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:12.516] <TB1> INFO: Expecting 2560 events.
[14:09:13.481] <TB1> INFO: 2560 events read in total (250ms).
[14:09:13.481] <TB1> INFO: Test took 1471ms.
[14:09:13.484] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:13.990] <TB1> INFO: Expecting 2560 events.
[14:09:14.955] <TB1> INFO: 2560 events read in total (250ms).
[14:09:14.956] <TB1> INFO: Test took 1472ms.
[14:09:14.958] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:15.464] <TB1> INFO: Expecting 2560 events.
[14:09:16.424] <TB1> INFO: 2560 events read in total (245ms).
[14:09:16.424] <TB1> INFO: Test took 1466ms.
[14:09:16.427] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:16.933] <TB1> INFO: Expecting 2560 events.
[14:09:17.894] <TB1> INFO: 2560 events read in total (246ms).
[14:09:17.894] <TB1> INFO: Test took 1467ms.
[14:09:17.897] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:18.403] <TB1> INFO: Expecting 2560 events.
[14:09:19.365] <TB1> INFO: 2560 events read in total (247ms).
[14:09:19.365] <TB1> INFO: Test took 1468ms.
[14:09:19.368] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:19.873] <TB1> INFO: Expecting 2560 events.
[14:09:20.832] <TB1> INFO: 2560 events read in total (244ms).
[14:09:20.833] <TB1> INFO: Test took 1465ms.
[14:09:20.835] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:21.342] <TB1> INFO: Expecting 2560 events.
[14:09:22.299] <TB1> INFO: 2560 events read in total (242ms).
[14:09:22.300] <TB1> INFO: Test took 1465ms.
[14:09:22.302] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:22.808] <TB1> INFO: Expecting 2560 events.
[14:09:23.769] <TB1> INFO: 2560 events read in total (245ms).
[14:09:23.770] <TB1> INFO: Test took 1468ms.
[14:09:23.773] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:24.279] <TB1> INFO: Expecting 2560 events.
[14:09:25.243] <TB1> INFO: 2560 events read in total (249ms).
[14:09:25.243] <TB1> INFO: Test took 1470ms.
[14:09:25.245] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:25.753] <TB1> INFO: Expecting 2560 events.
[14:09:26.717] <TB1> INFO: 2560 events read in total (249ms).
[14:09:26.718] <TB1> INFO: Test took 1473ms.
[14:09:26.721] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:27.227] <TB1> INFO: Expecting 2560 events.
[14:09:28.186] <TB1> INFO: 2560 events read in total (244ms).
[14:09:28.187] <TB1> INFO: Test took 1467ms.
[14:09:28.190] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:28.695] <TB1> INFO: Expecting 2560 events.
[14:09:29.658] <TB1> INFO: 2560 events read in total (248ms).
[14:09:29.658] <TB1> INFO: Test took 1469ms.
[14:09:29.661] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:30.166] <TB1> INFO: Expecting 2560 events.
[14:09:31.127] <TB1> INFO: 2560 events read in total (246ms).
[14:09:31.127] <TB1> INFO: Test took 1466ms.
[14:09:31.130] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:31.636] <TB1> INFO: Expecting 2560 events.
[14:09:32.600] <TB1> INFO: 2560 events read in total (249ms).
[14:09:32.600] <TB1> INFO: Test took 1470ms.
[14:09:32.603] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:33.109] <TB1> INFO: Expecting 2560 events.
[14:09:34.071] <TB1> INFO: 2560 events read in total (247ms).
[14:09:34.072] <TB1> INFO: Test took 1469ms.
[14:09:34.075] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:34.580] <TB1> INFO: Expecting 2560 events.
[14:09:35.541] <TB1> INFO: 2560 events read in total (246ms).
[14:09:35.542] <TB1> INFO: Test took 1468ms.
[14:09:35.544] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:36.051] <TB1> INFO: Expecting 2560 events.
[14:09:37.012] <TB1> INFO: 2560 events read in total (246ms).
[14:09:37.012] <TB1> INFO: Test took 1468ms.
[14:09:37.015] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:37.521] <TB1> INFO: Expecting 2560 events.
[14:09:38.483] <TB1> INFO: 2560 events read in total (248ms).
[14:09:38.483] <TB1> INFO: Test took 1468ms.
[14:09:38.486] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:38.992] <TB1> INFO: Expecting 2560 events.
[14:09:39.950] <TB1> INFO: 2560 events read in total (243ms).
[14:09:39.950] <TB1> INFO: Test took 1465ms.
[14:09:39.953] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:40.460] <TB1> INFO: Expecting 2560 events.
[14:09:41.421] <TB1> INFO: 2560 events read in total (246ms).
[14:09:41.422] <TB1> INFO: Test took 1470ms.
[14:09:41.424] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:41.931] <TB1> INFO: Expecting 2560 events.
[14:09:42.892] <TB1> INFO: 2560 events read in total (246ms).
[14:09:42.892] <TB1> INFO: Test took 1468ms.
[14:09:42.895] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:43.401] <TB1> INFO: Expecting 2560 events.
[14:09:44.363] <TB1> INFO: 2560 events read in total (247ms).
[14:09:44.364] <TB1> INFO: Test took 1469ms.
[14:09:44.366] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:44.873] <TB1> INFO: Expecting 2560 events.
[14:09:45.836] <TB1> INFO: 2560 events read in total (247ms).
[14:09:45.836] <TB1> INFO: Test took 1470ms.
[14:09:45.839] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:46.345] <TB1> INFO: Expecting 2560 events.
[14:09:47.307] <TB1> INFO: 2560 events read in total (246ms).
[14:09:47.307] <TB1> INFO: Test took 1468ms.
[14:09:47.310] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:47.816] <TB1> INFO: Expecting 2560 events.
[14:09:48.776] <TB1> INFO: 2560 events read in total (245ms).
[14:09:48.776] <TB1> INFO: Test took 1467ms.
[14:09:48.779] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:49.284] <TB1> INFO: Expecting 2560 events.
[14:09:50.247] <TB1> INFO: 2560 events read in total (248ms).
[14:09:50.247] <TB1> INFO: Test took 1469ms.
[14:09:50.251] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:50.757] <TB1> INFO: Expecting 2560 events.
[14:09:51.719] <TB1> INFO: 2560 events read in total (247ms).
[14:09:51.719] <TB1> INFO: Test took 1469ms.
[14:09:51.722] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:52.229] <TB1> INFO: Expecting 2560 events.
[14:09:53.192] <TB1> INFO: 2560 events read in total (248ms).
[14:09:53.192] <TB1> INFO: Test took 1470ms.
[14:09:53.195] <TB1> INFO: Not all ROCs have their pixels configured the same way. Running in FLAG_FORCE_SERIAL mode.
[14:09:53.702] <TB1> INFO: Expecting 2560 events.
[14:09:54.664] <TB1> INFO: 2560 events read in total (247ms).
[14:09:54.664] <TB1> INFO: Test took 1469ms.
[14:09:55.400] <TB1> INFO: PixTestPhOptimization::doTest() done, duration: 496 seconds
[14:09:55.400] <TB1> INFO: PH scale (per ROC): 68 70 77 81 65 67 75 71 71 70 70 75 74 75 69 62
[14:09:55.400] <TB1> INFO: PH offset (per ROC): 190 178 167 170 182 197 176 182 166 173 176 175 170 154 179 180
[14:09:55.405] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:55.405] <TB1> INFO: Decoding statistics:
[14:09:55.405] <TB1> INFO: General information:
[14:09:55.405] <TB1> INFO: 16bit words read: 66448
[14:09:55.405] <TB1> INFO: valid events total: 5120
[14:09:55.405] <TB1> INFO: empty events: 2616
[14:09:55.405] <TB1> INFO: valid events with pixels: 2504
[14:09:55.405] <TB1> INFO: valid pixel hits: 2504
[14:09:55.405] <TB1> INFO: Event errors: 0
[14:09:55.405] <TB1> INFO: start marker: 0
[14:09:55.405] <TB1> INFO: stop marker: 0
[14:09:55.405] <TB1> INFO: overflow: 0
[14:09:55.405] <TB1> INFO: invalid 5bit words: 0
[14:09:55.405] <TB1> INFO: invalid XOR eye diagram: 0
[14:09:55.405] <TB1> INFO: TBM errors: 0
[14:09:55.405] <TB1> INFO: flawed TBM headers: 0
[14:09:55.405] <TB1> INFO: flawed TBM trailers: 0
[14:09:55.405] <TB1> INFO: event ID mismatches: 0
[14:09:55.405] <TB1> INFO: ROC errors: 0
[14:09:55.405] <TB1> INFO: missing ROC header(s): 0
[14:09:55.405] <TB1> INFO: misplaced readback start: 0
[14:09:55.405] <TB1> INFO: Pixel decoding errors: 0
[14:09:55.405] <TB1> INFO: pixel data incomplete: 0
[14:09:55.405] <TB1> INFO: pixel address: 0
[14:09:55.405] <TB1> INFO: pulse height fill bit: 0
[14:09:55.405] <TB1> INFO: buffer corruption: 0
[14:09:55.568] <TB1> INFO: ######################################################################
[14:09:55.568] <TB1> INFO: PixTestGainPedestal::fullTest() ntrig = 10
[14:09:55.568] <TB1> INFO: ######################################################################
[14:09:55.577] <TB1> INFO: scanning low vcal = 10
[14:09:55.877] <TB1> INFO: Expecting 41600 events.
[14:09:59.654] <TB1> INFO: 41600 events read in total (3061ms).
[14:09:59.654] <TB1> INFO: Test took 4077ms.
[14:09:59.656] <TB1> INFO: scanning low vcal = 20
[14:10:00.162] <TB1> INFO: Expecting 41600 events.
[14:10:03.894] <TB1> INFO: 41600 events read in total (3017ms).
[14:10:03.895] <TB1> INFO: Test took 4239ms.
[14:10:03.897] <TB1> INFO: scanning low vcal = 30
[14:10:04.403] <TB1> INFO: Expecting 41600 events.
[14:10:08.215] <TB1> INFO: 41600 events read in total (3097ms).
[14:10:08.216] <TB1> INFO: Test took 4319ms.
[14:10:08.218] <TB1> INFO: scanning low vcal = 40
[14:10:08.709] <TB1> INFO: Expecting 41600 events.
[14:10:12.995] <TB1> INFO: 41600 events read in total (3571ms).
[14:10:12.996] <TB1> INFO: Test took 4778ms.
[14:10:12.999] <TB1> INFO: scanning low vcal = 50
[14:10:13.439] <TB1> INFO: Expecting 41600 events.
[14:10:17.815] <TB1> INFO: 41600 events read in total (3661ms).
[14:10:17.815] <TB1> INFO: Test took 4816ms.
[14:10:17.819] <TB1> INFO: scanning low vcal = 60
[14:10:18.259] <TB1> INFO: Expecting 41600 events.
[14:10:22.587] <TB1> INFO: 41600 events read in total (3613ms).
[14:10:22.587] <TB1> INFO: Test took 4768ms.
[14:10:22.590] <TB1> INFO: scanning low vcal = 70
[14:10:23.030] <TB1> INFO: Expecting 41600 events.
[14:10:27.326] <TB1> INFO: 41600 events read in total (3581ms).
[14:10:27.327] <TB1> INFO: Test took 4737ms.
[14:10:27.330] <TB1> INFO: scanning low vcal = 80
[14:10:27.779] <TB1> INFO: Expecting 41600 events.
[14:10:31.991] <TB1> INFO: 41600 events read in total (3497ms).
[14:10:31.992] <TB1> INFO: Test took 4662ms.
[14:10:31.994] <TB1> INFO: scanning low vcal = 90
[14:10:32.444] <TB1> INFO: Expecting 41600 events.
[14:10:36.620] <TB1> INFO: 41600 events read in total (3462ms).
[14:10:36.621] <TB1> INFO: Test took 4627ms.
[14:10:36.632] <TB1> INFO: scanning low vcal = 100
[14:10:37.074] <TB1> INFO: Expecting 41600 events.
[14:10:41.407] <TB1> INFO: 41600 events read in total (3618ms).
[14:10:41.408] <TB1> INFO: Test took 4776ms.
[14:10:41.411] <TB1> INFO: scanning low vcal = 110
[14:10:41.850] <TB1> INFO: Expecting 41600 events.
[14:10:46.104] <TB1> INFO: 41600 events read in total (3539ms).
[14:10:46.104] <TB1> INFO: Test took 4693ms.
[14:10:46.107] <TB1> INFO: scanning low vcal = 120
[14:10:46.541] <TB1> INFO: Expecting 41600 events.
[14:10:50.718] <TB1> INFO: 41600 events read in total (3462ms).
[14:10:50.719] <TB1> INFO: Test took 4612ms.
[14:10:50.722] <TB1> INFO: scanning low vcal = 130
[14:10:51.172] <TB1> INFO: Expecting 41600 events.
[14:10:55.346] <TB1> INFO: 41600 events read in total (3459ms).
[14:10:55.347] <TB1> INFO: Test took 4625ms.
[14:10:55.349] <TB1> INFO: scanning low vcal = 140
[14:10:55.800] <TB1> INFO: Expecting 41600 events.
[14:11:00.059] <TB1> INFO: 41600 events read in total (3544ms).
[14:11:00.060] <TB1> INFO: Test took 4711ms.
[14:11:00.063] <TB1> INFO: scanning low vcal = 150
[14:11:00.485] <TB1> INFO: Expecting 41600 events.
[14:11:04.793] <TB1> INFO: 41600 events read in total (3594ms).
[14:11:04.793] <TB1> INFO: Test took 4730ms.
[14:11:04.796] <TB1> INFO: scanning low vcal = 160
[14:11:05.243] <TB1> INFO: Expecting 41600 events.
[14:11:09.545] <TB1> INFO: 41600 events read in total (3587ms).
[14:11:09.545] <TB1> INFO: Test took 4749ms.
[14:11:09.548] <TB1> INFO: scanning low vcal = 170
[14:11:09.998] <TB1> INFO: Expecting 41600 events.
[14:11:14.310] <TB1> INFO: 41600 events read in total (3597ms).
[14:11:14.311] <TB1> INFO: Test took 4763ms.
[14:11:14.315] <TB1> INFO: scanning low vcal = 180
[14:11:14.749] <TB1> INFO: Expecting 41600 events.
[14:11:19.093] <TB1> INFO: 41600 events read in total (3629ms).
[14:11:19.094] <TB1> INFO: Test took 4779ms.
[14:11:19.097] <TB1> INFO: scanning low vcal = 190
[14:11:19.533] <TB1> INFO: Expecting 41600 events.
[14:11:23.932] <TB1> INFO: 41600 events read in total (3684ms).
[14:11:23.933] <TB1> INFO: Test took 4836ms.
[14:11:23.936] <TB1> INFO: scanning low vcal = 200
[14:11:24.382] <TB1> INFO: Expecting 41600 events.
[14:11:28.714] <TB1> INFO: 41600 events read in total (3617ms).
[14:11:28.715] <TB1> INFO: Test took 4779ms.
[14:11:28.718] <TB1> INFO: scanning low vcal = 210
[14:11:29.144] <TB1> INFO: Expecting 41600 events.
[14:11:33.504] <TB1> INFO: 41600 events read in total (3645ms).
[14:11:33.504] <TB1> INFO: Test took 4786ms.
[14:11:33.507] <TB1> INFO: scanning low vcal = 220
[14:11:33.922] <TB1> INFO: Expecting 41600 events.
[14:11:38.273] <TB1> INFO: 41600 events read in total (3636ms).
[14:11:38.273] <TB1> INFO: Test took 4766ms.
[14:11:38.276] <TB1> INFO: scanning low vcal = 230
[14:11:38.697] <TB1> INFO: Expecting 41600 events.
[14:11:43.024] <TB1> INFO: 41600 events read in total (3611ms).
[14:11:43.025] <TB1> INFO: Test took 4749ms.
[14:11:43.027] <TB1> INFO: scanning low vcal = 240
[14:11:43.475] <TB1> INFO: Expecting 41600 events.
[14:11:47.792] <TB1> INFO: 41600 events read in total (3602ms).
[14:11:47.793] <TB1> INFO: Test took 4766ms.
[14:11:47.796] <TB1> INFO: scanning low vcal = 250
[14:11:48.215] <TB1> INFO: Expecting 41600 events.
[14:11:52.605] <TB1> INFO: 41600 events read in total (3675ms).
[14:11:52.605] <TB1> INFO: Test took 4809ms.
[14:11:52.610] <TB1> INFO: scanning high vcal = 30 (= 210 in low range)
[14:11:53.017] <TB1> INFO: Expecting 41600 events.
[14:11:57.334] <TB1> INFO: 41600 events read in total (3602ms).
[14:11:57.334] <TB1> INFO: Test took 4724ms.
[14:11:57.337] <TB1> INFO: scanning high vcal = 50 (= 350 in low range)
[14:11:57.783] <TB1> INFO: Expecting 41600 events.
[14:12:02.112] <TB1> INFO: 41600 events read in total (3614ms).
[14:12:02.112] <TB1> INFO: Test took 4775ms.
[14:12:02.115] <TB1> INFO: scanning high vcal = 70 (= 490 in low range)
[14:12:02.562] <TB1> INFO: Expecting 41600 events.
[14:12:06.983] <TB1> INFO: 41600 events read in total (3706ms).
[14:12:06.984] <TB1> INFO: Test took 4869ms.
[14:12:06.987] <TB1> INFO: scanning high vcal = 90 (= 630 in low range)
[14:12:07.422] <TB1> INFO: Expecting 41600 events.
[14:12:11.804] <TB1> INFO: 41600 events read in total (3667ms).
[14:12:11.805] <TB1> INFO: Test took 4818ms.
[14:12:11.807] <TB1> INFO: scanning high vcal = 200 (= 1400 in low range)
[14:12:12.249] <TB1> INFO: Expecting 41600 events.
[14:12:16.599] <TB1> INFO: 41600 events read in total (3635ms).
[14:12:16.600] <TB1> INFO: Test took 4793ms.
[14:12:17.222] <TB1> INFO: PixTestGainPedestal::measure() done
[14:12:50.248] <TB1> INFO: PixTestGainPedestal::fit() done
[14:12:50.248] <TB1> INFO: non-linearity mean: 0.959 0.960 0.947 0.959 0.961 0.960 0.960 0.949 0.959 0.962 0.958 0.958 0.957 0.960 0.954 0.960
[14:12:50.248] <TB1> INFO: non-linearity RMS: 0.007 0.006 0.007 0.007 0.006 0.006 0.004 0.008 0.006 0.005 0.006 0.006 0.006 0.005 0.007 0.006
[14:12:50.248] <TB1> INFO: write gain/ped parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//phCalibrationFitErr35_C0.dat
[14:12:50.267] <TB1> INFO: write gain/ped parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//phCalibrationFitErr35_C1.dat
[14:12:50.285] <TB1> INFO: write gain/ped parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//phCalibrationFitErr35_C2.dat
[14:12:50.304] <TB1> INFO: write gain/ped parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//phCalibrationFitErr35_C3.dat
[14:12:50.322] <TB1> INFO: write gain/ped parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//phCalibrationFitErr35_C4.dat
[14:12:50.340] <TB1> INFO: write gain/ped parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//phCalibrationFitErr35_C5.dat
[14:12:50.358] <TB1> INFO: write gain/ped parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//phCalibrationFitErr35_C6.dat
[14:12:50.376] <TB1> INFO: write gain/ped parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//phCalibrationFitErr35_C7.dat
[14:12:50.394] <TB1> INFO: write gain/ped parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//phCalibrationFitErr35_C8.dat
[14:12:50.412] <TB1> INFO: write gain/ped parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//phCalibrationFitErr35_C9.dat
[14:12:50.431] <TB1> INFO: write gain/ped parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//phCalibrationFitErr35_C10.dat
[14:12:50.464] <TB1> INFO: write gain/ped parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//phCalibrationFitErr35_C11.dat
[14:12:50.496] <TB1> INFO: write gain/ped parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//phCalibrationFitErr35_C12.dat
[14:12:50.530] <TB1> INFO: write gain/ped parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//phCalibrationFitErr35_C13.dat
[14:12:50.553] <TB1> INFO: write gain/ped parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//phCalibrationFitErr35_C14.dat
[14:12:50.571] <TB1> INFO: write gain/ped parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//phCalibrationFitErr35_C15.dat
[14:12:50.589] <TB1> INFO: PixTestGainPedestal::fullTest() done, duration: 175 seconds
[14:12:50.589] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:50.589] <TB1> INFO: Decoding statistics:
[14:12:50.589] <TB1> INFO: General information:
[14:12:50.589] <TB1> INFO: 16bit words read: 2328990
[14:12:50.589] <TB1> INFO: valid events total: 83200
[14:12:50.589] <TB1> INFO: empty events: 0
[14:12:50.589] <TB1> INFO: valid events with pixels: 83200
[14:12:50.589] <TB1> INFO: valid pixel hits: 665295
[14:12:50.589] <TB1> INFO: Event errors: 0
[14:12:50.589] <TB1> INFO: start marker: 0
[14:12:50.589] <TB1> INFO: stop marker: 0
[14:12:50.589] <TB1> INFO: overflow: 0
[14:12:50.589] <TB1> INFO: invalid 5bit words: 0
[14:12:50.589] <TB1> INFO: invalid XOR eye diagram: 0
[14:12:50.589] <TB1> INFO: TBM errors: 0
[14:12:50.589] <TB1> INFO: flawed TBM headers: 0
[14:12:50.589] <TB1> INFO: flawed TBM trailers: 0
[14:12:50.589] <TB1> INFO: event ID mismatches: 0
[14:12:50.589] <TB1> INFO: ROC errors: 0
[14:12:50.589] <TB1> INFO: missing ROC header(s): 0
[14:12:50.589] <TB1> INFO: misplaced readback start: 0
[14:12:50.589] <TB1> INFO: Pixel decoding errors: 0
[14:12:50.589] <TB1> INFO: pixel data incomplete: 0
[14:12:50.589] <TB1> INFO: pixel address: 0
[14:12:50.589] <TB1> INFO: pulse height fill bit: 0
[14:12:50.589] <TB1> INFO: buffer corruption: 0
[14:12:50.595] <TB1> INFO: readReadbackCal: /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C0.dat .. /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C15.dat
[14:12:50.596] <TB1> INFO: ######################################################################
[14:12:50.596] <TB1> INFO: PixTestReadback::doTest()
[14:12:50.596] <TB1> INFO: ######################################################################
[14:12:50.597] <TB1> INFO: PixTestReadback::RES sent once
[14:13:01.829] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C0.dat
[14:13:01.829] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C1.dat
[14:13:01.829] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C2.dat
[14:13:01.830] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C3.dat
[14:13:01.830] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C4.dat
[14:13:01.830] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C5.dat
[14:13:01.830] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C6.dat
[14:13:01.830] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C7.dat
[14:13:01.830] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C8.dat
[14:13:01.830] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C9.dat
[14:13:01.830] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C10.dat
[14:13:01.830] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C11.dat
[14:13:01.831] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C12.dat
[14:13:01.831] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C13.dat
[14:13:01.831] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C14.dat
[14:13:01.831] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C15.dat
[14:13:01.863] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:13:01.863] <TB1> INFO: PixTestReadback::RES sent once
[14:13:13.044] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C0.dat
[14:13:13.044] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C1.dat
[14:13:13.044] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C2.dat
[14:13:13.044] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C3.dat
[14:13:13.044] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C4.dat
[14:13:13.044] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C5.dat
[14:13:13.044] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C6.dat
[14:13:13.044] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C7.dat
[14:13:13.044] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C8.dat
[14:13:13.044] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C9.dat
[14:13:13.044] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C10.dat
[14:13:13.044] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C11.dat
[14:13:13.044] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C12.dat
[14:13:13.044] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C13.dat
[14:13:13.045] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C14.dat
[14:13:13.045] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C15.dat
[14:13:13.080] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:13:13.081] <TB1> INFO: PixTestReadback::RES sent once
[14:13:21.666] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:13:21.666] <TB1> INFO: Vbg will be calibrated using Vd calibration
[14:13:21.666] <TB1> INFO: /*/*/*/*::: ROC 0: uncalibrated Vbg = 151.2calibrated Vbg = 1.24932 :::*/*/*/*/
[14:13:21.666] <TB1> INFO: /*/*/*/*::: ROC 1: uncalibrated Vbg = 152.7calibrated Vbg = 1.24717 :::*/*/*/*/
[14:13:21.666] <TB1> INFO: /*/*/*/*::: ROC 2: uncalibrated Vbg = 152.9calibrated Vbg = 1.25311 :::*/*/*/*/
[14:13:21.666] <TB1> INFO: /*/*/*/*::: ROC 3: uncalibrated Vbg = 150.1calibrated Vbg = 1.26206 :::*/*/*/*/
[14:13:21.666] <TB1> INFO: /*/*/*/*::: ROC 4: uncalibrated Vbg = 160.1calibrated Vbg = 1.26188 :::*/*/*/*/
[14:13:21.666] <TB1> INFO: /*/*/*/*::: ROC 5: uncalibrated Vbg = 161.9calibrated Vbg = 1.26487 :::*/*/*/*/
[14:13:21.666] <TB1> INFO: /*/*/*/*::: ROC 6: uncalibrated Vbg = 158.4calibrated Vbg = 1.26141 :::*/*/*/*/
[14:13:21.666] <TB1> INFO: /*/*/*/*::: ROC 7: uncalibrated Vbg = 163.3calibrated Vbg = 1.26085 :::*/*/*/*/
[14:13:21.666] <TB1> INFO: /*/*/*/*::: ROC 8: uncalibrated Vbg = 155.3calibrated Vbg = 1.26226 :::*/*/*/*/
[14:13:21.666] <TB1> INFO: /*/*/*/*::: ROC 9: uncalibrated Vbg = 160.3calibrated Vbg = 1.26103 :::*/*/*/*/
[14:13:21.666] <TB1> INFO: /*/*/*/*::: ROC 10: uncalibrated Vbg = 154.5calibrated Vbg = 1.25483 :::*/*/*/*/
[14:13:21.666] <TB1> INFO: /*/*/*/*::: ROC 11: uncalibrated Vbg = 154.6calibrated Vbg = 1.25027 :::*/*/*/*/
[14:13:21.666] <TB1> INFO: /*/*/*/*::: ROC 12: uncalibrated Vbg = 159.5calibrated Vbg = 1.2481 :::*/*/*/*/
[14:13:21.666] <TB1> INFO: /*/*/*/*::: ROC 13: uncalibrated Vbg = 150.6calibrated Vbg = 1.2519 :::*/*/*/*/
[14:13:21.667] <TB1> INFO: /*/*/*/*::: ROC 14: uncalibrated Vbg = 155.8calibrated Vbg = 1.25022 :::*/*/*/*/
[14:13:21.667] <TB1> INFO: /*/*/*/*::: ROC 15: uncalibrated Vbg = 155.2calibrated Vbg = 1.24749 :::*/*/*/*/
[14:13:21.670] <TB1> INFO: PixTestReadback::RES sent once
[14:16:15.578] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C0.dat
[14:16:15.579] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C1.dat
[14:16:15.579] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C2.dat
[14:16:15.579] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C3.dat
[14:16:15.579] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C4.dat
[14:16:15.579] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C5.dat
[14:16:15.579] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C6.dat
[14:16:15.579] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C7.dat
[14:16:15.579] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C8.dat
[14:16:15.579] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C9.dat
[14:16:15.579] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C10.dat
[14:16:15.579] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C11.dat
[14:16:15.579] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C12.dat
[14:16:15.579] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C13.dat
[14:16:15.580] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C14.dat
[14:16:15.580] <TB1> INFO: write readback calibration parameters into /usr/local/data/M3021_Fulltest17_2015-10-26_13h47m_1445863656//000_FulltestPxar_p17//readbackCal_C15.dat
[14:16:15.610] <TB1> INFO: PixTestPattern:: pg_setup set to default.
[14:16:15.610] <TB1> INFO: PixTestReadback::doTest() done
[14:16:15.610] <TB1> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:15.610] <TB1> INFO: Decoding statistics:
[14:16:15.610] <TB1> INFO: General information:
[14:16:15.610] <TB1> INFO: 16bit words read: 768
[14:16:15.610] <TB1> INFO: valid events total: 64
[14:16:15.610] <TB1> INFO: empty events: 64
[14:16:15.610] <TB1> INFO: valid events with pixels: 0
[14:16:15.610] <TB1> INFO: valid pixel hits: 0
[14:16:15.610] <TB1> INFO: Event errors: 0
[14:16:15.610] <TB1> INFO: start marker: 0
[14:16:15.610] <TB1> INFO: stop marker: 0
[14:16:15.610] <TB1> INFO: overflow: 0
[14:16:15.610] <TB1> INFO: invalid 5bit words: 0
[14:16:15.610] <TB1> INFO: invalid XOR eye diagram: 0
[14:16:15.610] <TB1> INFO: TBM errors: 0
[14:16:15.610] <TB1> INFO: flawed TBM headers: 0
[14:16:15.610] <TB1> INFO: flawed TBM trailers: 0
[14:16:15.610] <TB1> INFO: event ID mismatches: 0
[14:16:15.610] <TB1> INFO: ROC errors: 0
[14:16:15.610] <TB1> INFO: missing ROC header(s): 0
[14:16:15.610] <TB1> INFO: misplaced readback start: 0
[14:16:15.610] <TB1> INFO: Pixel decoding errors: 0
[14:16:15.610] <TB1> INFO: pixel data incomplete: 0
[14:16:15.610] <TB1> INFO: pixel address: 0
[14:16:15.610] <TB1> INFO: pulse height fill bit: 0
[14:16:15.610] <TB1> INFO: buffer corruption: 0
[14:16:15.622] <TB1> INFO: Decoding statistics:
[14:16:15.622] <TB1> INFO: General information:
[14:16:15.622] <TB1> INFO: 16bit words read: 2396206
[14:16:15.622] <TB1> INFO: valid events total: 88384
[14:16:15.622] <TB1> INFO: empty events: 2680
[14:16:15.622] <TB1> INFO: valid events with pixels: 85704
[14:16:15.622] <TB1> INFO: valid pixel hits: 667799
[14:16:15.622] <TB1> INFO: Event errors: 0
[14:16:15.622] <TB1> INFO: start marker: 0
[14:16:15.622] <TB1> INFO: stop marker: 0
[14:16:15.622] <TB1> INFO: overflow: 0
[14:16:15.623] <TB1> INFO: invalid 5bit words: 0
[14:16:15.623] <TB1> INFO: invalid XOR eye diagram: 0
[14:16:15.623] <TB1> INFO: TBM errors: 0
[14:16:15.623] <TB1> INFO: flawed TBM headers: 0
[14:16:15.623] <TB1> INFO: flawed TBM trailers: 0
[14:16:15.623] <TB1> INFO: event ID mismatches: 0
[14:16:15.623] <TB1> INFO: ROC errors: 0
[14:16:15.623] <TB1> INFO: missing ROC header(s): 0
[14:16:15.623] <TB1> INFO: misplaced readback start: 0
[14:16:15.623] <TB1> INFO: Pixel decoding errors: 0
[14:16:15.623] <TB1> INFO: pixel data incomplete: 0
[14:16:15.623] <TB1> INFO: pixel address: 0
[14:16:15.623] <TB1> INFO: pulse height fill bit: 0
[14:16:15.623] <TB1> INFO: buffer corruption: 0
[14:16:15.623] <TB1> INFO: enter test to run
[14:16:15.623] <TB1> INFO: test: exit no parameter change
[14:16:15.792] <TB1> QUIET: Connection to board 147 closed.
[14:16:16.708] <TB1> INFO: pXar: this is the end, my friend