[08:33:06.885] <TB0> INFO: *** Welcome to pxar ***
[08:33:06.885] <TB0> INFO: *** Today: 2015/08/28
[08:33:06.885] <TB0> INFO: readRocDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//dacParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//dacParameters35_C15.dat
[08:33:06.885] <TB0> INFO: readTbmDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//tbmParameters_C0a.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//tbmParameters_C0b.dat
[08:33:06.885] <TB0> INFO: readMaskFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//defaultMaskFile.dat
[08:33:06.885] <TB0> INFO: readTrimFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//trimParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//000_PixelAlive_p17//trimParameters35_C15.dat
[08:33:06.951] <TB0> INFO: clk: 4
[08:33:06.951] <TB0> INFO: ctr: 4
[08:33:06.951] <TB0> INFO: sda: 19
[08:33:06.951] <TB0> INFO: tin: 9
[08:33:06.951] <TB0> INFO: level: 15
[08:33:06.951] <TB0> INFO: triggerdelay: 0
[08:33:06.951] <TB0> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[08:33:06.951] <TB0> INFO: Log level: INFO
[08:33:06.965] <TB0> INFO: Found DTB DTB_WWXTQT
[08:33:06.973] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[08:33:06.976] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[08:33:06.979] <TB0> INFO: RPC call hashes of host and DTB match: 397073690
[08:33:08.493] <TB0> INFO: DUT info:
[08:33:08.493] <TB0> INFO: The DUT currently contains the following objects:
[08:33:08.493] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[08:33:08.493] <TB0> INFO: TBM Core alpha (0): 7 registers set
[08:33:08.493] <TB0> INFO: TBM Core beta (1): 7 registers set
[08:33:08.493] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:33:08.493] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:08.493] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:08.493] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:08.493] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:08.493] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:08.494] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:08.494] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:08.494] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:08.494] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:08.494] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:08.494] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:08.494] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:08.494] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:08.494] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:08.494] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:08.494] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:33:08.894] <TB0> INFO: enter 'restricted' command line mode
[08:33:08.894] <TB0> INFO: enter test to run
[08:33:08.894] <TB0> INFO: test: PixelAlive no parameter change
[08:33:08.894] <TB0> INFO: running: pixelalive
[08:33:08.898] <TB0> INFO: ######################################################################
[08:33:08.898] <TB0> INFO: PixTestAlive::doTest()
[08:33:08.898] <TB0> INFO: ######################################################################
[08:33:08.900] <TB0> INFO: ----------------------------------------------------------------------
[08:33:08.900] <TB0> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:33:08.900] <TB0> INFO: ----------------------------------------------------------------------
[08:33:09.206] <TB0> INFO: Expecting 41600 events.
[08:33:13.589] <TB0> INFO: 41600 events read in total (3664ms).
[08:33:13.669] <TB0> INFO: Test took 4768ms.
[08:33:13.675] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:13.928] <TB0> INFO: PixTestAlive::aliveTest() done
[08:33:13.928] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 2 1 0 0 0 0 0 0 3 0 1 0 3
[08:33:13.930] <TB0> INFO: ----------------------------------------------------------------------
[08:33:13.930] <TB0> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:33:13.930] <TB0> INFO: ----------------------------------------------------------------------
[08:33:14.234] <TB0> INFO: Expecting 41600 events.
[08:33:17.474] <TB0> INFO: 41600 events read in total (2524ms).
[08:33:17.474] <TB0> INFO: Test took 3542ms.
[08:33:17.474] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:17.475] <TB0> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[08:33:17.790] <TB0> INFO: PixTestAlive::maskTest() done
[08:33:17.790] <TB0> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:33:17.791] <TB0> INFO: ----------------------------------------------------------------------
[08:33:17.791] <TB0> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:33:17.791] <TB0> INFO: ----------------------------------------------------------------------
[08:33:18.101] <TB0> INFO: Expecting 41600 events.
[08:33:22.950] <TB0> INFO: 41600 events read in total (4134ms).
[08:33:22.951] <TB0> INFO: Test took 5158ms.
[08:33:22.960] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:23.247] <TB0> INFO: PixTestAlive::addressDecodingTest() done
[08:33:23.247] <TB0> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[08:33:23.247] <TB0> INFO: PixTestAlive::doTest() done, duration: 14 seconds
[08:33:23.305] <TB0> INFO: enter test to run
[08:33:23.305] <TB0> INFO: test: no parameter change
[08:33:23.472] <TB0> QUIET: Connection to board 147 closed.
[08:33:23.552] <TB0> INFO: pXar: this is the end, my friend
commander_RetrimHotPixels.log
[08:38:40.965] <TB0> INFO: *** Welcome to pxar ***
[08:38:40.965] <TB0> INFO: *** Today: 2015/08/28
[08:38:40.965] <TB0> INFO: readRocDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//dacParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//dacParameters35_C15.dat
[08:38:40.966] <TB0> INFO: readTbmDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//tbmParameters_C0a.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//tbmParameters_C0b.dat
[08:38:40.966] <TB0> INFO: readMaskFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//defaultMaskFile.dat
[08:38:40.966] <TB0> INFO: readTrimFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C15.dat
[08:38:41.031] <TB0> INFO: clk: 4
[08:38:41.031] <TB0> INFO: ctr: 4
[08:38:41.031] <TB0> INFO: sda: 19
[08:38:41.031] <TB0> INFO: tin: 9
[08:38:41.031] <TB0> INFO: level: 15
[08:38:41.031] <TB0> INFO: triggerdelay: 0
[08:38:41.031] <TB0> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[08:38:41.031] <TB0> INFO: Log level: INFO
[08:38:41.044] <TB0> INFO: Found DTB DTB_WWXTQT
[08:38:41.053] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[08:38:41.056] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[08:38:41.058] <TB0> INFO: RPC call hashes of host and DTB match: 397073690
[08:38:42.569] <TB0> INFO: DUT info:
[08:38:42.569] <TB0> INFO: The DUT currently contains the following objects:
[08:38:42.569] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[08:38:42.569] <TB0> INFO: TBM Core alpha (0): 7 registers set
[08:38:42.569] <TB0> INFO: TBM Core beta (1): 7 registers set
[08:38:42.569] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:38:42.569] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:42.569] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:42.569] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:42.569] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:42.569] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:42.569] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:42.569] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:42.569] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:42.569] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:42.569] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:42.569] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:42.569] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:42.569] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:42.569] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:42.569] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:42.569] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:38:42.970] <TB0> INFO: enter 'restricted' command line mode
[08:38:42.970] <TB0> INFO: enter test to run
[08:38:42.970] <TB0> INFO: test: delay setting parameters: ->5<-
[08:38:42.970] <TB0> INFO: delay test by 5 seconds...
[08:38:47.970] <TB0> INFO: enter test to run
[08:38:47.970] <TB0> INFO: test: highrate no parameter change
[08:38:47.970] <TB0> INFO: running: highrate
[08:38:47.971] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//phCalibrationFitErr35_C15.dat
[08:38:48.101] <TB0> INFO: ----------------------------------------------------------------------
[08:38:48.101] <TB0> INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[08:38:48.101] <TB0> INFO: ----------------------------------------------------------------------
[08:38:48.101] <TB0> INFO: THR = 150, corresponding to ~ 400 MHz/cm2
[08:38:48.101] <TB0> INFO: edge/corner pixel THR is adjusted
[08:38:48.101] <TB0> INFO: PixTestHighRate::trimHotPixels: step 0...
[08:38:49.061] <TB0> INFO: Collecting data for 1 seconds...
[08:38:50.063] <TB0> INFO: Done with hot pixel readout
[08:38:56.025] <TB0> INFO: PixTest:: pg_setup set to default.
[08:38:56.026] <TB0> INFO: 9 hot pixels found in step 0
[08:38:56.995] <TB0> INFO: Collecting data for 1 seconds...
[08:38:57.997] <TB0> INFO: Done with hot pixel readout
[08:39:04.705] <TB0> INFO: PixTest:: pg_setup set to default.
[08:39:04.705] <TB0> INFO: 7 hot pixels found in step 1
[08:39:05.670] <TB0> INFO: Collecting data for 1 seconds...
[08:39:06.672] <TB0> INFO: Done with hot pixel readout
[08:39:12.645] <TB0> INFO: PixTest:: pg_setup set to default.
[08:39:12.646] <TB0> INFO: 3 hot pixels found in step 2
[08:39:13.611] <TB0> INFO: Collecting data for 1 seconds...
[08:39:14.613] <TB0> INFO: Done with hot pixel readout
[08:39:21.036] <TB0> INFO: PixTest:: pg_setup set to default.
[08:39:21.037] <TB0> INFO: 0 hot pixels found in step 3
[08:39:21.045] <TB0> INFO: 0 hot pixels could not be trimmed and have been masked.
[08:39:21.077] <TB0> INFO: PixTest::trimHotPixels() done
[08:39:21.078] <TB0> INFO: write trim parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C0.dat
[08:39:21.092] <TB0> INFO: write trim parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C1.dat
[08:39:21.099] <TB0> INFO: write trim parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C2.dat
[08:39:21.108] <TB0> INFO: write trim parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C3.dat
[08:39:21.115] <TB0> INFO: write trim parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C4.dat
[08:39:21.122] <TB0> INFO: write trim parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C5.dat
[08:39:21.128] <TB0> INFO: write trim parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C6.dat
[08:39:21.135] <TB0> INFO: write trim parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C7.dat
[08:39:21.142] <TB0> INFO: write trim parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C8.dat
[08:39:21.149] <TB0> INFO: write trim parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C9.dat
[08:39:21.155] <TB0> INFO: write trim parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C10.dat
[08:39:21.161] <TB0> INFO: write trim parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C11.dat
[08:39:21.168] <TB0> INFO: write trim parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C12.dat
[08:39:21.175] <TB0> INFO: write trim parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C13.dat
[08:39:21.181] <TB0> INFO: write trim parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C14.dat
[08:39:21.188] <TB0> INFO: write trim parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//trimParameters35_C15.dat
[08:39:21.195] <TB0> INFO: write masked pixels into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//002_RetrimHotPixels_150//defaultMaskFile.dat
[08:39:21.230] <TB0> INFO: enter test to run
[08:39:21.230] <TB0> INFO: test: no parameter change
[08:39:21.414] <TB0> QUIET: Connection to board 147 closed.
[08:39:21.494] <TB0> INFO: pXar: this is the end, my friend
[08:40:13.166] <TB0> INFO: *** Welcome to pxar ***
[08:40:13.166] <TB0> INFO: *** Today: 2015/08/28
[08:40:13.166] <TB0> INFO: readRocDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//dacParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//dacParameters35_C15.dat
[08:40:13.167] <TB0> INFO: readTbmDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//tbmParameters_C0a.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//tbmParameters_C0b.dat
[08:40:13.167] <TB0> INFO: readMaskFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//defaultMaskFile.dat
[08:40:13.167] <TB0> INFO: readTrimFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//trimParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//trimParameters35_C15.dat
[08:40:13.237] <TB0> INFO: clk: 4
[08:40:13.237] <TB0> INFO: ctr: 4
[08:40:13.237] <TB0> INFO: sda: 19
[08:40:13.237] <TB0> INFO: tin: 9
[08:40:13.237] <TB0> INFO: level: 15
[08:40:13.237] <TB0> INFO: triggerdelay: 0
[08:40:13.237] <TB0> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[08:40:13.237] <TB0> INFO: Log level: INFO
[08:40:13.249] <TB0> INFO: Found DTB DTB_WWXTQT
[08:40:13.258] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[08:40:13.261] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[08:40:13.263] <TB0> INFO: RPC call hashes of host and DTB match: 397073690
[08:40:14.779] <TB0> INFO: DUT info:
[08:40:14.779] <TB0> INFO: The DUT currently contains the following objects:
[08:40:14.779] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[08:40:14.779] <TB0> INFO: TBM Core alpha (0): 7 registers set
[08:40:14.779] <TB0> INFO: TBM Core beta (1): 7 registers set
[08:40:14.779] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:40:14.779] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:14.779] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:14.779] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:14.779] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:14.779] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:14.779] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:14.779] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:14.780] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:14.780] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:14.780] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:14.780] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:14.780] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:14.780] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:14.780] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:14.780] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:14.780] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:40:15.180] <TB0> INFO: enter 'restricted' command line mode
[08:40:15.180] <TB0> INFO: enter test to run
[08:40:15.180] <TB0> INFO: test: delay setting parameters: ->5<-
[08:40:15.180] <TB0> INFO: delay test by 5 seconds...
[08:40:20.181] <TB0> INFO: enter test to run
[08:40:20.181] <TB0> INFO: test: Xray no parameter change
[08:40:20.181] <TB0> INFO: running: xray
[08:40:20.181] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//003_HRData_50//phCalibrationFitErr35_C15.dat
[08:40:20.308] <TB0> INFO: ######################################################################
[08:40:20.309] <TB0> INFO: PixTestXray::doTest()
[08:40:20.309] <TB0> INFO: ######################################################################
[08:40:20.309] <TB0> INFO: ----------------------------------------------------------------------
[08:40:20.309] <TB0> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:40:20.309] <TB0> INFO: ----------------------------------------------------------------------
[08:40:21.276] <TB0> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:40:28.050] <TB0> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[08:40:56.327] <TB0> INFO: Resuming triggers.
[08:41:03.099] <TB0> INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[08:41:31.246] <TB0> INFO: Resuming triggers.
[08:41:38.022] <TB0> INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[08:42:06.431] <TB0> INFO: Resuming triggers.
[08:42:13.209] <TB0> INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[08:42:41.479] <TB0> INFO: Resuming triggers.
[08:42:48.255] <TB0> INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[08:43:17.258] <TB0> INFO: Resuming triggers.
[08:43:24.034] <TB0> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[08:43:52.432] <TB0> INFO: Resuming triggers.
[08:43:59.214] <TB0> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[08:44:27.950] <TB0> INFO: Resuming triggers.
[08:44:34.725] <TB0> INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[08:45:04.583] <TB0> INFO: Resuming triggers.
[08:45:11.357] <TB0> INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[08:45:40.439] <TB0> INFO: Resuming triggers.
[08:45:47.212] <TB0> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[08:46:16.834] <TB0> INFO: Resuming triggers.
[08:46:23.608] <TB0> INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[08:46:54.177] <TB0> INFO: Resuming triggers.
[08:47:00.947] <TB0> INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[08:47:29.541] <TB0> INFO: Resuming triggers.
[08:47:36.310] <TB0> INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[08:48:05.155] <TB0> INFO: Resuming triggers.
[08:48:11.923] <TB0> INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[08:48:41.549] <TB0> INFO: Resuming triggers.
[08:48:46.840] <TB0> INFO: data taking finished, elapsed time: 100 seconds.
[08:49:10.347] <TB0> INFO: PixTest:: pg_setup set to default.
[08:49:10.349] <TB0> INFO: PixTestXray::doPhRun() done
[08:49:10.349] <TB0> INFO: PixTestXray::doTest() done
[08:49:10.550] <TB0> INFO: enter test to run
[08:49:10.550] <TB0> INFO: test: no parameter change
[08:49:10.763] <TB0> QUIET: Connection to board 147 closed.
[08:49:10.845] <TB0> INFO: pXar: this is the end, my friend
[08:50:09.664] <TB0> INFO: *** Welcome to pxar ***
[08:50:09.664] <TB0> INFO: *** Today: 2015/08/28
[08:50:09.664] <TB0> INFO: readRocDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//dacParameters35_C15.dat
[08:50:09.664] <TB0> INFO: readTbmDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//tbmParameters_C0b.dat
[08:50:09.665] <TB0> INFO: readMaskFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//defaultMaskFile.dat
[08:50:09.665] <TB0> INFO: readTrimFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//trimParameters35_C15.dat
[08:50:09.730] <TB0> INFO: clk: 4
[08:50:09.730] <TB0> INFO: ctr: 4
[08:50:09.730] <TB0> INFO: sda: 19
[08:50:09.730] <TB0> INFO: tin: 9
[08:50:09.730] <TB0> INFO: level: 15
[08:50:09.730] <TB0> INFO: triggerdelay: 0
[08:50:09.730] <TB0> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[08:50:09.730] <TB0> INFO: Log level: INFO
[08:50:09.745] <TB0> INFO: Found DTB DTB_WWXTQT
[08:50:09.753] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[08:50:09.757] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[08:50:09.759] <TB0> INFO: RPC call hashes of host and DTB match: 397073690
[08:50:11.269] <TB0> INFO: DUT info:
[08:50:11.269] <TB0> INFO: The DUT currently contains the following objects:
[08:50:11.269] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[08:50:11.269] <TB0> INFO: TBM Core alpha (0): 7 registers set
[08:50:11.269] <TB0> INFO: TBM Core beta (1): 7 registers set
[08:50:11.269] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:50:11.269] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:11.269] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:11.269] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:11.269] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:11.269] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:11.269] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:11.269] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:11.269] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:11.269] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:11.269] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:11.269] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:11.269] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:11.269] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:11.269] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:11.269] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:11.269] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:50:11.670] <TB0> INFO: enter 'restricted' command line mode
[08:50:11.670] <TB0> INFO: enter test to run
[08:50:11.670] <TB0> INFO: test: delay setting parameters: ->5<-
[08:50:11.670] <TB0> INFO: delay test by 5 seconds...
[08:50:16.670] <TB0> INFO: enter test to run
[08:50:16.670] <TB0> INFO: test: Xray no parameter change
[08:50:16.670] <TB0> INFO: running: xray
[08:50:16.670] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//004_HRData_150//phCalibrationFitErr35_C15.dat
[08:50:16.814] <TB0> INFO: ######################################################################
[08:50:16.814] <TB0> INFO: PixTestXray::doTest()
[08:50:16.814] <TB0> INFO: ######################################################################
[08:50:16.814] <TB0> INFO: ----------------------------------------------------------------------
[08:50:16.814] <TB0> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:50:16.814] <TB0> INFO: ----------------------------------------------------------------------
[08:50:17.781] <TB0> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:50:21.543] <TB0> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[08:50:51.536] <TB0> INFO: Resuming triggers.
[08:50:55.306] <TB0> INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[08:51:24.492] <TB0> INFO: Resuming triggers.
[08:51:28.258] <TB0> INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[08:51:58.252] <TB0> INFO: Resuming triggers.
[08:52:02.022] <TB0> INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[08:52:31.424] <TB0> INFO: Resuming triggers.
[08:52:35.194] <TB0> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[08:53:05.496] <TB0> INFO: Resuming triggers.
[08:53:09.266] <TB0> INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[08:53:39.809] <TB0> INFO: Resuming triggers.
[08:53:43.576] <TB0> INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[08:54:15.026] <TB0> INFO: Resuming triggers.
[08:54:18.797] <TB0> INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[08:54:49.162] <TB0> INFO: Resuming triggers.
[08:54:52.929] <TB0> INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[08:55:23.000] <TB0> INFO: Resuming triggers.
[08:55:26.766] <TB0> INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[08:55:57.151] <TB0> INFO: Resuming triggers.
[08:56:00.918] <TB0> INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[08:56:32.248] <TB0> INFO: Resuming triggers.
[08:56:36.014] <TB0> INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[08:57:06.672] <TB0> INFO: Resuming triggers.
[08:57:10.438] <TB0> INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[08:57:42.378] <TB0> INFO: Resuming triggers.
[08:57:46.144] <TB0> INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[08:58:17.659] <TB0> INFO: Resuming triggers.
[08:58:21.424] <TB0> INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[08:58:51.963] <TB0> INFO: Resuming triggers.
[08:58:55.732] <TB0> INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[08:59:26.517] <TB0> INFO: Resuming triggers.
[08:59:30.279] <TB0> INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[09:00:01.541] <TB0> INFO: Resuming triggers.
[09:00:05.305] <TB0> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[09:00:35.121] <TB0> INFO: Resuming triggers.
[09:00:38.885] <TB0> INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[09:01:09.130] <TB0> INFO: Resuming triggers.
[09:01:12.891] <TB0> INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[09:01:42.255] <TB0> INFO: Resuming triggers.
[09:01:46.017] <TB0> INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[09:02:16.938] <TB0> INFO: Resuming triggers.
[09:02:20.704] <TB0> INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[09:02:50.656] <TB0> INFO: Resuming triggers.
[09:02:54.424] <TB0> INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[09:03:25.094] <TB0> INFO: Resuming triggers.
[09:03:28.861] <TB0> INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[09:03:59.657] <TB0> INFO: Resuming triggers.
[09:04:03.420] <TB0> INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[09:04:34.031] <TB0> INFO: Resuming triggers.
[09:04:37.794] <TB0> INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[09:05:08.546] <TB0> INFO: Resuming triggers.
[09:05:10.747] <TB0> INFO: data taking finished, elapsed time: 100 seconds.
[09:05:29.768] <TB0> INFO: PixTest:: pg_setup set to default.
[09:05:29.769] <TB0> INFO: PixTestXray::doPhRun() done
[09:05:29.769] <TB0> INFO: PixTestXray::doTest() done
[09:05:29.958] <TB0> INFO: enter test to run
[09:05:29.958] <TB0> INFO: test: no parameter change
[09:05:30.181] <TB0> QUIET: Connection to board 147 closed.
[09:05:30.267] <TB0> INFO: pXar: this is the end, my friend
[09:07:56.165] <TB0> INFO: *** Welcome to pxar ***
[09:07:56.165] <TB0> INFO: *** Today: 2015/08/28
[09:07:56.165] <TB0> INFO: readRocDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//dacParameters35_C15.dat
[09:07:56.165] <TB0> INFO: readTbmDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//tbmParameters_C0b.dat
[09:07:56.165] <TB0> INFO: readMaskFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//defaultMaskFile.dat
[09:07:56.165] <TB0> INFO: readTrimFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//trimParameters35_C15.dat
[09:07:56.230] <TB0> INFO: clk: 4
[09:07:56.230] <TB0> INFO: ctr: 4
[09:07:56.230] <TB0> INFO: sda: 19
[09:07:56.230] <TB0> INFO: tin: 9
[09:07:56.230] <TB0> INFO: level: 15
[09:07:56.230] <TB0> INFO: triggerdelay: 0
[09:07:56.230] <TB0> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[09:07:56.230] <TB0> INFO: Log level: INFO
[09:07:56.244] <TB0> INFO: Found DTB DTB_WWXTQT
[09:07:56.252] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[09:07:56.256] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[09:07:56.258] <TB0> INFO: RPC call hashes of host and DTB match: 397073690
[09:07:57.774] <TB0> INFO: DUT info:
[09:07:57.774] <TB0> INFO: The DUT currently contains the following objects:
[09:07:57.774] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[09:07:57.774] <TB0> INFO: TBM Core alpha (0): 7 registers set
[09:07:57.774] <TB0> INFO: TBM Core beta (1): 7 registers set
[09:07:57.774] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:07:57.774] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:07:57.774] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:07:57.774] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:07:57.774] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:07:57.774] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:07:57.774] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:07:57.774] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:07:57.774] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:07:57.774] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:07:57.774] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:07:57.774] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:07:57.774] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:07:57.774] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:07:57.774] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:07:57.774] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:07:57.774] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:07:58.175] <TB0> INFO: enter 'restricted' command line mode
[09:07:58.175] <TB0> INFO: enter test to run
[09:07:58.175] <TB0> INFO: test: Highrate no parameter change
[09:07:58.175] <TB0> INFO: running: highrate
[09:07:58.175] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[09:07:58.304] <TB0> INFO: ----------------------------------------------------------------------
[09:07:58.304] <TB0> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[09:07:58.304] <TB0> INFO: ----------------------------------------------------------------------
[09:07:58.310] <TB0> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 0 .. 100 (1/-1) hits flags = 384 (plus default)
[09:07:58.321] <TB0> INFO: dacScan step from 0 .. 0
[09:07:58.321] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:07:58.321] <TB0> INFO: run 1 of 1
[09:07:58.910] <TB0> INFO: Expecting 208000 events.
[09:08:11.665] <TB0> INFO: 208000 events read in total (12225ms).
[09:08:11.674] <TB0> INFO: Test took 13352ms.
[09:08:11.823] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:08:12.053] <TB0> INFO: dacScan step from 1 .. 1
[09:08:12.053] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:08:12.053] <TB0> INFO: run 1 of 1
[09:08:12.651] <TB0> INFO: Expecting 208000 events.
[09:08:28.307] <TB0> INFO: 208000 events read in total (15129ms).
[09:08:28.316] <TB0> INFO: Test took 16262ms.
[09:08:28.494] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:08:28.712] <TB0> INFO: dacScan step from 2 .. 2
[09:08:28.712] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:08:28.712] <TB0> INFO: run 1 of 1
[09:08:29.307] <TB0> INFO: Expecting 208000 events.
[09:08:45.678] <TB0> INFO: 208000 events read in total (15844ms).
[09:08:45.686] <TB0> INFO: Test took 16973ms.
[09:08:45.765] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:08:46.000] <TB0> INFO: dacScan step from 3 .. 3
[09:08:46.000] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:08:46.000] <TB0> INFO: run 1 of 1
[09:08:46.598] <TB0> INFO: Expecting 208000 events.
[09:09:02.092] <TB0> INFO: 208000 events read in total (14966ms).
[09:09:02.101] <TB0> INFO: Test took 16100ms.
[09:09:02.178] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:02.417] <TB0> INFO: dacScan step from 4 .. 4
[09:09:02.417] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:09:02.417] <TB0> INFO: run 1 of 1
[09:09:03.023] <TB0> INFO: Expecting 208000 events.
[09:09:18.151] <TB0> INFO: 208000 events read in total (14599ms).
[09:09:18.159] <TB0> INFO: Test took 15742ms.
[09:09:18.245] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:18.470] <TB0> INFO: dacScan step from 5 .. 5
[09:09:18.470] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:09:18.470] <TB0> INFO: run 1 of 1
[09:09:19.071] <TB0> INFO: Expecting 208000 events.
[09:09:33.290] <TB0> INFO: 208000 events read in total (13692ms).
[09:09:33.297] <TB0> INFO: Test took 14827ms.
[09:09:33.448] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:33.687] <TB0> INFO: dacScan step from 6 .. 6
[09:09:33.687] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:09:33.687] <TB0> INFO: run 1 of 1
[09:09:34.337] <TB0> INFO: Expecting 208000 events.
[09:09:48.415] <TB0> INFO: 208000 events read in total (13551ms).
[09:09:48.423] <TB0> INFO: Test took 14736ms.
[09:09:48.504] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:09:48.756] <TB0> INFO: dacScan step from 7 .. 7
[09:09:48.756] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:09:48.756] <TB0> INFO: run 1 of 1
[09:09:49.372] <TB0> INFO: Expecting 208000 events.
[09:10:04.496] <TB0> INFO: 208000 events read in total (14597ms).
[09:10:04.503] <TB0> INFO: Test took 15746ms.
[09:10:04.585] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:04.821] <TB0> INFO: dacScan step from 8 .. 8
[09:10:04.821] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:10:04.821] <TB0> INFO: run 1 of 1
[09:10:05.437] <TB0> INFO: Expecting 208000 events.
[09:10:21.822] <TB0> INFO: 208000 events read in total (15857ms).
[09:10:21.830] <TB0> INFO: Test took 17008ms.
[09:10:21.909] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:22.178] <TB0> INFO: dacScan step from 9 .. 9
[09:10:22.178] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:10:22.178] <TB0> INFO: run 1 of 1
[09:10:22.778] <TB0> INFO: Expecting 208000 events.
[09:10:39.234] <TB0> INFO: 208000 events read in total (15929ms).
[09:10:39.242] <TB0> INFO: Test took 17064ms.
[09:10:39.325] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:39.567] <TB0> INFO: dacScan step from 10 .. 10
[09:10:39.567] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:10:39.567] <TB0> INFO: run 1 of 1
[09:10:40.164] <TB0> INFO: Expecting 208000 events.
[09:10:55.628] <TB0> INFO: 208000 events read in total (14937ms).
[09:10:55.636] <TB0> INFO: Test took 16069ms.
[09:10:55.777] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:10:56.008] <TB0> INFO: dacScan step from 11 .. 11
[09:10:56.009] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:10:56.009] <TB0> INFO: run 1 of 1
[09:10:56.609] <TB0> INFO: Expecting 208000 events.
[09:11:11.613] <TB0> INFO: 208000 events read in total (14477ms).
[09:11:11.623] <TB0> INFO: Test took 15614ms.
[09:11:11.704] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:11.931] <TB0> INFO: dacScan step from 12 .. 12
[09:11:11.931] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:11:11.931] <TB0> INFO: run 1 of 1
[09:11:12.543] <TB0> INFO: Expecting 208000 events.
[09:11:25.744] <TB0> INFO: 208000 events read in total (12674ms).
[09:11:25.752] <TB0> INFO: Test took 13820ms.
[09:11:25.837] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:26.081] <TB0> INFO: dacScan step from 13 .. 13
[09:11:26.081] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:11:26.081] <TB0> INFO: run 1 of 1
[09:11:26.687] <TB0> INFO: Expecting 208000 events.
[09:11:41.045] <TB0> INFO: 208000 events read in total (13830ms).
[09:11:41.053] <TB0> INFO: Test took 14972ms.
[09:11:41.139] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:41.405] <TB0> INFO: dacScan step from 14 .. 14
[09:11:41.405] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:11:41.405] <TB0> INFO: run 1 of 1
[09:11:42.016] <TB0> INFO: Expecting 208000 events.
[09:11:57.143] <TB0> INFO: 208000 events read in total (14600ms).
[09:11:57.153] <TB0> INFO: Test took 15748ms.
[09:11:57.240] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:11:57.470] <TB0> INFO: dacScan step from 15 .. 15
[09:11:57.470] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:11:57.470] <TB0> INFO: run 1 of 1
[09:11:58.064] <TB0> INFO: Expecting 208000 events.
[09:12:14.650] <TB0> INFO: 208000 events read in total (16059ms).
[09:12:14.660] <TB0> INFO: Test took 17189ms.
[09:12:14.869] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:12:15.120] <TB0> INFO: dacScan step from 16 .. 16
[09:12:15.120] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:12:15.120] <TB0> INFO: run 1 of 1
[09:12:15.725] <TB0> INFO: Expecting 208000 events.
[09:12:31.465] <TB0> INFO: 208000 events read in total (15213ms).
[09:12:31.474] <TB0> INFO: Test took 16354ms.
[09:12:31.555] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:12:31.809] <TB0> INFO: dacScan step from 17 .. 17
[09:12:31.809] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:12:31.809] <TB0> INFO: run 1 of 1
[09:12:32.422] <TB0> INFO: Expecting 208000 events.
[09:12:47.283] <TB0> INFO: 208000 events read in total (14334ms).
[09:12:47.292] <TB0> INFO: Test took 15482ms.
[09:12:47.375] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:12:47.617] <TB0> INFO: dacScan step from 18 .. 18
[09:12:47.617] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:12:47.617] <TB0> INFO: run 1 of 1
[09:12:48.215] <TB0> INFO: Expecting 208000 events.
[09:13:01.992] <TB0> INFO: 208000 events read in total (13250ms).
[09:13:02.003] <TB0> INFO: Test took 14386ms.
[09:13:02.105] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:02.388] <TB0> INFO: dacScan step from 19 .. 19
[09:13:02.388] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:13:02.389] <TB0> INFO: run 1 of 1
[09:13:02.997] <TB0> INFO: Expecting 208000 events.
[09:13:17.024] <TB0> INFO: 208000 events read in total (13500ms).
[09:13:17.033] <TB0> INFO: Test took 14644ms.
[09:13:17.141] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:17.377] <TB0> INFO: dacScan step from 20 .. 20
[09:13:17.377] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:13:17.377] <TB0> INFO: run 1 of 1
[09:13:17.986] <TB0> INFO: Expecting 208000 events.
[09:13:32.858] <TB0> INFO: 208000 events read in total (14345ms).
[09:13:32.867] <TB0> INFO: Test took 15490ms.
[09:13:32.963] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:33.213] <TB0> INFO: dacScan step from 21 .. 21
[09:13:33.213] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:13:33.213] <TB0> INFO: run 1 of 1
[09:13:33.814] <TB0> INFO: Expecting 208000 events.
[09:13:50.373] <TB0> INFO: 208000 events read in total (16032ms).
[09:13:50.384] <TB0> INFO: Test took 17171ms.
[09:13:50.586] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:13:50.821] <TB0> INFO: dacScan step from 22 .. 22
[09:13:50.821] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:13:50.821] <TB0> INFO: run 1 of 1
[09:13:51.418] <TB0> INFO: Expecting 208000 events.
[09:14:08.226] <TB0> INFO: 208000 events read in total (16279ms).
[09:14:08.234] <TB0> INFO: Test took 17413ms.
[09:14:08.317] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:14:08.690] <TB0> INFO: dacScan step from 23 .. 23
[09:14:08.690] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:14:08.690] <TB0> INFO: run 1 of 1
[09:14:09.371] <TB0> INFO: Expecting 208000 events.
[09:14:25.391] <TB0> INFO: 208000 events read in total (15493ms).
[09:14:25.399] <TB0> INFO: Test took 16709ms.
[09:14:25.482] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:14:25.717] <TB0> INFO: dacScan step from 24 .. 24
[09:14:25.717] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:14:25.717] <TB0> INFO: run 1 of 1
[09:14:26.322] <TB0> INFO: Expecting 208000 events.
[09:14:41.593] <TB0> INFO: 208000 events read in total (14744ms).
[09:14:41.601] <TB0> INFO: Test took 15883ms.
[09:14:41.692] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:14:41.940] <TB0> INFO: dacScan step from 25 .. 25
[09:14:41.940] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:14:41.940] <TB0> INFO: run 1 of 1
[09:14:42.545] <TB0> INFO: Expecting 208000 events.
[09:14:56.488] <TB0> INFO: 208000 events read in total (13416ms).
[09:14:56.496] <TB0> INFO: Test took 14556ms.
[09:14:56.585] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:14:56.819] <TB0> INFO: dacScan step from 26 .. 26
[09:14:56.819] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:14:56.819] <TB0> INFO: run 1 of 1
[09:14:57.431] <TB0> INFO: Expecting 208000 events.
[09:15:11.013] <TB0> INFO: 208000 events read in total (13055ms).
[09:15:11.021] <TB0> INFO: Test took 14201ms.
[09:15:11.101] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:15:11.337] <TB0> INFO: dacScan step from 27 .. 27
[09:15:11.337] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:15:11.337] <TB0> INFO: run 1 of 1
[09:15:11.936] <TB0> INFO: Expecting 208000 events.
[09:15:27.186] <TB0> INFO: 208000 events read in total (14723ms).
[09:15:27.195] <TB0> INFO: Test took 15858ms.
[09:15:27.276] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:15:27.516] <TB0> INFO: dacScan step from 28 .. 28
[09:15:27.516] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:15:27.516] <TB0> INFO: run 1 of 1
[09:15:28.121] <TB0> INFO: Expecting 208000 events.
[09:15:44.113] <TB0> INFO: 208000 events read in total (15465ms).
[09:15:44.123] <TB0> INFO: Test took 16606ms.
[09:15:44.333] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:15:44.687] <TB0> INFO: dacScan step from 29 .. 29
[09:15:44.687] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:15:44.687] <TB0> INFO: run 1 of 1
[09:15:45.292] <TB0> INFO: Expecting 208000 events.
[09:16:01.241] <TB0> INFO: 208000 events read in total (15422ms).
[09:16:01.253] <TB0> INFO: Test took 16565ms.
[09:16:01.342] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:16:01.579] <TB0> INFO: dacScan step from 30 .. 30
[09:16:01.579] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:16:01.579] <TB0> INFO: run 1 of 1
[09:16:02.183] <TB0> INFO: Expecting 208000 events.
[09:16:17.794] <TB0> INFO: 208000 events read in total (15084ms).
[09:16:17.802] <TB0> INFO: Test took 16223ms.
[09:16:17.883] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:16:18.113] <TB0> INFO: dacScan step from 31 .. 31
[09:16:18.113] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:16:18.113] <TB0> INFO: run 1 of 1
[09:16:18.719] <TB0> INFO: Expecting 208000 events.
[09:16:33.300] <TB0> INFO: 208000 events read in total (14054ms).
[09:16:33.308] <TB0> INFO: Test took 15195ms.
[09:16:33.392] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:16:33.649] <TB0> INFO: dacScan step from 32 .. 32
[09:16:33.649] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:16:33.649] <TB0> INFO: run 1 of 1
[09:16:34.257] <TB0> INFO: Expecting 208000 events.
[09:16:48.034] <TB0> INFO: 208000 events read in total (13249ms).
[09:16:48.042] <TB0> INFO: Test took 14392ms.
[09:16:48.122] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:16:48.354] <TB0> INFO: dacScan step from 33 .. 33
[09:16:48.354] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:16:48.354] <TB0> INFO: run 1 of 1
[09:16:48.954] <TB0> INFO: Expecting 208000 events.
[09:17:03.327] <TB0> INFO: 208000 events read in total (13845ms).
[09:17:03.335] <TB0> INFO: Test took 14980ms.
[09:17:03.427] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:17:03.705] <TB0> INFO: dacScan step from 34 .. 34
[09:17:03.705] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:17:03.705] <TB0> INFO: run 1 of 1
[09:17:04.331] <TB0> INFO: Expecting 208000 events.
[09:17:19.665] <TB0> INFO: 208000 events read in total (14807ms).
[09:17:19.673] <TB0> INFO: Test took 15967ms.
[09:17:19.764] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:17:20.035] <TB0> INFO: dacScan step from 35 .. 35
[09:17:20.035] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:17:20.035] <TB0> INFO: run 1 of 1
[09:17:20.632] <TB0> INFO: Expecting 208000 events.
[09:17:36.975] <TB0> INFO: 208000 events read in total (15815ms).
[09:17:36.984] <TB0> INFO: Test took 16949ms.
[09:17:37.070] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:17:37.298] <TB0> INFO: dacScan step from 36 .. 36
[09:17:37.298] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:17:37.298] <TB0> INFO: run 1 of 1
[09:17:37.895] <TB0> INFO: Expecting 208000 events.
[09:17:54.715] <TB0> INFO: 208000 events read in total (16293ms).
[09:17:54.724] <TB0> INFO: Test took 17425ms.
[09:17:54.810] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:17:55.045] <TB0> INFO: dacScan step from 37 .. 37
[09:17:55.045] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:17:55.045] <TB0> INFO: run 1 of 1
[09:17:55.654] <TB0> INFO: Expecting 208000 events.
[09:18:11.753] <TB0> INFO: 208000 events read in total (15572ms).
[09:18:11.761] <TB0> INFO: Test took 16715ms.
[09:18:11.852] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:12.121] <TB0> INFO: dacScan step from 38 .. 38
[09:18:12.121] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:18:12.121] <TB0> INFO: run 1 of 1
[09:18:12.732] <TB0> INFO: Expecting 208000 events.
[09:18:28.651] <TB0> INFO: 208000 events read in total (15392ms).
[09:18:28.661] <TB0> INFO: Test took 16539ms.
[09:18:28.747] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:28.988] <TB0> INFO: dacScan step from 39 .. 39
[09:18:28.988] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:18:28.988] <TB0> INFO: run 1 of 1
[09:18:29.588] <TB0> INFO: Expecting 208000 events.
[09:18:45.063] <TB0> INFO: 208000 events read in total (14948ms).
[09:18:45.073] <TB0> INFO: Test took 16085ms.
[09:18:45.161] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:18:45.408] <TB0> INFO: dacScan step from 40 .. 40
[09:18:45.408] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:18:45.408] <TB0> INFO: run 1 of 1
[09:18:46.014] <TB0> INFO: Expecting 208000 events.
[09:19:01.150] <TB0> INFO: 208000 events read in total (14609ms).
[09:19:01.161] <TB0> INFO: Test took 15753ms.
[09:19:01.247] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:19:01.481] <TB0> INFO: dacScan step from 41 .. 41
[09:19:01.481] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:19:01.481] <TB0> INFO: run 1 of 1
[09:19:02.089] <TB0> INFO: Expecting 208000 events.
[09:19:17.905] <TB0> INFO: 208000 events read in total (15289ms).
[09:19:17.915] <TB0> INFO: Test took 16433ms.
[09:19:18.005] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:19:18.288] <TB0> INFO: dacScan step from 42 .. 42
[09:19:18.288] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:19:18.288] <TB0> INFO: run 1 of 1
[09:19:18.908] <TB0> INFO: Expecting 208000 events.
[09:19:35.337] <TB0> INFO: 208000 events read in total (15902ms).
[09:19:35.346] <TB0> INFO: Test took 17058ms.
[09:19:35.427] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:19:35.662] <TB0> INFO: dacScan step from 43 .. 43
[09:19:35.662] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:19:35.662] <TB0> INFO: run 1 of 1
[09:19:36.259] <TB0> INFO: Expecting 208000 events.
[09:19:53.653] <TB0> INFO: 208000 events read in total (16867ms).
[09:19:53.661] <TB0> INFO: Test took 17999ms.
[09:19:53.750] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:19:54.098] <TB0> INFO: dacScan step from 44 .. 44
[09:19:54.098] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:19:54.098] <TB0> INFO: run 1 of 1
[09:19:54.696] <TB0> INFO: Expecting 208000 events.
[09:20:12.955] <TB0> INFO: 208000 events read in total (17732ms).
[09:20:12.963] <TB0> INFO: Test took 18864ms.
[09:20:13.050] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:20:13.280] <TB0> INFO: dacScan step from 45 .. 45
[09:20:13.280] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:20:13.280] <TB0> INFO: run 1 of 1
[09:20:13.874] <TB0> INFO: Expecting 208000 events.
[09:20:32.497] <TB0> INFO: 208000 events read in total (18096ms).
[09:20:32.506] <TB0> INFO: Test took 19226ms.
[09:20:32.596] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:20:32.853] <TB0> INFO: dacScan step from 46 .. 46
[09:20:32.853] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:20:32.853] <TB0> INFO: run 1 of 1
[09:20:33.457] <TB0> INFO: Expecting 208000 events.
[09:20:52.666] <TB0> INFO: 208000 events read in total (18681ms).
[09:20:52.675] <TB0> INFO: Test took 19822ms.
[09:20:52.761] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:20:53.018] <TB0> INFO: dacScan step from 47 .. 47
[09:20:53.018] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:20:53.018] <TB0> INFO: run 1 of 1
[09:20:53.613] <TB0> INFO: Expecting 208000 events.
[09:21:13.245] <TB0> INFO: 208000 events read in total (19104ms).
[09:21:13.253] <TB0> INFO: Test took 20234ms.
[09:21:13.340] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:21:13.603] <TB0> INFO: dacScan step from 48 .. 48
[09:21:13.603] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:21:13.603] <TB0> INFO: run 1 of 1
[09:21:14.208] <TB0> INFO: Expecting 208000 events.
[09:21:34.097] <TB0> INFO: 208000 events read in total (19362ms).
[09:21:34.105] <TB0> INFO: Test took 20502ms.
[09:21:34.187] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:21:34.430] <TB0> INFO: dacScan step from 49 .. 49
[09:21:34.430] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:21:34.430] <TB0> INFO: run 1 of 1
[09:21:35.027] <TB0> INFO: Expecting 208000 events.
[09:21:54.909] <TB0> INFO: 208000 events read in total (19354ms).
[09:21:54.917] <TB0> INFO: Test took 20486ms.
[09:21:55.002] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:21:55.267] <TB0> INFO: dacScan step from 50 .. 50
[09:21:55.267] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:21:55.267] <TB0> INFO: run 1 of 1
[09:21:55.872] <TB0> INFO: Expecting 208000 events.
[09:22:15.203] <TB0> INFO: 208000 events read in total (18804ms).
[09:22:15.211] <TB0> INFO: Test took 19943ms.
[09:22:15.302] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:22:15.549] <TB0> INFO: dacScan step from 51 .. 51
[09:22:15.549] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:22:15.549] <TB0> INFO: run 1 of 1
[09:22:16.158] <TB0> INFO: Expecting 208000 events.
[09:22:36.131] <TB0> INFO: 208000 events read in total (19446ms).
[09:22:36.140] <TB0> INFO: Test took 20590ms.
[09:22:36.232] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:22:36.502] <TB0> INFO: dacScan step from 52 .. 52
[09:22:36.502] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:22:36.502] <TB0> INFO: run 1 of 1
[09:22:37.105] <TB0> INFO: Expecting 208000 events.
[09:22:56.606] <TB0> INFO: 208000 events read in total (18973ms).
[09:22:56.614] <TB0> INFO: Test took 20112ms.
[09:22:56.703] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:22:56.959] <TB0> INFO: dacScan step from 53 .. 53
[09:22:56.959] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:22:56.959] <TB0> INFO: run 1 of 1
[09:22:57.571] <TB0> INFO: Expecting 208000 events.
[09:23:16.289] <TB0> INFO: 208000 events read in total (18190ms).
[09:23:16.297] <TB0> INFO: Test took 19337ms.
[09:23:16.386] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:23:16.648] <TB0> INFO: dacScan step from 54 .. 54
[09:23:16.648] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:23:16.648] <TB0> INFO: run 1 of 1
[09:23:17.259] <TB0> INFO: Expecting 208000 events.
[09:23:35.034] <TB0> INFO: 208000 events read in total (17247ms).
[09:23:35.043] <TB0> INFO: Test took 18394ms.
[09:23:35.126] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:23:35.388] <TB0> INFO: dacScan step from 55 .. 55
[09:23:35.388] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:23:35.388] <TB0> INFO: run 1 of 1
[09:23:35.988] <TB0> INFO: Expecting 208000 events.
[09:23:52.852] <TB0> INFO: 208000 events read in total (16337ms).
[09:23:52.861] <TB0> INFO: Test took 17473ms.
[09:23:52.947] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:23:53.190] <TB0> INFO: dacScan step from 56 .. 56
[09:23:53.190] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:23:53.190] <TB0> INFO: run 1 of 1
[09:23:53.795] <TB0> INFO: Expecting 208000 events.
[09:24:10.920] <TB0> INFO: 208000 events read in total (16598ms).
[09:24:10.929] <TB0> INFO: Test took 17738ms.
[09:24:11.015] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:24:11.266] <TB0> INFO: dacScan step from 57 .. 57
[09:24:11.266] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:24:11.266] <TB0> INFO: run 1 of 1
[09:24:11.883] <TB0> INFO: Expecting 208000 events.
[09:24:30.779] <TB0> INFO: 208000 events read in total (18369ms).
[09:24:30.789] <TB0> INFO: Test took 19522ms.
[09:24:30.882] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:24:31.176] <TB0> INFO: dacScan step from 58 .. 58
[09:24:31.176] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:24:31.176] <TB0> INFO: run 1 of 1
[09:24:31.805] <TB0> INFO: Expecting 208000 events.
[09:24:51.423] <TB0> INFO: 208000 events read in total (19091ms).
[09:24:51.432] <TB0> INFO: Test took 20256ms.
[09:24:51.514] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:24:51.742] <TB0> INFO: dacScan step from 59 .. 59
[09:24:51.742] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:24:51.742] <TB0> INFO: run 1 of 1
[09:24:52.336] <TB0> INFO: Expecting 208000 events.
[09:25:12.102] <TB0> INFO: 208000 events read in total (19239ms).
[09:25:12.110] <TB0> INFO: Test took 20368ms.
[09:25:12.194] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:25:12.434] <TB0> INFO: dacScan step from 60 .. 60
[09:25:12.434] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:25:12.434] <TB0> INFO: run 1 of 1
[09:25:13.034] <TB0> INFO: Expecting 208000 events.
[09:25:33.130] <TB0> INFO: 208000 events read in total (19569ms).
[09:25:33.139] <TB0> INFO: Test took 20705ms.
[09:25:33.226] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:25:33.480] <TB0> INFO: dacScan step from 61 .. 61
[09:25:33.480] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:25:33.480] <TB0> INFO: run 1 of 1
[09:25:34.080] <TB0> INFO: Expecting 208000 events.
[09:25:52.665] <TB0> INFO: 208000 events read in total (18059ms).
[09:25:52.675] <TB0> INFO: Test took 19195ms.
[09:25:52.761] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:25:52.993] <TB0> INFO: dacScan step from 62 .. 62
[09:25:52.993] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:25:52.993] <TB0> INFO: run 1 of 1
[09:25:53.595] <TB0> INFO: Expecting 208000 events.
[09:26:10.255] <TB0> INFO: 208000 events read in total (16133ms).
[09:26:10.264] <TB0> INFO: Test took 17271ms.
[09:26:10.348] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:26:10.581] <TB0> INFO: dacScan step from 63 .. 63
[09:26:10.581] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:26:10.581] <TB0> INFO: run 1 of 1
[09:26:11.180] <TB0> INFO: Expecting 208000 events.
[09:26:28.926] <TB0> INFO: 208000 events read in total (17219ms).
[09:26:28.935] <TB0> INFO: Test took 18353ms.
[09:26:29.023] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:26:29.270] <TB0> INFO: dacScan step from 64 .. 64
[09:26:29.270] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:26:29.270] <TB0> INFO: run 1 of 1
[09:26:29.873] <TB0> INFO: Expecting 208000 events.
[09:26:47.752] <TB0> INFO: 208000 events read in total (17352ms).
[09:26:47.761] <TB0> INFO: Test took 18490ms.
[09:26:47.851] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:26:48.088] <TB0> INFO: dacScan step from 65 .. 65
[09:26:48.088] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:26:48.088] <TB0> INFO: run 1 of 1
[09:26:48.687] <TB0> INFO: Expecting 208000 events.
[09:27:08.670] <TB0> INFO: 208000 events read in total (19456ms).
[09:27:08.679] <TB0> INFO: Test took 20590ms.
[09:27:08.774] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:27:09.009] <TB0> INFO: dacScan step from 66 .. 66
[09:27:09.009] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:27:09.009] <TB0> INFO: run 1 of 1
[09:27:09.606] <TB0> INFO: Expecting 208000 events.
[09:27:30.357] <TB0> INFO: 208000 events read in total (20223ms).
[09:27:30.366] <TB0> INFO: Test took 21356ms.
[09:27:30.452] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:27:30.681] <TB0> INFO: dacScan step from 67 .. 67
[09:27:30.681] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:27:30.681] <TB0> INFO: run 1 of 1
[09:27:31.276] <TB0> INFO: Expecting 208000 events.
[09:27:51.227] <TB0> INFO: 208000 events read in total (19424ms).
[09:27:51.235] <TB0> INFO: Test took 20553ms.
[09:27:51.321] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:27:51.558] <TB0> INFO: dacScan step from 68 .. 68
[09:27:51.558] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:27:51.558] <TB0> INFO: run 1 of 1
[09:27:52.155] <TB0> INFO: Expecting 208000 events.
[09:28:11.255] <TB0> INFO: 208000 events read in total (18572ms).
[09:28:11.264] <TB0> INFO: Test took 19705ms.
[09:28:11.349] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:28:11.587] <TB0> INFO: dacScan step from 69 .. 69
[09:28:11.587] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:28:11.587] <TB0> INFO: run 1 of 1
[09:28:12.193] <TB0> INFO: Expecting 208000 events.
[09:28:30.302] <TB0> INFO: 208000 events read in total (17582ms).
[09:28:30.311] <TB0> INFO: Test took 18723ms.
[09:28:30.396] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:28:30.631] <TB0> INFO: dacScan step from 70 .. 70
[09:28:30.632] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:28:30.632] <TB0> INFO: run 1 of 1
[09:28:31.230] <TB0> INFO: Expecting 208000 events.
[09:28:48.319] <TB0> INFO: 208000 events read in total (16561ms).
[09:28:48.328] <TB0> INFO: Test took 17696ms.
[09:28:48.419] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:28:48.694] <TB0> INFO: dacScan step from 71 .. 71
[09:28:48.694] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:28:48.694] <TB0> INFO: run 1 of 1
[09:28:49.318] <TB0> INFO: Expecting 208000 events.
[09:29:07.339] <TB0> INFO: 208000 events read in total (17494ms).
[09:29:07.348] <TB0> INFO: Test took 18653ms.
[09:29:07.434] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:29:07.686] <TB0> INFO: dacScan step from 72 .. 72
[09:29:07.686] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:29:07.686] <TB0> INFO: run 1 of 1
[09:29:08.287] <TB0> INFO: Expecting 208000 events.
[09:29:27.191] <TB0> INFO: 208000 events read in total (18377ms).
[09:29:27.200] <TB0> INFO: Test took 19513ms.
[09:29:27.292] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:29:27.567] <TB0> INFO: dacScan step from 73 .. 73
[09:29:27.567] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:29:27.567] <TB0> INFO: run 1 of 1
[09:29:28.181] <TB0> INFO: Expecting 208000 events.
[09:29:48.494] <TB0> INFO: 208000 events read in total (19786ms).
[09:29:48.503] <TB0> INFO: Test took 20934ms.
[09:29:48.590] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:29:48.832] <TB0> INFO: dacScan step from 74 .. 74
[09:29:48.832] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:29:48.832] <TB0> INFO: run 1 of 1
[09:29:49.436] <TB0> INFO: Expecting 208000 events.
[09:30:09.428] <TB0> INFO: 208000 events read in total (19464ms).
[09:30:09.437] <TB0> INFO: Test took 20604ms.
[09:30:09.522] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:09.761] <TB0> INFO: dacScan step from 75 .. 75
[09:30:09.761] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:30:09.761] <TB0> INFO: run 1 of 1
[09:30:10.364] <TB0> INFO: Expecting 208000 events.
[09:30:29.270] <TB0> INFO: 208000 events read in total (18379ms).
[09:30:29.279] <TB0> INFO: Test took 19517ms.
[09:30:29.367] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:29.620] <TB0> INFO: dacScan step from 76 .. 76
[09:30:29.620] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:30:29.620] <TB0> INFO: run 1 of 1
[09:30:30.229] <TB0> INFO: Expecting 208000 events.
[09:30:48.130] <TB0> INFO: 208000 events read in total (17374ms).
[09:30:48.139] <TB0> INFO: Test took 18518ms.
[09:30:48.231] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:30:48.490] <TB0> INFO: dacScan step from 77 .. 77
[09:30:48.490] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:30:48.490] <TB0> INFO: run 1 of 1
[09:30:49.097] <TB0> INFO: Expecting 208000 events.
[09:31:06.906] <TB0> INFO: 208000 events read in total (17282ms).
[09:31:06.915] <TB0> INFO: Test took 18424ms.
[09:31:07.005] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:31:07.259] <TB0> INFO: dacScan step from 78 .. 78
[09:31:07.259] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:31:07.259] <TB0> INFO: run 1 of 1
[09:31:07.880] <TB0> INFO: Expecting 208000 events.
[09:31:26.440] <TB0> INFO: 208000 events read in total (18033ms).
[09:31:26.450] <TB0> INFO: Test took 19190ms.
[09:31:26.565] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:31:26.846] <TB0> INFO: dacScan step from 79 .. 79
[09:31:26.846] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:31:26.846] <TB0> INFO: run 1 of 1
[09:31:27.451] <TB0> INFO: Expecting 208000 events.
[09:31:46.120] <TB0> INFO: 208000 events read in total (18142ms).
[09:31:46.129] <TB0> INFO: Test took 19282ms.
[09:31:46.218] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:31:46.476] <TB0> INFO: dacScan step from 80 .. 80
[09:31:46.476] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:31:46.476] <TB0> INFO: run 1 of 1
[09:31:47.074] <TB0> INFO: Expecting 208000 events.
[09:32:06.702] <TB0> INFO: 208000 events read in total (19101ms).
[09:32:06.711] <TB0> INFO: Test took 20234ms.
[09:32:06.797] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:32:07.030] <TB0> INFO: dacScan step from 81 .. 81
[09:32:07.030] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:32:07.030] <TB0> INFO: run 1 of 1
[09:32:07.640] <TB0> INFO: Expecting 208000 events.
[09:32:28.369] <TB0> INFO: 208000 events read in total (20202ms).
[09:32:28.379] <TB0> INFO: Test took 21348ms.
[09:32:28.469] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:32:28.719] <TB0> INFO: dacScan step from 82 .. 82
[09:32:28.719] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:32:28.719] <TB0> INFO: run 1 of 1
[09:32:29.319] <TB0> INFO: Expecting 208000 events.
[09:32:49.091] <TB0> INFO: 208000 events read in total (19245ms).
[09:32:49.100] <TB0> INFO: Test took 20380ms.
[09:32:49.190] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:32:49.446] <TB0> INFO: dacScan step from 83 .. 83
[09:32:49.446] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:32:49.446] <TB0> INFO: run 1 of 1
[09:32:50.058] <TB0> INFO: Expecting 208000 events.
[09:33:08.603] <TB0> INFO: 208000 events read in total (18018ms).
[09:33:08.612] <TB0> INFO: Test took 19165ms.
[09:33:08.702] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:33:08.950] <TB0> INFO: dacScan step from 84 .. 84
[09:33:08.950] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:33:08.950] <TB0> INFO: run 1 of 1
[09:33:09.558] <TB0> INFO: Expecting 208000 events.
[09:33:27.620] <TB0> INFO: 208000 events read in total (17535ms).
[09:33:27.630] <TB0> INFO: Test took 18679ms.
[09:33:27.718] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:33:28.007] <TB0> INFO: dacScan step from 85 .. 85
[09:33:28.008] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:33:28.008] <TB0> INFO: run 1 of 1
[09:33:28.630] <TB0> INFO: Expecting 208000 events.
[09:33:46.323] <TB0> INFO: 208000 events read in total (17166ms).
[09:33:46.334] <TB0> INFO: Test took 18325ms.
[09:33:46.423] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:33:46.675] <TB0> INFO: dacScan step from 86 .. 86
[09:33:46.675] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:33:46.675] <TB0> INFO: run 1 of 1
[09:33:47.283] <TB0> INFO: Expecting 208000 events.
[09:34:05.115] <TB0> INFO: 208000 events read in total (17305ms).
[09:34:05.123] <TB0> INFO: Test took 18447ms.
[09:34:05.208] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:34:05.454] <TB0> INFO: dacScan step from 87 .. 87
[09:34:05.454] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:34:05.454] <TB0> INFO: run 1 of 1
[09:34:06.058] <TB0> INFO: Expecting 208000 events.
[09:34:25.068] <TB0> INFO: 208000 events read in total (18483ms).
[09:34:25.077] <TB0> INFO: Test took 19622ms.
[09:34:25.161] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:34:25.409] <TB0> INFO: dacScan step from 88 .. 88
[09:34:25.409] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:34:25.409] <TB0> INFO: run 1 of 1
[09:34:26.018] <TB0> INFO: Expecting 208000 events.
[09:34:46.395] <TB0> INFO: 208000 events read in total (19850ms).
[09:34:46.405] <TB0> INFO: Test took 20995ms.
[09:34:46.892] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:34:47.139] <TB0> INFO: dacScan step from 89 .. 89
[09:34:47.139] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:34:47.139] <TB0> INFO: run 1 of 1
[09:34:47.737] <TB0> INFO: Expecting 208000 events.
[09:35:08.301] <TB0> INFO: 208000 events read in total (20037ms).
[09:35:08.311] <TB0> INFO: Test took 21171ms.
[09:35:08.488] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:35:08.726] <TB0> INFO: dacScan step from 90 .. 90
[09:35:08.726] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:35:08.726] <TB0> INFO: run 1 of 1
[09:35:09.328] <TB0> INFO: Expecting 208000 events.
[09:35:28.556] <TB0> INFO: 208000 events read in total (18700ms).
[09:35:28.564] <TB0> INFO: Test took 19837ms.
[09:35:28.966] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:35:29.242] <TB0> INFO: dacScan step from 91 .. 91
[09:35:29.242] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:35:29.242] <TB0> INFO: run 1 of 1
[09:35:29.842] <TB0> INFO: Expecting 208000 events.
[09:35:48.703] <TB0> INFO: 208000 events read in total (18333ms).
[09:35:48.711] <TB0> INFO: Test took 19468ms.
[09:35:48.931] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:35:49.191] <TB0> INFO: dacScan step from 92 .. 92
[09:35:49.191] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:35:49.191] <TB0> INFO: run 1 of 1
[09:35:49.799] <TB0> INFO: Expecting 208000 events.
[09:36:07.336] <TB0> INFO: 208000 events read in total (17009ms).
[09:36:07.344] <TB0> INFO: Test took 18152ms.
[09:36:07.492] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:36:07.744] <TB0> INFO: dacScan step from 93 .. 93
[09:36:07.744] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:36:07.744] <TB0> INFO: run 1 of 1
[09:36:08.346] <TB0> INFO: Expecting 208000 events.
[09:36:26.236] <TB0> INFO: 208000 events read in total (17363ms).
[09:36:26.244] <TB0> INFO: Test took 18499ms.
[09:36:26.390] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:36:26.637] <TB0> INFO: dacScan step from 94 .. 94
[09:36:26.638] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:36:26.638] <TB0> INFO: run 1 of 1
[09:36:27.246] <TB0> INFO: Expecting 208000 events.
[09:36:44.758] <TB0> INFO: 208000 events read in total (16985ms).
[09:36:44.766] <TB0> INFO: Test took 18127ms.
[09:36:44.920] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:36:45.166] <TB0> INFO: dacScan step from 95 .. 95
[09:36:45.166] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:36:45.166] <TB0> INFO: run 1 of 1
[09:36:45.779] <TB0> INFO: Expecting 208000 events.
[09:37:03.790] <TB0> INFO: 208000 events read in total (17484ms).
[09:37:03.798] <TB0> INFO: Test took 18631ms.
[09:37:03.879] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:37:04.116] <TB0> INFO: dacScan step from 96 .. 96
[09:37:04.116] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:37:04.116] <TB0> INFO: run 1 of 1
[09:37:04.716] <TB0> INFO: Expecting 208000 events.
[09:37:25.006] <TB0> INFO: 208000 events read in total (19762ms).
[09:37:25.016] <TB0> INFO: Test took 20899ms.
[09:37:25.101] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:37:25.347] <TB0> INFO: dacScan step from 97 .. 97
[09:37:25.347] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:37:25.347] <TB0> INFO: run 1 of 1
[09:37:25.946] <TB0> INFO: Expecting 208000 events.
[09:37:47.043] <TB0> INFO: 208000 events read in total (20569ms).
[09:37:47.051] <TB0> INFO: Test took 21703ms.
[09:37:47.131] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:37:47.357] <TB0> INFO: dacScan step from 98 .. 98
[09:37:47.357] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:37:47.357] <TB0> INFO: run 1 of 1
[09:37:47.956] <TB0> INFO: Expecting 208000 events.
[09:38:07.926] <TB0> INFO: 208000 events read in total (19443ms).
[09:38:07.934] <TB0> INFO: Test took 20576ms.
[09:38:08.025] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:08.276] <TB0> INFO: dacScan step from 99 .. 99
[09:38:08.276] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:38:08.276] <TB0> INFO: run 1 of 1
[09:38:08.882] <TB0> INFO: Expecting 208000 events.
[09:38:28.006] <TB0> INFO: 208000 events read in total (18597ms).
[09:38:28.015] <TB0> INFO: Test took 19738ms.
[09:38:28.095] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:28.345] <TB0> INFO: dacScan step from 100 .. 100
[09:38:28.345] <TB0> INFO: dacScan split into 1 runs with ntrig = 50
[09:38:28.345] <TB0> INFO: run 1 of 1
[09:38:28.944] <TB0> INFO: Expecting 208000 events.
[09:38:46.674] <TB0> INFO: 208000 events read in total (17202ms).
[09:38:46.684] <TB0> INFO: Test took 18338ms.
[09:38:46.768] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:47.004] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[09:38:48.407] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[09:38:49.746] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[09:38:51.084] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[09:38:52.447] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[09:38:53.860] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[09:38:55.332] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[09:38:56.832] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[09:38:58.470] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[09:38:59.995] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:01.665] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:03.222] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:04.656] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:06.201] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:07.558] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:09.003] <TB0> INFO: dumping ASCII scurve output file: XSCurveData
[09:39:10.434] <TB0> INFO: PixTest:: pg_setup set to default.
[09:39:10.436] <TB0> INFO: vcal mean: 38.28 38.76 38.15 40.82 41.27 42.61 41.94 42.25 42.09 41.92 41.20 40.99 39.98 37.71 38.39 37.71
[09:39:10.436] <TB0> INFO: vcal RMS: 1.34 1.27 1.13 1.70 1.37 1.42 1.47 1.59 1.50 1.53 1.53 1.74 1.36 1.48 1.26 1.35
[09:39:10.436] <TB0> INFO: number of X-ray hits detected: 188306 191299 177849 295518 311218 383447 351594 375101 354422 341238 318614 291182 271312 158430 167286 162001
[09:39:10.436] <TB0> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:39:10.436] <TB0> INFO: X-ray hit rate [MHz/cm2]: 55.2 56.1 52.1 86.6 91.2 112.4 103.1 109.9 103.9 100.0 93.4 85.3 79.5 46.4 49.0 47.5
[09:39:10.436] <TB0> INFO: PixTestHighRate::doXNoiseMaps() done
[09:39:10.539] <TB0> INFO: enter test to run
[09:39:10.539] <TB0> INFO: test: no parameter change
[09:39:10.728] <TB0> QUIET: Connection to board 147 closed.
[09:39:10.808] <TB0> INFO: pXar: this is the end, my friend
commander_CalDelScanAndSaveDacs.log
[10:01:06.235] <TB0> INFO: *** Welcome to pxar ***
[10:01:06.235] <TB0> INFO: *** Today: 2015/08/28
[10:01:06.235] <TB0> INFO: readRocDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[10:01:06.236] <TB0> INFO: readTbmDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0a.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0b.dat
[10:01:06.236] <TB0> INFO: readMaskFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//defaultMaskFile.dat
[10:01:06.236] <TB0> INFO: readTrimFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C15.dat
[10:01:06.307] <TB0> INFO: clk: 4
[10:01:06.307] <TB0> INFO: ctr: 4
[10:01:06.307] <TB0> INFO: sda: 19
[10:01:06.307] <TB0> INFO: tin: 9
[10:01:06.307] <TB0> INFO: level: 15
[10:01:06.307] <TB0> INFO: triggerdelay: 0
[10:01:06.307] <TB0> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:01:06.307] <TB0> INFO: Log level: INFO
[10:01:06.321] <TB0> INFO: Found DTB DTB_WWXTQT
[10:01:06.330] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[10:01:06.333] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[10:01:06.335] <TB0> INFO: RPC call hashes of host and DTB match: 397073690
[10:01:07.850] <TB0> INFO: DUT info:
[10:01:07.850] <TB0> INFO: The DUT currently contains the following objects:
[10:01:07.850] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[10:01:07.850] <TB0> INFO: TBM Core alpha (0): 7 registers set
[10:01:07.850] <TB0> INFO: TBM Core beta (1): 7 registers set
[10:01:07.850] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:01:07.850] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:07.850] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:07.850] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:07.850] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:07.850] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:07.850] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:07.850] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:07.850] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:07.850] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:07.850] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:07.850] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:07.850] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:07.850] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:07.850] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:07.850] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:07.850] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:01:08.251] <TB0> INFO: enter 'restricted' command line mode
[10:01:08.251] <TB0> INFO: enter test to run
[10:01:08.251] <TB0> INFO: test: HighRate no parameter change
[10:01:08.251] <TB0> INFO: running: highrate
[10:01:08.251] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C15.dat
[10:01:08.383] <TB0> INFO: ----------------------------------------------------------------------
[10:01:08.383] <TB0> INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:01:08.383] <TB0> INFO: ----------------------------------------------------------------------
[10:01:08.521] <TB0> INFO: Expecting 768 events.
[10:01:09.748] <TB0> INFO: 768 events read in total (509ms).
[10:01:09.843] <TB0> INFO: Test took 1454ms.
[10:01:10.458] <TB0> INFO: Expecting 41600 events.
[10:01:13.425] <TB0> INFO: 41600 events read in total (2439ms).
[10:01:13.427] <TB0> INFO: Test took 3581ms.
[10:01:13.492] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:14.129] <TB0> INFO: Expecting 41600 events.
[10:01:17.524] <TB0> INFO: 41600 events read in total (2868ms).
[10:01:17.526] <TB0> INFO: Test took 4009ms.
[10:01:17.596] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:18.246] <TB0> INFO: Expecting 41600 events.
[10:01:21.966] <TB0> INFO: 41600 events read in total (3193ms).
[10:01:21.967] <TB0> INFO: Test took 4334ms.
[10:01:22.056] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:22.727] <TB0> INFO: Expecting 41600 events.
[10:01:26.297] <TB0> INFO: 41600 events read in total (3042ms).
[10:01:26.299] <TB0> INFO: Test took 4201ms.
[10:01:26.372] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:27.013] <TB0> INFO: Expecting 41600 events.
[10:01:30.652] <TB0> INFO: 41600 events read in total (3112ms).
[10:01:30.655] <TB0> INFO: Test took 4255ms.
[10:01:30.726] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:31.366] <TB0> INFO: Expecting 41600 events.
[10:01:34.973] <TB0> INFO: 41600 events read in total (3080ms).
[10:01:34.975] <TB0> INFO: Test took 4222ms.
[10:01:35.050] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:35.692] <TB0> INFO: Expecting 41600 events.
[10:01:39.428] <TB0> INFO: 41600 events read in total (3209ms).
[10:01:39.430] <TB0> INFO: Test took 4351ms.
[10:01:39.501] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:40.139] <TB0> INFO: Expecting 41600 events.
[10:01:44.089] <TB0> INFO: 41600 events read in total (3422ms).
[10:01:44.091] <TB0> INFO: Test took 4564ms.
[10:01:44.167] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:44.815] <TB0> INFO: Expecting 41600 events.
[10:01:48.601] <TB0> INFO: 41600 events read in total (3259ms).
[10:01:48.603] <TB0> INFO: Test took 4405ms.
[10:01:48.669] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:49.309] <TB0> INFO: Expecting 41600 events.
[10:01:53.413] <TB0> INFO: 41600 events read in total (3577ms).
[10:01:53.414] <TB0> INFO: Test took 4719ms.
[10:01:53.483] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:54.123] <TB0> INFO: Expecting 41600 events.
[10:01:58.245] <TB0> INFO: 41600 events read in total (3595ms).
[10:01:58.246] <TB0> INFO: Test took 4736ms.
[10:01:58.322] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:01:58.964] <TB0> INFO: Expecting 41600 events.
[10:02:03.251] <TB0> INFO: 41600 events read in total (3759ms).
[10:02:03.253] <TB0> INFO: Test took 4901ms.
[10:02:03.324] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:03.966] <TB0> INFO: Expecting 41600 events.
[10:02:07.816] <TB0> INFO: 41600 events read in total (3323ms).
[10:02:07.818] <TB0> INFO: Test took 4466ms.
[10:02:07.899] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:08.549] <TB0> INFO: Expecting 41600 events.
[10:02:12.531] <TB0> INFO: 41600 events read in total (3454ms).
[10:02:12.533] <TB0> INFO: Test took 4600ms.
[10:02:12.604] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:13.246] <TB0> INFO: Expecting 41600 events.
[10:02:17.177] <TB0> INFO: 41600 events read in total (3404ms).
[10:02:17.179] <TB0> INFO: Test took 4546ms.
[10:02:17.249] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:17.891] <TB0> INFO: Expecting 41600 events.
[10:02:21.673] <TB0> INFO: 41600 events read in total (3254ms).
[10:02:21.674] <TB0> INFO: Test took 4396ms.
[10:02:21.745] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:22.386] <TB0> INFO: Expecting 41600 events.
[10:02:26.069] <TB0> INFO: 41600 events read in total (3154ms).
[10:02:26.071] <TB0> INFO: Test took 4298ms.
[10:02:26.141] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:26.787] <TB0> INFO: Expecting 41600 events.
[10:02:30.477] <TB0> INFO: 41600 events read in total (3163ms).
[10:02:30.478] <TB0> INFO: Test took 4308ms.
[10:02:30.548] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:31.190] <TB0> INFO: Expecting 41600 events.
[10:02:34.874] <TB0> INFO: 41600 events read in total (3157ms).
[10:02:34.875] <TB0> INFO: Test took 4299ms.
[10:02:34.946] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:35.592] <TB0> INFO: Expecting 41600 events.
[10:02:39.053] <TB0> INFO: 41600 events read in total (2934ms).
[10:02:39.054] <TB0> INFO: Test took 4079ms.
[10:02:39.123] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:02:39.449] <TB0> INFO: enter test to run
[10:02:39.449] <TB0> INFO: test: Pretest no parameter change
[10:02:39.449] <TB0> INFO: running: pretest
[10:02:39.449] <TB0> INFO: write dac parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat
[10:02:39.449] <TB0> INFO: write dac parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C1.dat
[10:02:39.450] <TB0> INFO: write dac parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C2.dat
[10:02:39.450] <TB0> INFO: write dac parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C3.dat
[10:02:39.450] <TB0> INFO: write dac parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C4.dat
[10:02:39.450] <TB0> INFO: write dac parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C5.dat
[10:02:39.450] <TB0> INFO: write dac parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C6.dat
[10:02:39.450] <TB0> INFO: write dac parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C7.dat
[10:02:39.450] <TB0> INFO: write dac parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C8.dat
[10:02:39.450] <TB0> INFO: write dac parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C9.dat
[10:02:39.450] <TB0> INFO: write dac parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C10.dat
[10:02:39.450] <TB0> INFO: write dac parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C11.dat
[10:02:39.451] <TB0> INFO: write dac parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C12.dat
[10:02:39.451] <TB0> INFO: write dac parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C13.dat
[10:02:39.451] <TB0> INFO: write dac parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C14.dat
[10:02:39.451] <TB0> INFO: write dac parameters into /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[10:02:39.451] <TB0> INFO: enter test to run
[10:02:39.451] <TB0> INFO: test: no parameter change
[10:02:39.663] <TB0> QUIET: Connection to board 147 closed.
[10:02:39.743] <TB0> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[10:03:17.304] <TB0> INFO: *** Welcome to pxar ***
[10:03:17.304] <TB0> INFO: *** Today: 2015/08/28
[10:03:17.304] <TB0> INFO: readRocDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//dacParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//dacParameters35_C15.dat
[10:03:17.305] <TB0> INFO: readTbmDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//tbmParameters_C0a.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//tbmParameters_C0b.dat
[10:03:17.305] <TB0> INFO: readMaskFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//defaultMaskFile.dat
[10:03:17.305] <TB0> INFO: readTrimFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//trimParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//trimParameters35_C15.dat
[10:03:17.371] <TB0> INFO: clk: 4
[10:03:17.371] <TB0> INFO: ctr: 4
[10:03:17.371] <TB0> INFO: sda: 19
[10:03:17.371] <TB0> INFO: tin: 9
[10:03:17.371] <TB0> INFO: level: 15
[10:03:17.371] <TB0> INFO: triggerdelay: 0
[10:03:17.371] <TB0> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:03:17.371] <TB0> INFO: Log level: INFO
[10:03:17.385] <TB0> INFO: Found DTB DTB_WWXTQT
[10:03:17.393] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[10:03:17.396] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[10:03:17.398] <TB0> INFO: RPC call hashes of host and DTB match: 397073690
[10:03:18.914] <TB0> INFO: DUT info:
[10:03:18.914] <TB0> INFO: The DUT currently contains the following objects:
[10:03:18.914] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[10:03:18.914] <TB0> INFO: TBM Core alpha (0): 7 registers set
[10:03:18.914] <TB0> INFO: TBM Core beta (1): 7 registers set
[10:03:18.914] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:03:18.914] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:18.914] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:18.914] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:18.914] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:18.914] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:18.914] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:18.914] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:18.914] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:18.914] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:18.914] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:18.914] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:18.914] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:18.914] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:18.914] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:18.914] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:18.914] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:03:19.315] <TB0> INFO: enter 'restricted' command line mode
[10:03:19.315] <TB0> INFO: enter test to run
[10:03:19.315] <TB0> INFO: test: HighRate no parameter change
[10:03:19.315] <TB0> INFO: running: highrate
[10:03:19.315] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//011_HREfficiency_50//phCalibrationFitErr35_C15.dat
[10:03:19.445] <TB0> INFO: ----------------------------------------------------------------------
[10:03:19.445] <TB0> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:03:19.445] <TB0> INFO: ----------------------------------------------------------------------
[10:03:20.042] <TB0> INFO: Expecting 208000 events.
[10:03:31.941] <TB0> INFO: 208000 events read in total (11369ms).
[10:03:31.947] <TB0> INFO: Test took 12496ms.
[10:03:32.198] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:03:32.540] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 0 0 0 2 0 1 0 0
[10:03:32.540] <TB0> INFO: number of red-efficiency pixels: 107 124 119 271 245 327 273 290 329 261 237 207 183 112 85 113
[10:03:32.540] <TB0> INFO: number of X-ray hits detected: 94462 95269 87641 149321 156317 194915 177532 189965 179898 171284 160407 144738 135941 76996 82448 80923
[10:03:32.541] <TB0> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:03:32.541] <TB0> INFO: number of Vcal hits detected: 207892 207872 207875 207614 207743 207656 207715 207693 207652 207733 207753 207687 207814 207837 207911 207886
[10:03:32.541] <TB0> INFO: Vcal hit fiducial efficiency (%): 100.0 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[10:03:32.541] <TB0> INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.9 99.8 99.9 99.9 99.8 99.9 99.9 99.8 99.9 99.9 100.0 99.9
[10:03:32.541] <TB0> INFO: X-ray hit rate [MHz/cm2]: 27.7 27.9 25.7 43.8 45.8 57.1 52.0 55.7 52.7 50.2 47.0 42.4 39.8 22.6 24.2 23.7
[10:03:32.541] <TB0> INFO: PixTestHighRate::doXPixelAlive() done
[10:03:32.576] <TB0> INFO: PixTest:: pg_setup set to default.
[10:03:32.618] <TB0> INFO: enter test to run
[10:03:32.619] <TB0> INFO: test: no parameter change
[10:03:32.767] <TB0> QUIET: Connection to board 147 closed.
[10:03:32.847] <TB0> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[10:04:07.837] <TB0> INFO: *** Welcome to pxar ***
[10:04:07.837] <TB0> INFO: *** Today: 2015/08/28
[10:04:07.837] <TB0> INFO: readRocDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//dacParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//dacParameters35_C15.dat
[10:04:07.838] <TB0> INFO: readTbmDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//tbmParameters_C0a.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//tbmParameters_C0b.dat
[10:04:07.838] <TB0> INFO: readMaskFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//defaultMaskFile.dat
[10:04:07.838] <TB0> INFO: readTrimFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//trimParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//trimParameters35_C15.dat
[10:04:07.902] <TB0> INFO: clk: 4
[10:04:07.902] <TB0> INFO: ctr: 4
[10:04:07.902] <TB0> INFO: sda: 19
[10:04:07.902] <TB0> INFO: tin: 9
[10:04:07.902] <TB0> INFO: level: 15
[10:04:07.902] <TB0> INFO: triggerdelay: 0
[10:04:07.902] <TB0> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:04:07.902] <TB0> INFO: Log level: INFO
[10:04:07.916] <TB0> INFO: Found DTB DTB_WWXTQT
[10:04:07.924] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[10:04:07.927] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[10:04:07.930] <TB0> INFO: RPC call hashes of host and DTB match: 397073690
[10:04:09.445] <TB0> INFO: DUT info:
[10:04:09.445] <TB0> INFO: The DUT currently contains the following objects:
[10:04:09.445] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[10:04:09.445] <TB0> INFO: TBM Core alpha (0): 7 registers set
[10:04:09.445] <TB0> INFO: TBM Core beta (1): 7 registers set
[10:04:09.445] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:04:09.445] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:09.445] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:09.445] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:09.445] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:09.445] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:09.445] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:09.445] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:09.445] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:09.445] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:09.445] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:09.445] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:09.445] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:09.445] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:09.445] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:09.445] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:09.445] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:04:09.846] <TB0> INFO: enter 'restricted' command line mode
[10:04:09.846] <TB0> INFO: enter test to run
[10:04:09.846] <TB0> INFO: test: HighRate no parameter change
[10:04:09.846] <TB0> INFO: running: highrate
[10:04:09.846] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//012_HREfficiency_100//phCalibrationFitErr35_C15.dat
[10:04:09.975] <TB0> INFO: ----------------------------------------------------------------------
[10:04:09.975] <TB0> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:04:09.975] <TB0> INFO: ----------------------------------------------------------------------
[10:04:10.573] <TB0> INFO: Expecting 208000 events.
[10:04:25.858] <TB0> INFO: 208000 events read in total (14755ms).
[10:04:25.868] <TB0> INFO: Test took 15887ms.
[10:04:26.352] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:04:26.731] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 0 0 0 2 0 1 0 0
[10:04:26.731] <TB0> INFO: number of red-efficiency pixels: 325 426 365 915 809 1164 930 1096 1229 896 782 673 650 300 263 274
[10:04:26.731] <TB0> INFO: number of X-ray hits detected: 190051 192556 178341 297743 312230 385266 350688 377604 359704 343493 320919 292168 272099 158834 167864 163476
[10:04:26.731] <TB0> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:04:26.731] <TB0> INFO: number of Vcal hits detected: 207656 207546 207583 206746 207026 206521 206837 206690 206496 206912 207095 207128 207279 207610 207704 207716
[10:04:26.731] <TB0> INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.8 99.5 99.6 99.4 99.5 99.4 99.3 99.5 99.6 99.7 99.7 99.9 99.9 99.9
[10:04:26.731] <TB0> INFO: Vcal hit overall efficiency (%): 99.8 99.8 99.8 99.4 99.5 99.3 99.4 99.4 99.3 99.5 99.6 99.6 99.7 99.8 99.9 99.9
[10:04:26.731] <TB0> INFO: X-ray hit rate [MHz/cm2]: 55.7 56.4 52.3 87.3 91.5 112.9 102.8 110.7 105.4 100.7 94.1 85.6 79.8 46.6 49.2 47.9
[10:04:26.731] <TB0> INFO: PixTestHighRate::doXPixelAlive() done
[10:04:26.763] <TB0> INFO: PixTest:: pg_setup set to default.
[10:04:26.809] <TB0> INFO: enter test to run
[10:04:26.809] <TB0> INFO: test: no parameter change
[10:04:26.973] <TB0> QUIET: Connection to board 147 closed.
[10:04:27.053] <TB0> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[10:05:04.734] <TB0> INFO: *** Welcome to pxar ***
[10:05:04.734] <TB0> INFO: *** Today: 2015/08/28
[10:05:04.734] <TB0> INFO: readRocDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//dacParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//dacParameters35_C15.dat
[10:05:04.734] <TB0> INFO: readTbmDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//tbmParameters_C0a.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//tbmParameters_C0b.dat
[10:05:04.734] <TB0> INFO: readMaskFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//defaultMaskFile.dat
[10:05:04.734] <TB0> INFO: readTrimFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//trimParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//trimParameters35_C15.dat
[10:05:04.799] <TB0> INFO: clk: 4
[10:05:04.799] <TB0> INFO: ctr: 4
[10:05:04.799] <TB0> INFO: sda: 19
[10:05:04.799] <TB0> INFO: tin: 9
[10:05:04.799] <TB0> INFO: level: 15
[10:05:04.799] <TB0> INFO: triggerdelay: 0
[10:05:04.799] <TB0> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:05:04.799] <TB0> INFO: Log level: INFO
[10:05:04.812] <TB0> INFO: Found DTB DTB_WWXTQT
[10:05:04.821] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[10:05:04.824] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[10:05:04.826] <TB0> INFO: RPC call hashes of host and DTB match: 397073690
[10:05:06.343] <TB0> INFO: DUT info:
[10:05:06.343] <TB0> INFO: The DUT currently contains the following objects:
[10:05:06.343] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[10:05:06.343] <TB0> INFO: TBM Core alpha (0): 7 registers set
[10:05:06.343] <TB0> INFO: TBM Core beta (1): 7 registers set
[10:05:06.343] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:05:06.343] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:06.343] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:06.343] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:06.343] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:06.343] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:06.343] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:06.343] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:06.343] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:06.343] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:06.343] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:06.343] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:06.343] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:06.343] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:06.343] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:06.343] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:06.343] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:05:06.744] <TB0> INFO: enter 'restricted' command line mode
[10:05:06.744] <TB0> INFO: enter test to run
[10:05:06.744] <TB0> INFO: test: HighRate no parameter change
[10:05:06.744] <TB0> INFO: running: highrate
[10:05:06.744] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//013_HREfficiency_150//phCalibrationFitErr35_C15.dat
[10:05:06.870] <TB0> INFO: ----------------------------------------------------------------------
[10:05:06.871] <TB0> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:05:06.871] <TB0> INFO: ----------------------------------------------------------------------
[10:05:07.467] <TB0> INFO: Expecting 208000 events.
[10:05:26.564] <TB0> INFO: 208000 events read in total (18567ms).
[10:05:26.576] <TB0> INFO: Test took 19700ms.
[10:05:27.261] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:05:27.753] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 0 0 0 2 0 1 0 0
[10:05:27.753] <TB0> INFO: number of red-efficiency pixels: 567 849 697 1778 1616 2224 1938 2180 2392 1905 1600 1331 1317 638 532 467
[10:05:27.753] <TB0> INFO: number of X-ray hits detected: 273180 279315 259386 425490 448589 552374 505998 540580 515566 494271 458784 419043 395130 231903 244007 237676
[10:05:27.753] <TB0> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:05:27.753] <TB0> INFO: number of Vcal hits detected: 207375 207014 206984 205090 205554 204011 204683 204508 203902 204950 205640 205939 206255 207058 207400 207483
[10:05:27.753] <TB0> INFO: Vcal hit fiducial efficiency (%): 99.7 99.6 99.6 98.8 99.0 98.3 98.6 98.5 98.2 98.7 99.0 99.2 99.3 99.7 99.7 99.8
[10:05:27.753] <TB0> INFO: Vcal hit overall efficiency (%): 99.7 99.5 99.5 98.6 98.8 98.1 98.4 98.3 98.0 98.5 98.9 99.0 99.2 99.5 99.7 99.8
[10:05:27.753] <TB0> INFO: X-ray hit rate [MHz/cm2]: 80.1 81.9 76.0 124.7 131.5 161.9 148.3 158.4 151.1 144.9 134.5 122.8 115.8 68.0 71.5 69.7
[10:05:27.753] <TB0> INFO: PixTestHighRate::doXPixelAlive() done
[10:05:27.787] <TB0> INFO: PixTest:: pg_setup set to default.
[10:05:27.830] <TB0> INFO: enter test to run
[10:05:27.830] <TB0> INFO: test: no parameter change
[10:05:28.014] <TB0> QUIET: Connection to board 147 closed.
[10:05:28.094] <TB0> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[10:06:05.382] <TB0> INFO: *** Welcome to pxar ***
[10:06:05.382] <TB0> INFO: *** Today: 2015/08/28
[10:06:05.382] <TB0> INFO: readRocDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//dacParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//dacParameters35_C15.dat
[10:06:05.383] <TB0> INFO: readTbmDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//tbmParameters_C0a.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//tbmParameters_C0b.dat
[10:06:05.383] <TB0> INFO: readMaskFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//defaultMaskFile.dat
[10:06:05.383] <TB0> INFO: readTrimFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//trimParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//trimParameters35_C15.dat
[10:06:05.447] <TB0> INFO: clk: 4
[10:06:05.447] <TB0> INFO: ctr: 4
[10:06:05.447] <TB0> INFO: sda: 19
[10:06:05.447] <TB0> INFO: tin: 9
[10:06:05.447] <TB0> INFO: level: 15
[10:06:05.447] <TB0> INFO: triggerdelay: 0
[10:06:05.447] <TB0> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:06:05.447] <TB0> INFO: Log level: INFO
[10:06:05.461] <TB0> INFO: Found DTB DTB_WWXTQT
[10:06:05.469] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[10:06:05.473] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[10:06:05.475] <TB0> INFO: RPC call hashes of host and DTB match: 397073690
[10:06:06.994] <TB0> INFO: DUT info:
[10:06:06.994] <TB0> INFO: The DUT currently contains the following objects:
[10:06:06.994] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[10:06:06.994] <TB0> INFO: TBM Core alpha (0): 7 registers set
[10:06:06.994] <TB0> INFO: TBM Core beta (1): 7 registers set
[10:06:06.994] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:06:06.994] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:06.994] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:06.994] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:06.994] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:06.994] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:06.994] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:06.994] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:06.994] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:06.994] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:06.994] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:06.994] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:06.994] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:06.994] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:06.994] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:06.994] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:06.994] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:06:07.395] <TB0> INFO: enter 'restricted' command line mode
[10:06:07.395] <TB0> INFO: enter test to run
[10:06:07.395] <TB0> INFO: test: HighRate no parameter change
[10:06:07.395] <TB0> INFO: running: highrate
[10:06:07.396] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//014_HREfficiency_200//phCalibrationFitErr35_C15.dat
[10:06:07.523] <TB0> INFO: ----------------------------------------------------------------------
[10:06:07.523] <TB0> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:06:07.523] <TB0> INFO: ----------------------------------------------------------------------
[10:06:08.121] <TB0> INFO: Expecting 208000 events.
[10:06:34.196] <TB0> INFO: 208000 events read in total (25545ms).
[10:06:34.210] <TB0> INFO: Test took 26681ms.
[10:06:35.146] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:06:35.679] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 0 0 0 2 0 1 0 0
[10:06:35.679] <TB0> INFO: number of red-efficiency pixels: 1080 1559 1079 2998 2677 3403 3021 3488 3547 3087 2733 2388 2359 1081 935 824
[10:06:35.679] <TB0> INFO: number of X-ray hits detected: 370189 378090 352376 572616 604211 742229 680748 729477 693230 666456 614807 567077 535386 316818 333001 323698
[10:06:35.679] <TB0> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:06:35.679] <TB0> INFO: number of Vcal hits detected: 206665 205878 205836 200916 202381 198390 199823 199110 198363 200300 202352 203117 203691 205800 206837 207056
[10:06:35.679] <TB0> INFO: Vcal hit fiducial efficiency (%): 99.4 99.0 99.2 97.1 97.6 95.9 96.6 96.1 95.8 96.8 97.6 98.0 98.2 99.2 99.5 99.6
[10:06:35.680] <TB0> INFO: Vcal hit overall efficiency (%): 99.4 99.0 99.0 96.6 97.3 95.4 96.1 95.7 95.4 96.3 97.3 97.7 97.9 98.9 99.4 99.5
[10:06:35.680] <TB0> INFO: X-ray hit rate [MHz/cm2]: 108.5 110.8 103.3 167.8 177.1 217.6 199.5 213.8 203.2 195.3 180.2 166.2 156.9 92.9 97.6 94.9
[10:06:35.680] <TB0> INFO: PixTestHighRate::doXPixelAlive() done
[10:06:35.716] <TB0> INFO: PixTest:: pg_setup set to default.
[10:06:35.760] <TB0> INFO: enter test to run
[10:06:35.760] <TB0> INFO: test: no parameter change
[10:06:36.025] <TB0> QUIET: Connection to board 147 closed.
[10:06:36.105] <TB0> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[10:07:09.679] <TB0> INFO: *** Welcome to pxar ***
[10:07:09.679] <TB0> INFO: *** Today: 2015/08/28
[10:07:09.679] <TB0> INFO: readRocDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//dacParameters35_C15.dat
[10:07:09.679] <TB0> INFO: readTbmDacs: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//tbmParameters_C0b.dat
[10:07:09.679] <TB0> INFO: readMaskFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//defaultMaskFile.dat
[10:07:09.679] <TB0> INFO: readTrimFile: /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//trimParameters35_C15.dat
[10:07:09.748] <TB0> INFO: clk: 4
[10:07:09.748] <TB0> INFO: ctr: 4
[10:07:09.748] <TB0> INFO: sda: 19
[10:07:09.748] <TB0> INFO: tin: 9
[10:07:09.748] <TB0> INFO: level: 15
[10:07:09.748] <TB0> INFO: triggerdelay: 0
[10:07:09.748] <TB0> QUIET: Instanciating API for pxar v2.5+57~g44d0777
[10:07:09.748] <TB0> INFO: Log level: INFO
[10:07:09.762] <TB0> INFO: Found DTB DTB_WWXTQT
[10:07:09.770] <TB0> QUIET: Connection to board DTB_WWXTQT opened.
[10:07:09.773] <TB0> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 147
HW version: DTB1.2
FW version: 4.2
SW version: 4.4
USB id: DTB_WWXTQT
MAC address: 40D855118093
Hostname: pixelDTB147
Comment:
------------------------------------------------------
[10:07:09.775] <TB0> INFO: RPC call hashes of host and DTB match: 397073690
[10:07:11.291] <TB0> INFO: DUT info:
[10:07:11.291] <TB0> INFO: The DUT currently contains the following objects:
[10:07:11.291] <TB0> INFO: 2 TBM Cores tbm09c (2 ON)
[10:07:11.291] <TB0> INFO: TBM Core alpha (0): 7 registers set
[10:07:11.291] <TB0> INFO: TBM Core beta (1): 7 registers set
[10:07:11.291] <TB0> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:07:11.291] <TB0> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:11.291] <TB0> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:11.291] <TB0> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:11.291] <TB0> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:11.291] <TB0> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:11.291] <TB0> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:11.291] <TB0> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:11.291] <TB0> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:11.291] <TB0> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:11.291] <TB0> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:11.291] <TB0> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:11.291] <TB0> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:11.291] <TB0> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:11.292] <TB0> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:11.292] <TB0> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:11.292] <TB0> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:11.692] <TB0> INFO: enter 'restricted' command line mode
[10:07:11.692] <TB0> INFO: enter test to run
[10:07:11.692] <TB0> INFO: test: HighRate no parameter change
[10:07:11.692] <TB0> INFO: running: highrate
[10:07:11.693] <TB0> INFO: readGainPedestalParameters /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2084_XrayQualification_2015-08-28_10h32m_1440750764//015_HREfficiency_250//phCalibrationFitErr35_C15.dat
[10:07:11.820] <TB0> INFO: ----------------------------------------------------------------------
[10:07:11.820] <TB0> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:07:11.820] <TB0> INFO: ----------------------------------------------------------------------
[10:07:12.415] <TB0> INFO: Expecting 208000 events.
[10:07:37.867] <TB0> INFO: 208000 events read in total (24923ms).
[10:07:37.887] <TB0> INFO: Test took 26061ms.
[10:07:38.891] <TB0> INFO: Fetched DAQ statistics. Counters are being reset now.
[10:07:39.423] <TB0> INFO: number of dead pixels (per ROC): 0 0 0 2 0 0 0 0 0 0 0 2 0 1 0 0
[10:07:39.423] <TB0> INFO: number of red-efficiency pixels: 1156 1699 1195 3097 2744 3519 3207 3556 3631 3185 2812 2476 2436 1114 981 877
[10:07:39.423] <TB0> INFO: number of X-ray hits detected: 380015 391528 366967 588194 621852 758927 698933 752467 715034 685643 636130 582022 550068 328960 345064 331173
[10:07:39.423] <TB0> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:07:39.423] <TB0> INFO: number of Vcal hits detected: 206534 205634 205661 200557 202056 197613 199153 198456 197177 199590 201902 202791 203584 205786 206780 206969
[10:07:39.423] <TB0> INFO: Vcal hit fiducial efficiency (%): 99.4 98.9 99.1 96.9 97.5 95.6 96.3 95.8 95.3 96.4 97.4 97.9 98.1 99.2 99.4 99.5
[10:07:39.423] <TB0> INFO: Vcal hit overall efficiency (%): 99.3 98.9 98.9 96.4 97.1 95.0 95.7 95.4 94.8 96.0 97.1 97.5 97.9 98.9 99.4 99.5
[10:07:39.423] <TB0> INFO: X-ray hit rate [MHz/cm2]: 111.4 114.8 107.6 172.4 182.3 222.4 204.9 220.6 209.6 201.0 186.5 170.6 161.2 96.4 101.1 97.1
[10:07:39.423] <TB0> INFO: PixTestHighRate::doXPixelAlive() done
[10:07:39.456] <TB0> INFO: PixTest:: pg_setup set to default.
[10:07:39.504] <TB0> INFO: enter test to run
[10:07:39.504] <TB0> INFO: test: no parameter change
[10:07:39.696] <TB0> QUIET: Connection to board 147 closed.
[10:07:39.776] <TB0> INFO: pXar: this is the end, my friend