Test Date: 2015-08-24 17:49
Analysis date: 2016-05-26 02:32
Logfile
commander_PixelAlive.log
[15:50:55.602] <TB2> INFO: *** Welcome to pxar ***
[15:50:55.602] <TB2> INFO: *** Today: 2015/08/24
[15:50:55.602] <TB2> INFO: readRocDacs: /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//dacParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//dacParameters35_C15.dat
[15:50:55.602] <TB2> INFO: readTbmDacs: /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//tbmParameters_C0a.dat .. /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//tbmParameters_C0b.dat
[15:50:55.602] <TB2> INFO: readMaskFile: /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//defaultMaskFile.dat
[15:50:55.602] <TB2> INFO: readTrimFile: /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//trimParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//trimParameters35_C15.dat
[15:50:55.672] <TB2> INFO: clk: 4
[15:50:55.672] <TB2> INFO: ctr: 4
[15:50:55.672] <TB2> INFO: sda: 19
[15:50:55.672] <TB2> INFO: tin: 9
[15:50:55.672] <TB2> INFO: level: 15
[15:50:55.672] <TB2> INFO: triggerdelay: 0
[15:50:55.672] <TB2> QUIET: Instanciating API for pxar prod-01+86~g1838649
[15:50:55.672] <TB2> INFO: Log level: INFO
[15:50:55.685] <TB2> INFO: Found DTB DTB_WXENWR
[15:50:55.697] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[15:50:55.701] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[15:50:55.704] <TB2> INFO: RPC call hashes of host and DTB match: 447413373
[15:50:57.235] <TB2> INFO: DUT info:
[15:50:57.235] <TB2> INFO: The DUT currently contains the following objects:
[15:50:57.235] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[15:50:57.235] <TB2> INFO: TBM Core alpha (0): 7 registers set
[15:50:57.236] <TB2> INFO: TBM Core beta (1): 7 registers set
[15:50:57.236] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:50:57.236] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:57.236] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:57.236] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:57.236] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:57.236] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:57.236] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:57.236] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:57.236] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:57.236] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:57.236] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:57.236] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:57.236] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:57.236] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:57.236] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:57.236] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:57.236] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:50:57.637] <TB2> INFO: enter 'restricted' command line mode
[15:50:57.637] <TB2> INFO: enter test to run
[15:50:57.638] <TB2> INFO: test: PixelAlive no parameter change
[15:50:57.638] <TB2> INFO: running: pixelalive
[15:50:57.643] <TB2> INFO: ######################################################################
[15:50:57.643] <TB2> INFO: PixTestAlive::doTest()
[15:50:57.643] <TB2> INFO: ######################################################################
[15:50:57.645] <TB2> INFO: ----------------------------------------------------------------------
[15:50:57.645] <TB2> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:50:57.645] <TB2> INFO: ----------------------------------------------------------------------
[15:51:00.944] <TB2> INFO: Test took 3297ms.
[15:51:00.961] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:01.227] <TB2> INFO: PixTestAlive::aliveTest() done
[15:51:01.227] <TB2> INFO: number of dead pixels (per ROC): 0 1 0 0 0 1 0 0 0 0 1 0 0 0 0 0
[15:51:01.229] <TB2> INFO: ----------------------------------------------------------------------
[15:51:01.229] <TB2> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:51:01.229] <TB2> INFO: ----------------------------------------------------------------------
[15:51:03.869] <TB2> INFO: Test took 2638ms.
[15:51:03.875] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:03.876] <TB2> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:51:04.115] <TB2> INFO: PixTestAlive::maskTest() done
[15:51:04.115] <TB2> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:51:04.117] <TB2> INFO: ----------------------------------------------------------------------
[15:51:04.117] <TB2> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:51:04.117] <TB2> INFO: ----------------------------------------------------------------------
[15:51:07.538] <TB2> INFO: Test took 3419ms.
[15:51:07.560] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:07.801] <TB2> INFO: PixTestAlive::addressDecodingTest() done
[15:51:07.801] <TB2> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:51:07.801] <TB2> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[15:51:07.846] <TB2> INFO: enter test to run
[15:51:07.846] <TB2> INFO: test: no parameter change
[15:51:07.978] <TB2> QUIET: Connection to board 162 closed.
[15:51:08.058] <TB2> INFO: pXar: this is the end, my friend
commander_RetrimHotPixels.log
[15:56:25.383] <TB2> INFO: *** Welcome to pxar ***
[15:56:25.383] <TB2> INFO: *** Today: 2015/08/24
[15:56:25.383] <TB2> INFO: readRocDacs: /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//dacParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//dacParameters35_C15.dat
[15:56:25.383] <TB2> INFO: readTbmDacs: /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//tbmParameters_C0a.dat .. /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//tbmParameters_C0b.dat
[15:56:25.384] <TB2> INFO: readMaskFile: /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//defaultMaskFile.dat
[15:56:25.384] <TB2> INFO: readTrimFile: /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C15.dat
[15:56:25.456] <TB2> INFO: clk: 4
[15:56:25.456] <TB2> INFO: ctr: 4
[15:56:25.456] <TB2> INFO: sda: 19
[15:56:25.456] <TB2> INFO: tin: 9
[15:56:25.456] <TB2> INFO: level: 15
[15:56:25.456] <TB2> INFO: triggerdelay: 0
[15:56:25.456] <TB2> QUIET: Instanciating API for pxar prod-01+86~g1838649
[15:56:25.456] <TB2> INFO: Log level: INFO
[15:56:25.470] <TB2> INFO: Found DTB DTB_WXENWR
[15:56:25.482] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[15:56:25.485] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[15:56:25.488] <TB2> INFO: RPC call hashes of host and DTB match: 447413373
[15:56:27.011] <TB2> INFO: DUT info:
[15:56:27.011] <TB2> INFO: The DUT currently contains the following objects:
[15:56:27.011] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[15:56:27.011] <TB2> INFO: TBM Core alpha (0): 7 registers set
[15:56:27.012] <TB2> INFO: TBM Core beta (1): 7 registers set
[15:56:27.012] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:56:27.012] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:27.012] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:27.012] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:27.012] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:27.012] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:27.012] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:27.012] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:27.012] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:27.012] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:27.012] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:27.012] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:27.012] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:27.012] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:27.012] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:27.012] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:27.012] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:27.413] <TB2> INFO: enter 'restricted' command line mode
[15:56:27.413] <TB2> INFO: enter test to run
[15:56:27.413] <TB2> INFO: test: delay setting parameters: ->5<-
[15:56:27.413] <TB2> INFO: delay test by 5 seconds...
[15:56:32.414] <TB2> INFO: enter test to run
[15:56:32.414] <TB2> INFO: test: highrate no parameter change
[15:56:32.414] <TB2> INFO: running: highrate
[15:56:32.414] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//phCalibrationFitErr35_C15.dat
[15:56:32.555] <TB2> INFO: ----------------------------------------------------------------------
[15:56:32.555] <TB2> INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[15:56:32.555] <TB2> INFO: ----------------------------------------------------------------------
[15:56:32.555] <TB2> INFO: THR = 150, corresponding to ~ 400 MHz/cm2
[15:56:32.555] <TB2> INFO: edge/corner pixel THR is adjusted
[15:56:32.555] <TB2> INFO: PixTestHighRate::trimHotPixels: step 0...
[15:56:33.523] <TB2> INFO: Collecting data for 1 seconds...
[15:56:34.524] <TB2> INFO: Done with hot pixel readout
[15:56:39.733] <TB2> INFO: PixTest:: pg_setup set to default.
[15:56:39.734] <TB2> INFO: 1 hot pixels found in step 0
[15:56:40.702] <TB2> INFO: Collecting data for 1 seconds...
[15:56:41.704] <TB2> INFO: Done with hot pixel readout
[15:56:46.908] <TB2> INFO: PixTest:: pg_setup set to default.
[15:56:46.909] <TB2> INFO: 1 hot pixels found in step 1
[15:56:47.877] <TB2> INFO: Collecting data for 1 seconds...
[15:56:48.879] <TB2> INFO: Done with hot pixel readout
[15:56:53.996] <TB2> INFO: PixTest:: pg_setup set to default.
[15:56:53.997] <TB2> INFO: 0 hot pixels found in step 2
[15:56:54.006] <TB2> INFO: 0 hot pixels could not be trimmed and have been masked.
[15:56:54.039] <TB2> INFO: PixTest::trimHotPixels() done
[15:56:54.040] <TB2> INFO: write trim parameters into /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C0.dat
[15:56:54.047] <TB2> INFO: write trim parameters into /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C1.dat
[15:56:54.054] <TB2> INFO: write trim parameters into /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C2.dat
[15:56:54.062] <TB2> INFO: write trim parameters into /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C3.dat
[15:56:54.069] <TB2> INFO: write trim parameters into /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C4.dat
[15:56:54.076] <TB2> INFO: write trim parameters into /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C5.dat
[15:56:54.083] <TB2> INFO: write trim parameters into /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C6.dat
[15:56:54.090] <TB2> INFO: write trim parameters into /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C7.dat
[15:56:54.096] <TB2> INFO: write trim parameters into /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C8.dat
[15:56:54.104] <TB2> INFO: write trim parameters into /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C9.dat
[15:56:54.110] <TB2> INFO: write trim parameters into /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C10.dat
[15:56:54.117] <TB2> INFO: write trim parameters into /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C11.dat
[15:56:54.124] <TB2> INFO: write trim parameters into /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C12.dat
[15:56:54.131] <TB2> INFO: write trim parameters into /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C13.dat
[15:56:54.138] <TB2> INFO: write trim parameters into /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C14.dat
[15:56:54.145] <TB2> INFO: write trim parameters into /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C15.dat
[15:56:54.151] <TB2> INFO: write masked pixels into /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//defaultMaskFile.dat
[15:56:54.186] <TB2> INFO: enter test to run
[15:56:54.186] <TB2> INFO: test: no parameter change
[15:56:54.380] <TB2> QUIET: Connection to board 162 closed.
[15:56:54.459] <TB2> INFO: pXar: this is the end, my friend
commander_HRData.log
[15:58:20.157] <TB2> INFO: *** Welcome to pxar ***
[15:58:20.157] <TB2> INFO: *** Today: 2015/08/24
[15:58:20.157] <TB2> INFO: readRocDacs: /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//dacParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//dacParameters35_C15.dat
[15:58:20.158] <TB2> INFO: readTbmDacs: /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//tbmParameters_C0a.dat .. /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//tbmParameters_C0b.dat
[15:58:20.158] <TB2> INFO: readMaskFile: /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//defaultMaskFile.dat
[15:58:20.158] <TB2> INFO: readTrimFile: /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//trimParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//trimParameters35_C15.dat
[15:58:20.237] <TB2> INFO: clk: 4
[15:58:20.237] <TB2> INFO: ctr: 4
[15:58:20.237] <TB2> INFO: sda: 19
[15:58:20.237] <TB2> INFO: tin: 9
[15:58:20.237] <TB2> INFO: level: 15
[15:58:20.237] <TB2> INFO: triggerdelay: 0
[15:58:20.237] <TB2> QUIET: Instanciating API for pxar prod-01+86~g1838649
[15:58:20.237] <TB2> INFO: Log level: INFO
[15:58:20.250] <TB2> INFO: Found DTB DTB_WXENWR
[15:58:20.263] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[15:58:20.266] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[15:58:20.269] <TB2> INFO: RPC call hashes of host and DTB match: 447413373
[15:58:21.802] <TB2> INFO: DUT info:
[15:58:21.803] <TB2> INFO: The DUT currently contains the following objects:
[15:58:21.803] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[15:58:21.803] <TB2> INFO: TBM Core alpha (0): 7 registers set
[15:58:21.803] <TB2> INFO: TBM Core beta (1): 7 registers set
[15:58:21.803] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:58:21.803] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:21.803] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:21.803] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:21.803] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:21.803] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:21.803] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:21.803] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:21.803] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:21.803] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:21.803] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:21.803] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:21.803] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:21.803] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:21.803] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:21.803] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:21.803] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:22.205] <TB2> INFO: enter 'restricted' command line mode
[15:58:22.205] <TB2> INFO: enter test to run
[15:58:22.205] <TB2> INFO: test: delay setting parameters: ->5<-
[15:58:22.205] <TB2> INFO: delay test by 5 seconds...
[15:58:27.205] <TB2> INFO: enter test to run
[15:58:27.205] <TB2> INFO: test: Xray no parameter change
[15:58:27.205] <TB2> INFO: running: xray
[15:58:27.206] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//phCalibrationFitErr35_C15.dat
[15:58:27.354] <TB2> INFO: ######################################################################
[15:58:27.354] <TB2> INFO: PixTestXray::doTest()
[15:58:27.354] <TB2> INFO: ######################################################################
[15:58:27.354] <TB2> INFO: ----------------------------------------------------------------------
[15:58:27.354] <TB2> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:58:27.354] <TB2> INFO: ----------------------------------------------------------------------
[15:58:28.329] <TB2> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:58:34.397] <TB2> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:58:54.619] <TB2> INFO: Resuming triggers.
[15:59:00.697] <TB2> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[15:59:04.457] <TB2> ERROR: <datapipe.cc/CheckEventID:L453> Channel 3 Event ID mismatch: local ID (132) != TBM ID (8)

[15:59:04.457] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[15:59:04.458] <TB2> ERROR: <datapipe.cc/CheckEventID:L453> Channel 3 Event ID mismatch: local ID (9) != TBM ID (133)

[15:59:20.724] <TB2> INFO: Resuming triggers.
[15:59:26.804] <TB2> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[15:59:47.299] <TB2> INFO: Resuming triggers.
[15:59:53.385] <TB2> INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[16:00:13.092] <TB2> INFO: Resuming triggers.
[16:00:19.179] <TB2> INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[16:00:39.704] <TB2> INFO: Resuming triggers.
[16:00:45.786] <TB2> INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[16:01:07.218] <TB2> INFO: Resuming triggers.
[16:01:13.300] <TB2> INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[16:01:35.877] <TB2> INFO: Resuming triggers.
[16:01:41.969] <TB2> INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[16:02:04.472] <TB2> INFO: Resuming triggers.
[16:02:10.550] <TB2> INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[16:02:31.860] <TB2> INFO: Resuming triggers.
[16:02:37.940] <TB2> INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[16:02:59.812] <TB2> INFO: Resuming triggers.
[16:03:05.892] <TB2> INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[16:03:26.206] <TB2> INFO: Resuming triggers.
[16:03:32.289] <TB2> INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[16:03:52.288] <TB2> INFO: Resuming triggers.
[16:03:58.377] <TB2> INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[16:04:18.554] <TB2> INFO: Resuming triggers.
[16:04:24.640] <TB2> INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[16:04:43.966] <TB2> INFO: Resuming triggers.
[16:04:50.047] <TB2> INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[16:05:10.361] <TB2> INFO: Resuming triggers.
[16:05:16.453] <TB2> INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[16:05:36.658] <TB2> INFO: Resuming triggers.
[16:05:39.413] <TB2> INFO: data taking finished, elapsed time: 100 seconds.
[16:05:48.000] <TB2> INFO: PixTest:: pg_setup set to default.
[16:05:48.002] <TB2> INFO: PixTestXray::doPhRun() done
[16:05:48.002] <TB2> INFO: PixTestXray::doTest() done
[16:05:48.175] <TB2> INFO: enter test to run
[16:05:48.175] <TB2> INFO: test: no parameter change
[16:05:48.365] <TB2> QUIET: Connection to board 162 closed.
[16:05:48.446] <TB2> INFO: pXar: this is the end, my friend
commander_HRData.log
[16:07:14.455] <TB2> INFO: *** Welcome to pxar ***
[16:07:14.455] <TB2> INFO: *** Today: 2015/08/24
[16:07:14.456] <TB2> INFO: readRocDacs: /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//dacParameters35_C15.dat
[16:07:14.456] <TB2> INFO: readTbmDacs: /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//tbmParameters_C0b.dat
[16:07:14.456] <TB2> INFO: readMaskFile: /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//defaultMaskFile.dat
[16:07:14.456] <TB2> INFO: readTrimFile: /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//trimParameters35_C15.dat
[16:07:14.524] <TB2> INFO: clk: 4
[16:07:14.524] <TB2> INFO: ctr: 4
[16:07:14.524] <TB2> INFO: sda: 19
[16:07:14.524] <TB2> INFO: tin: 9
[16:07:14.524] <TB2> INFO: level: 15
[16:07:14.524] <TB2> INFO: triggerdelay: 0
[16:07:14.524] <TB2> QUIET: Instanciating API for pxar prod-01+86~g1838649
[16:07:14.524] <TB2> INFO: Log level: INFO
[16:07:15.540] <TB2> INFO: Found DTB DTB_WXENWR
[16:07:16.552] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[16:07:16.555] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[16:07:16.558] <TB2> INFO: RPC call hashes of host and DTB match: 447413373
[16:07:18.085] <TB2> INFO: DUT info:
[16:07:18.085] <TB2> INFO: The DUT currently contains the following objects:
[16:07:18.085] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[16:07:18.085] <TB2> INFO: TBM Core alpha (0): 7 registers set
[16:07:18.085] <TB2> INFO: TBM Core beta (1): 7 registers set
[16:07:18.085] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:07:18.085] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:18.085] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:18.085] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:18.085] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:18.085] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:18.085] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:18.085] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:18.085] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:18.085] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:18.085] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:18.085] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:18.085] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:18.086] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:18.086] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:18.086] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:18.086] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:18.487] <TB2> INFO: enter 'restricted' command line mode
[16:07:18.487] <TB2> INFO: enter test to run
[16:07:18.487] <TB2> INFO: test: delay setting parameters: ->5<-
[16:07:18.487] <TB2> INFO: delay test by 5 seconds...
[16:07:23.487] <TB2> INFO: enter test to run
[16:07:23.487] <TB2> INFO: test: Xray no parameter change
[16:07:23.487] <TB2> INFO: running: xray
[16:07:23.488] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//phCalibrationFitErr35_C15.dat
[16:07:23.700] <TB2> INFO: ######################################################################
[16:07:23.700] <TB2> INFO: PixTestXray::doTest()
[16:07:23.700] <TB2> INFO: ######################################################################
[16:07:23.700] <TB2> INFO: ----------------------------------------------------------------------
[16:07:23.700] <TB2> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:07:23.700] <TB2> INFO: ----------------------------------------------------------------------
[16:07:24.675] <TB2> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:07:27.900] <TB2> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[16:07:49.171] <TB2> INFO: Resuming triggers.
[16:07:52.410] <TB2> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[16:07:56.003] <TB2> ERROR: <datapipe.cc/CheckEventID:L453> Channel 3 Event ID mismatch: local ID (211) != TBM ID (17)

[16:07:56.003] <TB2> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[16:07:56.003] <TB2> ERROR: <datapipe.cc/CheckEventID:L453> Channel 3 Event ID mismatch: local ID (18) != TBM ID (212)

[16:08:13.196] <TB2> INFO: Resuming triggers.
[16:08:16.433] <TB2> INFO: run duration 9 seconds, buffer almost full (81%), pausing triggers.
[16:08:37.798] <TB2> INFO: Resuming triggers.
[16:08:41.038] <TB2> INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[16:09:02.161] <TB2> INFO: Resuming triggers.
[16:09:05.399] <TB2> INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[16:09:26.590] <TB2> INFO: Resuming triggers.
[16:09:29.831] <TB2> INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[16:09:51.492] <TB2> INFO: Resuming triggers.
[16:09:54.728] <TB2> INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[16:10:16.055] <TB2> INFO: Resuming triggers.
[16:10:19.291] <TB2> INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[16:10:40.426] <TB2> INFO: Resuming triggers.
[16:10:43.662] <TB2> INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[16:11:04.428] <TB2> INFO: Resuming triggers.
[16:11:07.663] <TB2> INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[16:11:28.415] <TB2> INFO: Resuming triggers.
[16:11:31.649] <TB2> INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[16:11:52.919] <TB2> INFO: Resuming triggers.
[16:11:56.152] <TB2> INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[16:12:18.783] <TB2> INFO: Resuming triggers.
[16:12:22.017] <TB2> INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[16:12:44.718] <TB2> INFO: Resuming triggers.
[16:12:47.951] <TB2> INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[16:13:11.145] <TB2> INFO: Resuming triggers.
[16:13:14.377] <TB2> INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[16:13:36.795] <TB2> INFO: Resuming triggers.
[16:13:40.026] <TB2> INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[16:14:02.438] <TB2> INFO: Resuming triggers.
[16:14:05.669] <TB2> INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[16:14:28.585] <TB2> INFO: Resuming triggers.
[16:14:31.815] <TB2> INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[16:14:54.451] <TB2> INFO: Resuming triggers.
[16:14:57.682] <TB2> INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[16:15:19.596] <TB2> INFO: Resuming triggers.
[16:15:22.828] <TB2> INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[16:15:44.899] <TB2> INFO: Resuming triggers.
[16:15:48.128] <TB2> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[16:16:09.556] <TB2> INFO: Resuming triggers.
[16:16:12.785] <TB2> INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[16:16:33.325] <TB2> INFO: Resuming triggers.
[16:16:36.555] <TB2> INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[16:16:56.890] <TB2> INFO: Resuming triggers.
[16:17:00.126] <TB2> INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[16:17:20.791] <TB2> INFO: Resuming triggers.
[16:17:24.023] <TB2> INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[16:17:45.297] <TB2> INFO: Resuming triggers.
[16:17:48.528] <TB2> INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[16:18:09.580] <TB2> INFO: Resuming triggers.
[16:18:12.812] <TB2> INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[16:18:35.668] <TB2> INFO: Resuming triggers.
[16:18:38.896] <TB2> INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[16:19:01.168] <TB2> INFO: Resuming triggers.
[16:19:04.396] <TB2> INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[16:19:27.743] <TB2> INFO: Resuming triggers.
[16:19:30.971] <TB2> INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[16:19:52.532] <TB2> INFO: Resuming triggers.
[16:19:55.636] <TB2> INFO: data taking finished, elapsed time: 100 seconds.
[16:20:17.090] <TB2> INFO: PixTest:: pg_setup set to default.
[16:20:17.092] <TB2> INFO: PixTestXray::doPhRun() done
[16:20:17.092] <TB2> INFO: PixTestXray::doTest() done
[16:20:17.298] <TB2> INFO: enter test to run
[16:20:17.298] <TB2> INFO: test: no parameter change
[16:20:17.491] <TB2> QUIET: Connection to board 162 closed.
[16:20:17.571] <TB2> INFO: pXar: this is the end, my friend
commander_HRSCurves.log
[16:20:50.077] <TB2> INFO: *** Welcome to pxar ***
[16:20:50.077] <TB2> INFO: *** Today: 2015/08/24
[16:20:50.077] <TB2> INFO: readRocDacs: /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//dacParameters35_C15.dat
[16:20:50.077] <TB2> INFO: readTbmDacs: /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//tbmParameters_C0b.dat
[16:20:50.078] <TB2> INFO: readMaskFile: /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//defaultMaskFile.dat
[16:20:50.078] <TB2> INFO: readTrimFile: /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//trimParameters35_C15.dat
[16:20:50.150] <TB2> INFO: clk: 4
[16:20:50.150] <TB2> INFO: ctr: 4
[16:20:50.150] <TB2> INFO: sda: 19
[16:20:50.150] <TB2> INFO: tin: 9
[16:20:50.150] <TB2> INFO: level: 15
[16:20:50.150] <TB2> INFO: triggerdelay: 0
[16:20:50.150] <TB2> QUIET: Instanciating API for pxar prod-01+86~g1838649
[16:20:50.150] <TB2> INFO: Log level: INFO
[16:20:51.163] <TB2> INFO: Found DTB DTB_WXENWR
[16:20:52.175] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[16:20:52.179] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[16:20:52.182] <TB2> INFO: RPC call hashes of host and DTB match: 447413373
[16:20:53.745] <TB2> INFO: DUT info:
[16:20:53.745] <TB2> INFO: The DUT currently contains the following objects:
[16:20:53.745] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[16:20:53.745] <TB2> INFO: TBM Core alpha (0): 7 registers set
[16:20:53.745] <TB2> INFO: TBM Core beta (1): 7 registers set
[16:20:53.745] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:20:53.745] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:53.745] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:53.745] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:53.745] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:53.745] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:53.746] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:53.746] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:53.746] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:53.746] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:53.746] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:53.746] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:53.746] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:53.746] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:53.746] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:53.746] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:53.746] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:20:54.147] <TB2> INFO: enter 'restricted' command line mode
[16:20:54.147] <TB2> INFO: enter test to run
[16:20:54.147] <TB2> INFO: test: Highrate no parameter change
[16:20:54.147] <TB2> INFO: running: highrate
[16:20:54.148] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2082_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[16:20:54.285] <TB2> INFO: ----------------------------------------------------------------------
[16:20:54.285] <TB2> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[16:20:54.285] <TB2> INFO: ----------------------------------------------------------------------
[16:20:54.292] <TB2> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 0 .. 100 (1/-1) hits flags = 384 (plus default)
[16:20:54.305] <TB2> INFO: dacScan step from 0 .. 0
[16:20:54.305] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:20:54.305] <TB2> INFO: run 1 of 1
[16:21:06.842] <TB2> INFO: Test took 12537ms.
[16:21:09.078] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:21:09.378] <TB2> INFO: dacScan step from 1 .. 1
[16:21:09.378] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:21:09.378] <TB2> INFO: run 1 of 1
[16:21:21.545] <TB2> INFO: Test took 12166ms.
[16:21:23.759] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:21:24.092] <TB2> INFO: dacScan step from 2 .. 2
[16:21:24.092] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:21:24.092] <TB2> INFO: run 1 of 1
[16:21:36.279] <TB2> INFO: Test took 12186ms.
[16:21:38.307] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:21:38.596] <TB2> INFO: dacScan step from 3 .. 3
[16:21:38.596] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:21:38.596] <TB2> INFO: run 1 of 1
[16:21:50.798] <TB2> INFO: Test took 12202ms.
[16:21:52.986] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:21:53.291] <TB2> INFO: dacScan step from 4 .. 4
[16:21:53.291] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:21:53.291] <TB2> INFO: run 1 of 1
[16:22:05.495] <TB2> INFO: Test took 12203ms.
[16:22:07.860] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:08.218] <TB2> INFO: dacScan step from 5 .. 5
[16:22:08.218] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:22:08.218] <TB2> INFO: run 1 of 1
[16:22:20.372] <TB2> INFO: Test took 12153ms.
[16:22:22.478] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:22.771] <TB2> INFO: dacScan step from 6 .. 6
[16:22:22.771] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:22:22.771] <TB2> INFO: run 1 of 1
[16:22:34.920] <TB2> INFO: Test took 12148ms.
[16:22:37.208] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:37.505] <TB2> INFO: dacScan step from 7 .. 7
[16:22:37.505] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:22:37.505] <TB2> INFO: run 1 of 1
[16:22:49.638] <TB2> INFO: Test took 12133ms.
[16:22:51.775] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:52.118] <TB2> INFO: dacScan step from 8 .. 8
[16:22:52.118] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:22:52.118] <TB2> INFO: run 1 of 1
[16:23:04.318] <TB2> INFO: Test took 12199ms.
[16:23:06.892] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:07.219] <TB2> INFO: dacScan step from 9 .. 9
[16:23:07.219] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:23:07.219] <TB2> INFO: run 1 of 1
[16:23:19.389] <TB2> INFO: Test took 12170ms.
[16:23:21.469] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:21.769] <TB2> INFO: dacScan step from 10 .. 10
[16:23:21.769] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:23:21.769] <TB2> INFO: run 1 of 1
[16:23:33.860] <TB2> INFO: Test took 12090ms.
[16:23:36.119] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:36.467] <TB2> INFO: dacScan step from 11 .. 11
[16:23:36.467] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:23:36.467] <TB2> INFO: run 1 of 1
[16:23:48.529] <TB2> INFO: Test took 12062ms.
[16:23:50.881] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:51.226] <TB2> INFO: dacScan step from 12 .. 12
[16:23:51.226] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:23:51.226] <TB2> INFO: run 1 of 1
[16:24:03.362] <TB2> INFO: Test took 12136ms.
[16:24:05.583] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:05.862] <TB2> INFO: dacScan step from 13 .. 13
[16:24:05.862] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:24:05.862] <TB2> INFO: run 1 of 1
[16:24:18.035] <TB2> INFO: Test took 12172ms.
[16:24:20.168] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:20.480] <TB2> INFO: dacScan step from 14 .. 14
[16:24:20.480] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:24:20.480] <TB2> INFO: run 1 of 1
[16:24:32.558] <TB2> INFO: Test took 12077ms.
[16:24:34.675] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:34.955] <TB2> INFO: dacScan step from 15 .. 15
[16:24:34.955] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:24:34.955] <TB2> INFO: run 1 of 1
[16:24:47.173] <TB2> INFO: Test took 12218ms.
[16:24:49.571] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:49.869] <TB2> INFO: dacScan step from 16 .. 16
[16:24:49.869] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:24:49.869] <TB2> INFO: run 1 of 1
[16:25:02.082] <TB2> INFO: Test took 12212ms.
[16:25:04.378] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:04.709] <TB2> INFO: dacScan step from 17 .. 17
[16:25:04.709] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:25:04.709] <TB2> INFO: run 1 of 1
[16:25:16.928] <TB2> INFO: Test took 12218ms.
[16:25:19.221] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:19.520] <TB2> INFO: dacScan step from 18 .. 18
[16:25:19.520] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:25:19.520] <TB2> INFO: run 1 of 1
[16:25:31.845] <TB2> INFO: Test took 12325ms.
[16:25:34.000] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:34.277] <TB2> INFO: dacScan step from 19 .. 19
[16:25:34.277] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:25:34.277] <TB2> INFO: run 1 of 1
[16:25:46.654] <TB2> INFO: Test took 12377ms.
[16:25:48.748] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:49.028] <TB2> INFO: dacScan step from 20 .. 20
[16:25:49.028] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:25:49.028] <TB2> INFO: run 1 of 1
[16:26:01.354] <TB2> INFO: Test took 12326ms.
[16:26:03.654] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:03.936] <TB2> INFO: dacScan step from 21 .. 21
[16:26:03.936] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:26:03.936] <TB2> INFO: run 1 of 1
[16:26:16.310] <TB2> INFO: Test took 12375ms.
[16:26:18.513] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:18.817] <TB2> INFO: dacScan step from 22 .. 22
[16:26:18.817] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:26:18.817] <TB2> INFO: run 1 of 1
[16:26:31.221] <TB2> INFO: Test took 12403ms.
[16:26:33.500] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:33.797] <TB2> INFO: dacScan step from 23 .. 23
[16:26:33.797] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:26:33.797] <TB2> INFO: run 1 of 1
[16:26:46.259] <TB2> INFO: Test took 12462ms.
[16:26:48.638] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:48.919] <TB2> INFO: dacScan step from 24 .. 24
[16:26:48.919] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:26:48.919] <TB2> INFO: run 1 of 1
[16:27:01.319] <TB2> INFO: Test took 12399ms.
[16:27:03.445] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:03.737] <TB2> INFO: dacScan step from 25 .. 25
[16:27:03.737] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:27:03.737] <TB2> INFO: run 1 of 1
[16:27:16.183] <TB2> INFO: Test took 12445ms.
[16:27:18.447] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:18.807] <TB2> INFO: dacScan step from 26 .. 26
[16:27:18.807] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:27:18.807] <TB2> INFO: run 1 of 1
[16:27:31.233] <TB2> INFO: Test took 12425ms.
[16:27:33.275] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:33.556] <TB2> INFO: dacScan step from 27 .. 27
[16:27:33.556] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:27:33.556] <TB2> INFO: run 1 of 1
[16:27:45.962] <TB2> INFO: Test took 12406ms.
[16:27:48.218] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:48.529] <TB2> INFO: dacScan step from 28 .. 28
[16:27:48.529] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:27:48.529] <TB2> INFO: run 1 of 1
[16:28:00.943] <TB2> INFO: Test took 12413ms.
[16:28:03.291] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:03.566] <TB2> INFO: dacScan step from 29 .. 29
[16:28:03.566] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:28:03.566] <TB2> INFO: run 1 of 1
[16:28:15.984] <TB2> INFO: Test took 12417ms.
[16:28:18.161] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:18.435] <TB2> INFO: dacScan step from 30 .. 30
[16:28:18.435] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:28:18.435] <TB2> INFO: run 1 of 1
[16:28:30.903] <TB2> INFO: Test took 12467ms.
[16:28:33.256] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:33.557] <TB2> INFO: dacScan step from 31 .. 31
[16:28:33.557] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:28:33.557] <TB2> INFO: run 1 of 1
[16:28:45.941] <TB2> INFO: Test took 12383ms.
[16:28:48.266] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:48.589] <TB2> INFO: dacScan step from 32 .. 32
[16:28:48.589] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:28:48.589] <TB2> INFO: run 1 of 1
[16:29:01.056] <TB2> INFO: Test took 12466ms.
[16:29:03.238] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:03.560] <TB2> INFO: dacScan step from 33 .. 33
[16:29:03.560] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:29:03.560] <TB2> INFO: run 1 of 1
[16:29:15.979] <TB2> INFO: Test took 12418ms.
[16:29:18.058] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:18.349] <TB2> INFO: dacScan step from 34 .. 34
[16:29:18.349] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:29:18.349] <TB2> INFO: run 1 of 1
[16:29:30.778] <TB2> INFO: Test took 12429ms.
[16:29:33.221] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:33.521] <TB2> INFO: dacScan step from 35 .. 35
[16:29:33.521] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:29:33.521] <TB2> INFO: run 1 of 1
[16:29:46.017] <TB2> INFO: Test took 12496ms.
[16:29:48.184] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:48.514] <TB2> INFO: dacScan step from 36 .. 36
[16:29:48.514] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:29:48.514] <TB2> INFO: run 1 of 1
[16:30:00.983] <TB2> INFO: Test took 12469ms.
[16:30:03.311] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:03.593] <TB2> INFO: dacScan step from 37 .. 37
[16:30:03.593] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:30:03.593] <TB2> INFO: run 1 of 1
[16:30:16.242] <TB2> INFO: Test took 12649ms.
[16:30:18.680] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:19.089] <TB2> INFO: dacScan step from 38 .. 38
[16:30:19.089] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:30:19.089] <TB2> INFO: run 1 of 1
[16:30:31.797] <TB2> INFO: Test took 12707ms.
[16:30:34.095] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:34.389] <TB2> INFO: dacScan step from 39 .. 39
[16:30:34.389] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:30:34.389] <TB2> INFO: run 1 of 1
[16:30:47.299] <TB2> INFO: Test took 12910ms.
[16:30:49.553] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:49.943] <TB2> INFO: dacScan step from 40 .. 40
[16:30:49.943] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:30:49.943] <TB2> INFO: run 1 of 1
[16:31:02.768] <TB2> INFO: Test took 12824ms.
[16:31:05.104] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:05.417] <TB2> INFO: dacScan step from 41 .. 41
[16:31:05.417] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:31:05.417] <TB2> INFO: run 1 of 1
[16:31:18.436] <TB2> INFO: Test took 13018ms.
[16:31:20.951] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:21.248] <TB2> INFO: dacScan step from 42 .. 42
[16:31:21.248] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:31:21.248] <TB2> INFO: run 1 of 1
[16:31:34.494] <TB2> INFO: Test took 13246ms.
[16:31:36.912] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:37.210] <TB2> INFO: dacScan step from 43 .. 43
[16:31:37.210] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:31:37.210] <TB2> INFO: run 1 of 1
[16:31:50.752] <TB2> INFO: Test took 13541ms.
[16:31:53.161] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:53.483] <TB2> INFO: dacScan step from 44 .. 44
[16:31:53.483] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:31:53.483] <TB2> INFO: run 1 of 1
[16:32:07.215] <TB2> INFO: Test took 13731ms.
[16:32:09.710] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:10.075] <TB2> INFO: dacScan step from 45 .. 45
[16:32:10.075] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:32:10.075] <TB2> INFO: run 1 of 1
[16:32:24.118] <TB2> INFO: Test took 14043ms.
[16:32:26.679] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:27.016] <TB2> INFO: dacScan step from 46 .. 46
[16:32:27.016] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:32:27.016] <TB2> INFO: run 1 of 1
[16:32:41.298] <TB2> INFO: Test took 14281ms.
[16:32:43.761] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:44.087] <TB2> INFO: dacScan step from 47 .. 47
[16:32:44.087] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:32:44.087] <TB2> INFO: run 1 of 1
[16:32:58.524] <TB2> INFO: Test took 14437ms.
[16:33:00.806] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:33:01.134] <TB2> INFO: dacScan step from 48 .. 48
[16:33:01.134] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:33:01.134] <TB2> INFO: run 1 of 1
[16:33:15.716] <TB2> INFO: Test took 14581ms.
[16:33:18.056] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:33:18.344] <TB2> INFO: dacScan step from 49 .. 49
[16:33:18.344] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:33:18.344] <TB2> INFO: run 1 of 1
[16:33:32.941] <TB2> INFO: Test took 14596ms.
[16:33:35.240] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:33:35.538] <TB2> INFO: dacScan step from 50 .. 50
[16:33:35.538] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:33:35.538] <TB2> INFO: run 1 of 1
[16:33:50.058] <TB2> INFO: Test took 14519ms.
[16:33:52.449] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:33:52.851] <TB2> INFO: dacScan step from 51 .. 51
[16:33:52.851] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:33:52.851] <TB2> INFO: run 1 of 1
[16:34:07.447] <TB2> INFO: Test took 14596ms.
[16:34:09.887] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:34:10.211] <TB2> INFO: dacScan step from 52 .. 52
[16:34:10.211] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:34:10.211] <TB2> INFO: run 1 of 1
[16:34:24.581] <TB2> INFO: Test took 14369ms.
[16:34:26.946] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:34:27.332] <TB2> INFO: dacScan step from 53 .. 53
[16:34:27.332] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:34:27.332] <TB2> INFO: run 1 of 1
[16:34:41.810] <TB2> INFO: Test took 14477ms.
[16:34:44.728] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:34:45.049] <TB2> INFO: dacScan step from 54 .. 54
[16:34:45.049] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:34:45.049] <TB2> INFO: run 1 of 1
[16:34:59.583] <TB2> INFO: Test took 14533ms.
[16:35:01.921] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:02.216] <TB2> INFO: dacScan step from 55 .. 55
[16:35:02.217] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:35:02.217] <TB2> INFO: run 1 of 1
[16:35:16.538] <TB2> INFO: Test took 14321ms.
[16:35:18.785] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:19.101] <TB2> INFO: dacScan step from 56 .. 56
[16:35:19.101] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:35:19.101] <TB2> INFO: run 1 of 1
[16:35:33.382] <TB2> INFO: Test took 14280ms.
[16:35:35.972] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:36.332] <TB2> INFO: dacScan step from 57 .. 57
[16:35:36.332] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:35:36.333] <TB2> INFO: run 1 of 1
[16:35:50.618] <TB2> INFO: Test took 14284ms.
[16:35:53.167] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:53.512] <TB2> INFO: dacScan step from 58 .. 58
[16:35:53.512] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:35:53.512] <TB2> INFO: run 1 of 1
[16:36:07.878] <TB2> INFO: Test took 14365ms.
[16:36:10.177] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:10.482] <TB2> INFO: dacScan step from 59 .. 59
[16:36:10.482] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:36:10.482] <TB2> INFO: run 1 of 1
[16:36:24.969] <TB2> INFO: Test took 14486ms.
[16:36:27.459] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:27.817] <TB2> INFO: dacScan step from 60 .. 60
[16:36:27.817] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:36:27.817] <TB2> INFO: run 1 of 1
[16:36:42.277] <TB2> INFO: Test took 14459ms.
[16:36:44.749] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:45.090] <TB2> INFO: dacScan step from 61 .. 61
[16:36:45.090] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:36:45.090] <TB2> INFO: run 1 of 1
[16:36:59.689] <TB2> INFO: Test took 14598ms.
[16:37:02.318] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:02.629] <TB2> INFO: dacScan step from 62 .. 62
[16:37:02.629] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:37:02.629] <TB2> INFO: run 1 of 1
[16:37:17.059] <TB2> INFO: Test took 14429ms.
[16:37:19.356] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:19.644] <TB2> INFO: dacScan step from 63 .. 63
[16:37:19.644] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:37:19.644] <TB2> INFO: run 1 of 1
[16:37:34.133] <TB2> INFO: Test took 14489ms.
[16:37:36.465] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:36.758] <TB2> INFO: dacScan step from 64 .. 64
[16:37:36.758] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:37:36.758] <TB2> INFO: run 1 of 1
[16:37:51.266] <TB2> INFO: Test took 14507ms.
[16:37:53.759] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:54.070] <TB2> INFO: dacScan step from 65 .. 65
[16:37:54.070] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:37:54.070] <TB2> INFO: run 1 of 1
[16:38:08.226] <TB2> INFO: Test took 14155ms.
[16:38:10.560] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:10.855] <TB2> INFO: dacScan step from 66 .. 66
[16:38:10.855] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:38:10.855] <TB2> INFO: run 1 of 1
[16:38:24.864] <TB2> INFO: Test took 14008ms.
[16:38:27.333] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:27.696] <TB2> INFO: dacScan step from 67 .. 67
[16:38:27.696] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:38:27.696] <TB2> INFO: run 1 of 1
[16:38:42.039] <TB2> INFO: Test took 14343ms.
[16:38:44.778] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:45.144] <TB2> INFO: dacScan step from 68 .. 68
[16:38:45.144] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:38:45.144] <TB2> INFO: run 1 of 1
[16:38:59.774] <TB2> INFO: Test took 14629ms.
[16:39:02.520] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:02.811] <TB2> INFO: dacScan step from 69 .. 69
[16:39:02.811] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:39:02.811] <TB2> INFO: run 1 of 1
[16:39:17.345] <TB2> INFO: Test took 14534ms.
[16:39:19.945] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:20.242] <TB2> INFO: dacScan step from 70 .. 70
[16:39:20.242] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:39:20.242] <TB2> INFO: run 1 of 1
[16:39:34.546] <TB2> INFO: Test took 14303ms.
[16:39:37.114] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:37.434] <TB2> INFO: dacScan step from 71 .. 71
[16:39:37.434] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:39:37.434] <TB2> INFO: run 1 of 1
[16:39:51.679] <TB2> INFO: Test took 14244ms.
[16:39:54.198] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:54.561] <TB2> INFO: dacScan step from 72 .. 72
[16:39:54.561] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:39:54.561] <TB2> INFO: run 1 of 1
[16:40:08.699] <TB2> INFO: Test took 14137ms.
[16:40:11.266] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:11.603] <TB2> INFO: dacScan step from 73 .. 73
[16:40:11.603] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:40:11.603] <TB2> INFO: run 1 of 1
[16:40:25.842] <TB2> INFO: Test took 14238ms.
[16:40:28.450] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:28.757] <TB2> INFO: dacScan step from 74 .. 74
[16:40:28.757] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:40:28.757] <TB2> INFO: run 1 of 1
[16:40:43.082] <TB2> INFO: Test took 14324ms.
[16:40:45.552] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:45.834] <TB2> INFO: dacScan step from 75 .. 75
[16:40:45.835] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:40:45.835] <TB2> INFO: run 1 of 1
[16:41:00.181] <TB2> INFO: Test took 14346ms.
[16:41:02.577] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:02.917] <TB2> INFO: dacScan step from 76 .. 76
[16:41:02.917] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:41:02.917] <TB2> INFO: run 1 of 1
[16:41:17.380] <TB2> INFO: Test took 14462ms.
[16:41:19.918] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:20.309] <TB2> INFO: dacScan step from 77 .. 77
[16:41:20.310] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:41:20.310] <TB2> INFO: run 1 of 1
[16:41:34.770] <TB2> INFO: Test took 14459ms.
[16:41:37.087] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:37.397] <TB2> INFO: dacScan step from 78 .. 78
[16:41:37.397] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:41:37.397] <TB2> INFO: run 1 of 1
[16:41:51.967] <TB2> INFO: Test took 14569ms.
[16:41:54.264] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:54.564] <TB2> INFO: dacScan step from 79 .. 79
[16:41:54.564] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:41:54.564] <TB2> INFO: run 1 of 1
[16:42:09.018] <TB2> INFO: Test took 14453ms.
[16:42:11.423] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:11.720] <TB2> INFO: dacScan step from 80 .. 80
[16:42:11.720] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:42:11.720] <TB2> INFO: run 1 of 1
[16:42:26.253] <TB2> INFO: Test took 14532ms.
[16:42:28.593] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:28.911] <TB2> INFO: dacScan step from 81 .. 81
[16:42:28.911] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:42:28.911] <TB2> INFO: run 1 of 1
[16:42:43.429] <TB2> INFO: Test took 14517ms.
[16:42:45.852] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:46.237] <TB2> INFO: dacScan step from 82 .. 82
[16:42:46.237] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:42:46.237] <TB2> INFO: run 1 of 1
[16:43:00.646] <TB2> INFO: Test took 14408ms.
[16:43:02.987] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:03.299] <TB2> INFO: dacScan step from 83 .. 83
[16:43:03.299] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:43:03.299] <TB2> INFO: run 1 of 1
[16:43:17.851] <TB2> INFO: Test took 14551ms.
[16:43:20.160] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:20.463] <TB2> INFO: dacScan step from 84 .. 84
[16:43:20.463] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:43:20.463] <TB2> INFO: run 1 of 1
[16:43:35.095] <TB2> INFO: Test took 14631ms.
[16:43:37.689] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:38.014] <TB2> INFO: dacScan step from 85 .. 85
[16:43:38.014] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:43:38.014] <TB2> INFO: run 1 of 1
[16:43:52.630] <TB2> INFO: Test took 14615ms.
[16:43:55.264] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:55.583] <TB2> INFO: dacScan step from 86 .. 86
[16:43:55.583] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:43:55.583] <TB2> INFO: run 1 of 1
[16:44:10.192] <TB2> INFO: Test took 14608ms.
[16:44:12.699] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:13.015] <TB2> INFO: dacScan step from 87 .. 87
[16:44:13.015] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:44:13.015] <TB2> INFO: run 1 of 1
[16:44:27.644] <TB2> INFO: Test took 14628ms.
[16:44:30.064] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:30.406] <TB2> INFO: dacScan step from 88 .. 88
[16:44:30.406] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:44:30.406] <TB2> INFO: run 1 of 1
[16:44:44.904] <TB2> INFO: Test took 14497ms.
[16:44:47.356] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:47.661] <TB2> INFO: dacScan step from 89 .. 89
[16:44:47.661] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:44:47.661] <TB2> INFO: run 1 of 1
[16:45:02.277] <TB2> INFO: Test took 14615ms.
[16:45:04.593] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:04.958] <TB2> INFO: dacScan step from 90 .. 90
[16:45:04.958] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:45:04.958] <TB2> INFO: run 1 of 1
[16:45:19.873] <TB2> INFO: Test took 14914ms.
[16:45:22.249] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:22.536] <TB2> INFO: dacScan step from 91 .. 91
[16:45:22.536] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:45:22.536] <TB2> INFO: run 1 of 1
[16:45:37.475] <TB2> INFO: Test took 14938ms.
[16:45:39.960] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:40.285] <TB2> INFO: dacScan step from 92 .. 92
[16:45:40.285] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:45:40.285] <TB2> INFO: run 1 of 1
[16:45:55.187] <TB2> INFO: Test took 14901ms.
[16:45:57.635] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:57.939] <TB2> INFO: dacScan step from 93 .. 93
[16:45:57.939] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:45:57.939] <TB2> INFO: run 1 of 1
[16:46:12.834] <TB2> INFO: Test took 14894ms.
[16:46:15.305] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:46:15.610] <TB2> INFO: dacScan step from 94 .. 94
[16:46:15.610] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:46:15.610] <TB2> INFO: run 1 of 1
[16:46:30.573] <TB2> INFO: Test took 14962ms.
[16:46:32.915] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:46:33.224] <TB2> INFO: dacScan step from 95 .. 95
[16:46:33.224] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:46:33.224] <TB2> INFO: run 1 of 1
[16:46:48.133] <TB2> INFO: Test took 14908ms.
[16:46:50.521] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:46:50.821] <TB2> INFO: dacScan step from 96 .. 96
[16:46:50.821] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:46:50.821] <TB2> INFO: run 1 of 1
[16:47:05.759] <TB2> INFO: Test took 14937ms.
[16:47:08.222] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:47:08.553] <TB2> INFO: dacScan step from 97 .. 97
[16:47:08.553] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:47:08.553] <TB2> INFO: run 1 of 1
[16:47:23.483] <TB2> INFO: Test took 14929ms.
[16:47:25.834] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:47:26.177] <TB2> INFO: dacScan step from 98 .. 98
[16:47:26.177] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:47:26.177] <TB2> INFO: run 1 of 1
[16:47:41.059] <TB2> INFO: Test took 14881ms.
[16:47:43.495] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:47:43.804] <TB2> INFO: dacScan step from 99 .. 99
[16:47:43.804] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:47:43.804] <TB2> INFO: run 1 of 1
[16:47:58.621] <TB2> INFO: Test took 14816ms.
[16:48:01.193] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:48:01.492] <TB2> INFO: dacScan step from 100 .. 100
[16:48:01.492] <TB2> INFO: dacScan split into 1 runs with ntrig = 50
[16:48:01.492] <TB2> INFO: run 1 of 1
[16:48:16.216] <TB2> INFO: Test took 14723ms.
[16:48:18.827] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:48:19.121] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:20.490] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:21.836] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:23.183] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:24.683] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:26.135] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:27.796] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:29.257] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:30.750] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:32.240] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:33.735] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:35.213] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:36.717] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:38.170] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:39.514] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:40.849] <TB2> INFO: dumping ASCII scurve output file: XSCurveData
[16:48:42.167] <TB2> INFO: PixTest:: pg_setup set to default.
[16:48:42.169] <TB2> INFO: vcal mean: 39.74 39.56 39.13 43.45 42.18 44.00 44.39 44.55 43.84 43.64 45.25 43.23 43.87 40.88 40.70 40.33
[16:48:42.169] <TB2> INFO: vcal RMS: 1.34 1.40 1.08 1.62 1.30 1.66 1.58 1.56 1.62 1.46 1.65 1.61 1.52 1.57 1.37 1.31
[16:48:42.169] <TB2> INFO: number of X-ray hits detected: 216431 222377 194643 390479 356789 446289 449244 456846 426744 404580 478567 389089 414645 235185 244095 230695
[16:48:42.169] <TB2> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:48:42.169] <TB2> INFO: X-ray hit rate [MHz/cm2]: 63.4 65.2 57.1 114.5 104.6 130.8 131.7 133.9 125.1 118.6 140.3 114.0 121.5 68.9 71.5 67.6
[16:48:42.169] <TB2> INFO: PixTestHighRate::doXNoiseMaps() done
[16:48:42.265] <TB2> INFO: enter test to run
[16:48:42.265] <TB2> INFO: test: no parameter change
[16:48:42.438] <TB2> QUIET: Connection to board 162 closed.
[16:48:42.518] <TB2> INFO: pXar: this is the end, my friend
commander_CalDelScanAndSaveDacs.log
[17:47:26.672] <TB2> INFO: *** Welcome to pxar ***
[17:47:26.672] <TB2> INFO: *** Today: 2015/08/24
[17:47:26.672] <TB2> INFO: readRocDacs: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[17:47:26.673] <TB2> INFO: readTbmDacs: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0a.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0b.dat
[17:47:26.673] <TB2> INFO: readMaskFile: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//defaultMaskFile.dat
[17:47:26.673] <TB2> INFO: readTrimFile: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C15.dat
[17:47:26.744] <TB2> INFO: clk: 4
[17:47:26.744] <TB2> INFO: ctr: 4
[17:47:26.744] <TB2> INFO: sda: 19
[17:47:26.744] <TB2> INFO: tin: 9
[17:47:26.744] <TB2> INFO: level: 15
[17:47:26.744] <TB2> INFO: triggerdelay: 0
[17:47:26.744] <TB2> QUIET: Instanciating API for pxar prod-01+86~g1838649
[17:47:26.744] <TB2> INFO: Log level: INFO
[17:47:26.758] <TB2> INFO: Found DTB DTB_WXENWR
[17:47:26.771] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[17:47:26.775] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[17:47:26.778] <TB2> INFO: RPC call hashes of host and DTB match: 447413373
[17:47:28.311] <TB2> INFO: DUT info:
[17:47:28.311] <TB2> INFO: The DUT currently contains the following objects:
[17:47:28.311] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[17:47:28.311] <TB2> INFO: TBM Core alpha (0): 7 registers set
[17:47:28.311] <TB2> INFO: TBM Core beta (1): 7 registers set
[17:47:28.311] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:47:28.311] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:28.311] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:28.311] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:28.311] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:28.311] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:28.311] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:28.311] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:28.311] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:28.311] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:28.311] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:28.311] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:28.311] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:28.311] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:28.312] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:28.312] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:28.312] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:28.713] <TB2> INFO: enter 'restricted' command line mode
[17:47:28.713] <TB2> INFO: enter test to run
[17:47:28.713] <TB2> INFO: test: HighRate no parameter change
[17:47:28.713] <TB2> INFO: running: highrate
[17:47:28.713] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C15.dat
[17:47:28.861] <TB2> INFO: ----------------------------------------------------------------------
[17:47:28.861] <TB2> INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:47:28.861] <TB2> INFO: ----------------------------------------------------------------------
[17:47:29.949] <TB2> INFO: Test took 1081ms.
[17:47:33.380] <TB2> INFO: Test took 3425ms.
[17:47:33.555] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:37.125] <TB2> INFO: Test took 3539ms.
[17:47:37.302] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:40.887] <TB2> INFO: Test took 3552ms.
[17:47:41.065] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:44.585] <TB2> INFO: Test took 3488ms.
[17:47:44.767] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:48.312] <TB2> INFO: Test took 3514ms.
[17:47:48.482] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:52.016] <TB2> INFO: Test took 3493ms.
[17:47:52.182] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:55.722] <TB2> INFO: Test took 3509ms.
[17:47:55.888] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:59.546] <TB2> INFO: Test took 3627ms.
[17:47:59.738] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:03.359] <TB2> INFO: Test took 3583ms.
[17:48:03.547] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:07.175] <TB2> INFO: Test took 3591ms.
[17:48:07.371] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:10.985] <TB2> INFO: Test took 3573ms.
[17:48:11.172] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:14.741] <TB2> INFO: Test took 3535ms.
[17:48:14.949] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:18.457] <TB2> INFO: Test took 3467ms.
[17:48:18.634] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:22.151] <TB2> INFO: Test took 3485ms.
[17:48:22.348] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:25.893] <TB2> INFO: Test took 3508ms.
[17:48:26.078] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:29.643] <TB2> INFO: Test took 3533ms.
[17:48:29.833] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:33.384] <TB2> INFO: Test took 3514ms.
[17:48:33.555] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:37.162] <TB2> INFO: Test took 3576ms.
[17:48:37.357] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:40.953] <TB2> INFO: Test took 3563ms.
[17:48:41.130] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:44.669] <TB2> INFO: Test took 3498ms.
[17:48:44.837] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:45.177] <TB2> INFO: enter test to run
[17:48:45.177] <TB2> INFO: test: Pretest no parameter change
[17:48:45.178] <TB2> INFO: running: pretest
[17:48:45.178] <TB2> INFO: write dac parameters into /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat
[17:48:45.178] <TB2> INFO: write dac parameters into /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C1.dat
[17:48:45.178] <TB2> INFO: write dac parameters into /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C2.dat
[17:48:45.178] <TB2> INFO: write dac parameters into /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C3.dat
[17:48:45.178] <TB2> INFO: write dac parameters into /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C4.dat
[17:48:45.178] <TB2> INFO: write dac parameters into /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C5.dat
[17:48:45.178] <TB2> INFO: write dac parameters into /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C6.dat
[17:48:45.178] <TB2> INFO: write dac parameters into /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C7.dat
[17:48:45.178] <TB2> INFO: write dac parameters into /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C8.dat
[17:48:45.178] <TB2> INFO: write dac parameters into /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C9.dat
[17:48:45.179] <TB2> INFO: write dac parameters into /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C10.dat
[17:48:45.179] <TB2> INFO: write dac parameters into /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C11.dat
[17:48:45.179] <TB2> INFO: write dac parameters into /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C12.dat
[17:48:45.179] <TB2> INFO: write dac parameters into /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C13.dat
[17:48:45.179] <TB2> INFO: write dac parameters into /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C14.dat
[17:48:45.179] <TB2> INFO: write dac parameters into /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[17:48:45.179] <TB2> INFO: enter test to run
[17:48:45.179] <TB2> INFO: test: no parameter change
[17:48:45.341] <TB2> QUIET: Connection to board 162 closed.
[17:48:45.421] <TB2> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[17:49:26.488] <TB2> INFO: *** Welcome to pxar ***
[17:49:26.488] <TB2> INFO: *** Today: 2015/08/24
[17:49:26.488] <TB2> INFO: readRocDacs: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//dacParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//dacParameters35_C15.dat
[17:49:26.489] <TB2> INFO: readTbmDacs: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//tbmParameters_C0a.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//tbmParameters_C0b.dat
[17:49:26.489] <TB2> INFO: readMaskFile: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//defaultMaskFile.dat
[17:49:26.489] <TB2> INFO: readTrimFile: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//trimParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//trimParameters35_C15.dat
[17:49:26.558] <TB2> INFO: clk: 4
[17:49:26.558] <TB2> INFO: ctr: 4
[17:49:26.558] <TB2> INFO: sda: 19
[17:49:26.558] <TB2> INFO: tin: 9
[17:49:26.558] <TB2> INFO: level: 15
[17:49:26.558] <TB2> INFO: triggerdelay: 0
[17:49:26.558] <TB2> QUIET: Instanciating API for pxar prod-01+86~g1838649
[17:49:26.558] <TB2> INFO: Log level: INFO
[17:49:26.573] <TB2> INFO: Found DTB DTB_WXENWR
[17:49:26.587] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[17:49:26.590] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[17:49:26.593] <TB2> INFO: RPC call hashes of host and DTB match: 447413373
[17:49:28.128] <TB2> INFO: DUT info:
[17:49:28.128] <TB2> INFO: The DUT currently contains the following objects:
[17:49:28.128] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[17:49:28.128] <TB2> INFO: TBM Core alpha (0): 7 registers set
[17:49:28.128] <TB2> INFO: TBM Core beta (1): 7 registers set
[17:49:28.128] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:49:28.128] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:28.128] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:28.128] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:28.128] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:28.128] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:28.129] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:28.129] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:28.129] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:28.129] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:28.129] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:28.129] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:28.129] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:28.129] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:28.129] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:28.129] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:28.129] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:28.530] <TB2> INFO: enter 'restricted' command line mode
[17:49:28.530] <TB2> INFO: enter test to run
[17:49:28.530] <TB2> INFO: test: HighRate no parameter change
[17:49:28.530] <TB2> INFO: running: highrate
[17:49:28.531] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//phCalibrationFitErr35_C15.dat
[17:49:28.683] <TB2> INFO: ----------------------------------------------------------------------
[17:49:28.683] <TB2> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:49:28.683] <TB2> INFO: ----------------------------------------------------------------------
[17:49:40.012] <TB2> INFO: Test took 11322ms.
[17:49:41.092] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:49:41.415] <TB2> INFO: number of dead pixels (per ROC): 0 1 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[17:49:41.415] <TB2> INFO: number of red-efficiency pixels: 142 164 149 316 250 353 457 415 359 339 483 296 354 228 188 152
[17:49:41.415] <TB2> INFO: number of X-ray hits detected: 109185 110769 95152 196939 178571 223447 228269 232282 211742 202821 247537 198188 211075 116715 122604 116771
[17:49:41.415] <TB2> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:49:41.415] <TB2> INFO: number of Vcal hits detected: 207857 207785 207844 207669 207739 207571 207512 207566 207608 207631 207477 207687 207622 207751 207806 207841
[17:49:41.415] <TB2> INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.9 99.8 99.9 99.9 99.9
[17:49:41.415] <TB2> INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.9 99.8 99.8 99.8 99.8 99.8 99.7 99.8 99.8 99.9 99.9 99.9
[17:49:41.415] <TB2> INFO: X-ray hit rate [MHz/cm2]: 32.0 32.5 27.9 57.7 52.3 65.5 66.9 68.1 62.1 59.4 72.6 58.1 61.9 34.2 35.9 34.2
[17:49:41.415] <TB2> INFO: PixTestHighRate::doXPixelAlive() done
[17:49:41.462] <TB2> INFO: PixTest:: pg_setup set to default.
[17:49:41.506] <TB2> INFO: enter test to run
[17:49:41.507] <TB2> INFO: test: no parameter change
[17:49:41.653] <TB2> QUIET: Connection to board 162 closed.
[17:49:41.736] <TB2> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[17:50:17.005] <TB2> INFO: *** Welcome to pxar ***
[17:50:17.005] <TB2> INFO: *** Today: 2015/08/24
[17:50:17.005] <TB2> INFO: readRocDacs: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//dacParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//dacParameters35_C15.dat
[17:50:17.006] <TB2> INFO: readTbmDacs: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//tbmParameters_C0a.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//tbmParameters_C0b.dat
[17:50:17.006] <TB2> INFO: readMaskFile: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//defaultMaskFile.dat
[17:50:17.006] <TB2> INFO: readTrimFile: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//trimParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//trimParameters35_C15.dat
[17:50:17.074] <TB2> INFO: clk: 4
[17:50:17.074] <TB2> INFO: ctr: 4
[17:50:17.074] <TB2> INFO: sda: 19
[17:50:17.074] <TB2> INFO: tin: 9
[17:50:17.074] <TB2> INFO: level: 15
[17:50:17.074] <TB2> INFO: triggerdelay: 0
[17:50:17.074] <TB2> QUIET: Instanciating API for pxar prod-01+86~g1838649
[17:50:17.074] <TB2> INFO: Log level: INFO
[17:50:17.087] <TB2> INFO: Found DTB DTB_WXENWR
[17:50:17.101] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[17:50:17.104] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[17:50:17.107] <TB2> INFO: RPC call hashes of host and DTB match: 447413373
[17:50:18.638] <TB2> INFO: DUT info:
[17:50:18.638] <TB2> INFO: The DUT currently contains the following objects:
[17:50:18.638] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[17:50:18.638] <TB2> INFO: TBM Core alpha (0): 7 registers set
[17:50:18.638] <TB2> INFO: TBM Core beta (1): 7 registers set
[17:50:18.638] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:50:18.638] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:18.638] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:18.638] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:18.638] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:18.638] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:18.638] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:18.638] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:18.638] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:18.638] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:18.638] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:18.638] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:18.638] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:18.638] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:18.638] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:18.638] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:18.638] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:19.039] <TB2> INFO: enter 'restricted' command line mode
[17:50:19.039] <TB2> INFO: enter test to run
[17:50:19.040] <TB2> INFO: test: HighRate no parameter change
[17:50:19.040] <TB2> INFO: running: highrate
[17:50:19.040] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//phCalibrationFitErr35_C15.dat
[17:50:19.183] <TB2> INFO: ----------------------------------------------------------------------
[17:50:19.183] <TB2> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:50:19.183] <TB2> INFO: ----------------------------------------------------------------------
[17:50:33.063] <TB2> INFO: Test took 13872ms.
[17:50:35.846] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:50:36.265] <TB2> INFO: number of dead pixels (per ROC): 0 1 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[17:50:36.265] <TB2> INFO: number of red-efficiency pixels: 329 538 371 1113 939 1429 1505 1596 1483 1307 1814 1174 1398 723 534 455
[17:50:36.265] <TB2> INFO: number of X-ray hits detected: 218025 224085 194459 389081 356373 440975 450737 458694 424105 404874 485811 394551 417597 235580 245493 232682
[17:50:36.265] <TB2> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:50:36.265] <TB2> INFO: number of Vcal hits detected: 207654 207371 207545 206590 206809 206026 205748 205853 206053 206259 205240 206409 206099 206908 207423 207490
[17:50:36.265] <TB2> INFO: Vcal hit fiducial efficiency (%): 99.8 99.7 99.8 99.4 99.5 99.2 99.1 99.0 99.1 99.3 98.9 99.3 99.2 99.6 99.7 99.8
[17:50:36.265] <TB2> INFO: Vcal hit overall efficiency (%): 99.8 99.7 99.8 99.3 99.4 99.1 98.9 99.0 99.1 99.2 98.7 99.2 99.1 99.5 99.7 99.8
[17:50:36.265] <TB2> INFO: X-ray hit rate [MHz/cm2]: 63.9 65.7 57.0 114.0 104.5 129.3 132.1 134.4 124.3 118.7 142.4 115.6 122.4 69.1 72.0 68.2
[17:50:36.265] <TB2> INFO: PixTestHighRate::doXPixelAlive() done
[17:50:36.309] <TB2> INFO: PixTest:: pg_setup set to default.
[17:50:36.348] <TB2> INFO: enter test to run
[17:50:36.348] <TB2> INFO: test: no parameter change
[17:50:36.507] <TB2> QUIET: Connection to board 162 closed.
[17:50:36.587] <TB2> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[17:51:11.125] <TB2> INFO: *** Welcome to pxar ***
[17:51:11.125] <TB2> INFO: *** Today: 2015/08/24
[17:51:11.125] <TB2> INFO: readRocDacs: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//dacParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//dacParameters35_C15.dat
[17:51:11.126] <TB2> INFO: readTbmDacs: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//tbmParameters_C0a.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//tbmParameters_C0b.dat
[17:51:11.126] <TB2> INFO: readMaskFile: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//defaultMaskFile.dat
[17:51:11.126] <TB2> INFO: readTrimFile: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//trimParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//trimParameters35_C15.dat
[17:51:11.196] <TB2> INFO: clk: 4
[17:51:11.196] <TB2> INFO: ctr: 4
[17:51:11.196] <TB2> INFO: sda: 19
[17:51:11.196] <TB2> INFO: tin: 9
[17:51:11.196] <TB2> INFO: level: 15
[17:51:11.196] <TB2> INFO: triggerdelay: 0
[17:51:11.196] <TB2> QUIET: Instanciating API for pxar prod-01+86~g1838649
[17:51:11.196] <TB2> INFO: Log level: INFO
[17:51:11.208] <TB2> INFO: Found DTB DTB_WXENWR
[17:51:11.221] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[17:51:11.225] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[17:51:11.228] <TB2> INFO: RPC call hashes of host and DTB match: 447413373
[17:51:12.774] <TB2> INFO: DUT info:
[17:51:12.774] <TB2> INFO: The DUT currently contains the following objects:
[17:51:12.774] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[17:51:12.774] <TB2> INFO: TBM Core alpha (0): 7 registers set
[17:51:12.775] <TB2> INFO: TBM Core beta (1): 7 registers set
[17:51:12.775] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:51:12.775] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:12.775] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:12.775] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:12.775] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:12.775] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:12.775] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:12.775] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:12.775] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:12.775] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:12.775] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:12.775] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:12.775] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:12.775] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:12.775] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:12.775] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:12.775] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:13.176] <TB2> INFO: enter 'restricted' command line mode
[17:51:13.176] <TB2> INFO: enter test to run
[17:51:13.176] <TB2> INFO: test: HighRate no parameter change
[17:51:13.176] <TB2> INFO: running: highrate
[17:51:13.176] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//phCalibrationFitErr35_C15.dat
[17:51:13.326] <TB2> INFO: ----------------------------------------------------------------------
[17:51:13.326] <TB2> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:51:13.326] <TB2> INFO: ----------------------------------------------------------------------
[17:51:29.355] <TB2> INFO: Test took 16022ms.
[17:51:34.386] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:51:34.880] <TB2> INFO: number of dead pixels (per ROC): 0 1 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[17:51:34.880] <TB2> INFO: number of red-efficiency pixels: 748 1023 813 2208 1840 2695 2782 2993 2771 2471 3150 2364 2550 1310 1113 903
[17:51:34.880] <TB2> INFO: number of X-ray hits detected: 313864 323969 282746 554339 511028 627241 643240 656774 610230 583432 690342 564269 595748 343500 354876 334505
[17:51:34.880] <TB2> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:51:34.880] <TB2> INFO: number of Vcal hits detected: 207142 206728 206696 204042 204951 202495 201499 201868 202903 203123 200262 203438 203011 205100 206631 206909
[17:51:34.880] <TB2> INFO: Vcal hit fiducial efficiency (%): 99.6 99.5 99.5 98.3 98.7 97.7 97.3 97.3 97.8 97.9 96.7 98.1 97.9 98.9 99.4 99.5
[17:51:34.880] <TB2> INFO: Vcal hit overall efficiency (%): 99.6 99.4 99.4 98.1 98.5 97.4 96.9 97.1 97.5 97.7 96.3 97.8 97.6 98.6 99.3 99.5
[17:51:34.880] <TB2> INFO: X-ray hit rate [MHz/cm2]: 92.0 95.0 82.9 162.5 149.8 183.8 188.5 192.5 178.9 171.0 202.3 165.4 174.6 100.7 104.0 98.0
[17:51:34.881] <TB2> INFO: PixTestHighRate::doXPixelAlive() done
[17:51:34.926] <TB2> INFO: PixTest:: pg_setup set to default.
[17:51:34.966] <TB2> INFO: enter test to run
[17:51:34.966] <TB2> INFO: test: no parameter change
[17:51:35.107] <TB2> QUIET: Connection to board 162 closed.
[17:51:35.187] <TB2> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[17:52:08.351] <TB2> INFO: *** Welcome to pxar ***
[17:52:08.352] <TB2> INFO: *** Today: 2015/08/24
[17:52:08.352] <TB2> INFO: readRocDacs: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//dacParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//dacParameters35_C15.dat
[17:52:08.353] <TB2> INFO: readTbmDacs: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//tbmParameters_C0a.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//tbmParameters_C0b.dat
[17:52:08.353] <TB2> INFO: readMaskFile: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//defaultMaskFile.dat
[17:52:08.353] <TB2> INFO: readTrimFile: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//trimParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//trimParameters35_C15.dat
[17:52:08.425] <TB2> INFO: clk: 4
[17:52:08.425] <TB2> INFO: ctr: 4
[17:52:08.425] <TB2> INFO: sda: 19
[17:52:08.425] <TB2> INFO: tin: 9
[17:52:08.425] <TB2> INFO: level: 15
[17:52:08.425] <TB2> INFO: triggerdelay: 0
[17:52:08.425] <TB2> QUIET: Instanciating API for pxar prod-01+86~g1838649
[17:52:08.425] <TB2> INFO: Log level: INFO
[17:52:08.439] <TB2> INFO: Found DTB DTB_WXENWR
[17:52:08.452] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[17:52:08.455] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[17:52:08.458] <TB2> INFO: RPC call hashes of host and DTB match: 447413373
[17:52:09.987] <TB2> INFO: DUT info:
[17:52:09.988] <TB2> INFO: The DUT currently contains the following objects:
[17:52:09.988] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[17:52:09.988] <TB2> INFO: TBM Core alpha (0): 7 registers set
[17:52:09.988] <TB2> INFO: TBM Core beta (1): 7 registers set
[17:52:09.988] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:52:09.988] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:09.988] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:09.988] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:09.988] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:09.988] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:09.988] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:09.988] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:09.988] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:09.988] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:09.988] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:09.988] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:09.988] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:09.988] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:09.988] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:09.988] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:09.988] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:10.390] <TB2> INFO: enter 'restricted' command line mode
[17:52:10.390] <TB2> INFO: enter test to run
[17:52:10.390] <TB2> INFO: test: HighRate no parameter change
[17:52:10.390] <TB2> INFO: running: highrate
[17:52:10.390] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//phCalibrationFitErr35_C15.dat
[17:52:10.529] <TB2> INFO: ----------------------------------------------------------------------
[17:52:10.529] <TB2> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:52:10.529] <TB2> INFO: ----------------------------------------------------------------------
[17:52:28.526] <TB2> INFO: Test took 17989ms.
[17:52:37.436] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:52:38.023] <TB2> INFO: number of dead pixels (per ROC): 0 1 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[17:52:38.023] <TB2> INFO: number of red-efficiency pixels: 1364 1881 1314 3353 2982 3735 3793 3961 3839 3600 4016 3507 3713 1878 1995 1632
[17:52:38.023] <TB2> INFO: number of X-ray hits detected: 425101 440798 386400 743564 684317 834764 856589 877990 822794 784852 924294 756074 801795 463801 478378 450261
[17:52:38.023] <TB2> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:52:38.023] <TB2> INFO: number of Vcal hits detected: 206205 205207 205094 198470 200587 194709 192699 192824 195147 196211 189332 197269 195751 201676 205025 205596
[17:52:38.023] <TB2> INFO: Vcal hit fiducial efficiency (%): 99.2 98.8 98.9 96.0 96.9 94.4 93.6 93.3 94.4 95.0 92.0 95.5 94.7 97.5 98.7 99.0
[17:52:38.023] <TB2> INFO: Vcal hit overall efficiency (%): 99.1 98.7 98.6 95.4 96.4 93.6 92.6 92.7 93.8 94.3 91.0 94.8 94.1 97.0 98.6 98.8
[17:52:38.023] <TB2> INFO: X-ray hit rate [MHz/cm2]: 124.6 129.2 113.3 217.9 200.6 244.7 251.1 257.3 241.2 230.0 270.9 221.6 235.0 135.9 140.2 132.0
[17:52:38.023] <TB2> INFO: PixTestHighRate::doXPixelAlive() done
[17:52:38.074] <TB2> INFO: PixTest:: pg_setup set to default.
[17:52:38.126] <TB2> INFO: enter test to run
[17:52:38.126] <TB2> INFO: test: no parameter change
[17:52:38.312] <TB2> QUIET: Connection to board 162 closed.
[17:52:38.391] <TB2> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[17:53:12.495] <TB2> INFO: *** Welcome to pxar ***
[17:53:12.495] <TB2> INFO: *** Today: 2015/08/24
[17:53:12.495] <TB2> INFO: readRocDacs: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//dacParameters35_C15.dat
[17:53:12.496] <TB2> INFO: readTbmDacs: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//tbmParameters_C0b.dat
[17:53:12.497] <TB2> INFO: readMaskFile: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//defaultMaskFile.dat
[17:53:12.497] <TB2> INFO: readTrimFile: /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//trimParameters35_C15.dat
[17:53:12.576] <TB2> INFO: clk: 4
[17:53:12.576] <TB2> INFO: ctr: 4
[17:53:12.576] <TB2> INFO: sda: 19
[17:53:12.576] <TB2> INFO: tin: 9
[17:53:12.576] <TB2> INFO: level: 15
[17:53:12.576] <TB2> INFO: triggerdelay: 0
[17:53:12.576] <TB2> QUIET: Instanciating API for pxar prod-01+86~g1838649
[17:53:12.576] <TB2> INFO: Log level: INFO
[17:53:12.590] <TB2> INFO: Found DTB DTB_WXENWR
[17:53:12.603] <TB2> QUIET: Connection to board DTB_WXENWR opened.
[17:53:12.606] <TB2> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 162
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WXENWR
MAC address: 40D8551180A2
Hostname: pixelDTB162
Comment:
------------------------------------------------------
[17:53:12.609] <TB2> INFO: RPC call hashes of host and DTB match: 447413373
[17:53:14.142] <TB2> INFO: DUT info:
[17:53:14.142] <TB2> INFO: The DUT currently contains the following objects:
[17:53:14.142] <TB2> INFO: 2 TBM Cores tbm09c (2 ON)
[17:53:14.142] <TB2> INFO: TBM Core alpha (0): 7 registers set
[17:53:14.143] <TB2> INFO: TBM Core beta (1): 7 registers set
[17:53:14.143] <TB2> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:53:14.143] <TB2> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:14.143] <TB2> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:14.143] <TB2> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:14.143] <TB2> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:14.143] <TB2> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:14.143] <TB2> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:14.143] <TB2> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:14.143] <TB2> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:14.143] <TB2> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:14.143] <TB2> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:14.143] <TB2> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:14.143] <TB2> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:14.143] <TB2> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:14.143] <TB2> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:14.143] <TB2> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:14.143] <TB2> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:14.544] <TB2> INFO: enter 'restricted' command line mode
[17:53:14.544] <TB2> INFO: enter test to run
[17:53:14.544] <TB2> INFO: test: HighRate no parameter change
[17:53:14.544] <TB2> INFO: running: highrate
[17:53:14.544] <TB2> INFO: readGainPedestalParameters /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2082_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//phCalibrationFitErr35_C15.dat
[17:53:14.719] <TB2> INFO: ----------------------------------------------------------------------
[17:53:14.719] <TB2> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:53:14.719] <TB2> INFO: ----------------------------------------------------------------------
[17:53:32.600] <TB2> INFO: Test took 17874ms.
[17:53:41.794] <TB2> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:53:42.392] <TB2> INFO: number of dead pixels (per ROC): 0 1 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[17:53:42.392] <TB2> INFO: number of red-efficiency pixels: 1452 2139 1393 3474 3123 3800 3843 4013 3924 3719 4014 3599 3766 2009 2076 1653
[17:53:42.392] <TB2> INFO: number of X-ray hits detected: 436278 456036 400898 760123 701895 855170 880682 901474 848562 808825 939715 776883 816352 476639 490515 458717
[17:53:42.392] <TB2> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:53:42.392] <TB2> INFO: number of Vcal hits detected: 206051 204706 204940 197839 200049 193730 191612 191318 193524 194862 188821 196490 195219 201241 204829 205531
[17:53:42.392] <TB2> INFO: Vcal hit fiducial efficiency (%): 99.1 98.5 98.8 95.7 96.7 93.9 93.1 92.6 93.6 94.4 91.8 95.1 94.5 97.3 98.6 99.0
[17:53:42.392] <TB2> INFO: Vcal hit overall efficiency (%): 99.1 98.4 98.5 95.1 96.2 93.1 92.1 92.0 93.0 93.7 90.8 94.5 93.9 96.8 98.5 98.8
[17:53:42.392] <TB2> INFO: X-ray hit rate [MHz/cm2]: 127.9 133.7 117.5 222.8 205.7 250.7 258.1 264.2 248.7 237.1 275.4 227.7 239.3 139.7 143.8 134.5
[17:53:42.392] <TB2> INFO: PixTestHighRate::doXPixelAlive() done
[17:53:42.438] <TB2> INFO: PixTest:: pg_setup set to default.
[17:53:42.478] <TB2> INFO: enter test to run
[17:53:42.478] <TB2> INFO: test: no parameter change
[17:53:42.655] <TB2> QUIET: Connection to board 162 closed.
[17:53:42.737] <TB2> INFO: pXar: this is the end, my friend
MoReWeb-v1.0.0-5-g82d9ff6 on branch psi46master