Test Date: 2015-08-11 15:16
Analysis date: 2016-05-26 01:05
Logfile
commander_PixelAlive.log
[13:17:34.845] <TB3> INFO: *** Welcome to pxar ***
[13:17:34.845] <TB3> INFO: *** Today: 2015/08/11
[13:17:34.845] <TB3> INFO: readRocDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//000_PixelAlive_p17//dacParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//000_PixelAlive_p17//dacParameters35_C15.dat
[13:17:34.846] <TB3> INFO: readTbmDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//000_PixelAlive_p17//tbmParameters_C0a.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//000_PixelAlive_p17//tbmParameters_C0b.dat
[13:17:34.846] <TB3> INFO: readMaskFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//000_PixelAlive_p17//defaultMaskFile.dat
[13:17:34.846] <TB3> INFO: readTrimFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//000_PixelAlive_p17//trimParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//000_PixelAlive_p17//trimParameters35_C15.dat
[13:17:34.947] <TB3> INFO: clk: 4
[13:17:34.947] <TB3> INFO: ctr: 4
[13:17:34.947] <TB3> INFO: sda: 19
[13:17:34.947] <TB3> INFO: tin: 9
[13:17:34.947] <TB3> INFO: level: 15
[13:17:34.947] <TB3> INFO: triggerdelay: 0
[13:17:34.947] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[13:17:34.947] <TB3> INFO: Log level: INFO
[13:17:35.958] <TB3> INFO: Found DTB DTB_WWV6Z5
[13:17:38.981] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[13:17:38.984] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[13:17:38.987] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[13:17:40.517] <TB3> INFO: DUT info:
[13:17:40.517] <TB3> INFO: The DUT currently contains the following objects:
[13:17:40.517] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[13:17:40.517] <TB3> INFO: TBM Core alpha (0): 7 registers set
[13:17:40.517] <TB3> INFO: TBM Core beta (1): 7 registers set
[13:17:40.517] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:17:40.517] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:17:40.517] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:17:40.517] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:17:40.517] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:17:40.517] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:17:40.517] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:17:40.517] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:17:40.517] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:17:40.517] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:17:40.517] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:17:40.517] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:17:40.517] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:17:40.518] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:17:40.518] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:17:40.518] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:17:40.518] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:17:40.919] <TB3> INFO: enter 'restricted' command line mode
[13:17:40.919] <TB3> INFO: enter test to run
[13:17:40.919] <TB3> INFO: test: PixelAlive no parameter change
[13:17:40.919] <TB3> INFO: running: pixelalive
[13:17:40.926] <TB3> INFO: ######################################################################
[13:17:40.926] <TB3> INFO: PixTestAlive::doTest()
[13:17:40.926] <TB3> INFO: ######################################################################
[13:17:40.928] <TB3> INFO: ----------------------------------------------------------------------
[13:17:40.928] <TB3> INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:17:40.928] <TB3> INFO: ----------------------------------------------------------------------
[13:17:44.460] <TB3> INFO: Test took 3530ms.
[13:17:44.477] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:44.722] <TB3> INFO: PixTestAlive::aliveTest() done
[13:17:44.722] <TB3> INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 1 1 0 0 0 0 11 0 0 0
[13:17:44.723] <TB3> INFO: ----------------------------------------------------------------------
[13:17:44.723] <TB3> INFO: PixTestAlive::maskTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:17:44.723] <TB3> INFO: ----------------------------------------------------------------------
[13:17:47.607] <TB3> INFO: Test took 2882ms.
[13:17:47.608] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:47.609] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[13:17:47.827] <TB3> INFO: PixTestAlive::maskTest() done
[13:17:47.827] <TB3> INFO: number of mask-defect pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:17:47.828] <TB3> INFO: ----------------------------------------------------------------------
[13:17:47.828] <TB3> INFO: PixTestAlive::addressDecodingTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:17:47.828] <TB3> INFO: ----------------------------------------------------------------------
[13:17:51.293] <TB3> INFO: Test took 3463ms.
[13:17:51.311] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:17:51.532] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[13:17:51.532] <TB3> INFO: number of address-decoding pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:17:51.532] <TB3> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[13:17:51.560] <TB3> INFO: enter test to run
[13:17:51.560] <TB3> INFO: test: no parameter change
[13:17:51.681] <TB3> QUIET: Connection to board 121 closed.
[13:17:51.761] <TB3> INFO: pXar: this is the end, my friend
commander_RetrimHotPixels.log
[13:23:04.668] <TB3> INFO: *** Welcome to pxar ***
[13:23:04.668] <TB3> INFO: *** Today: 2015/08/11
[13:23:04.668] <TB3> INFO: readRocDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//dacParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//dacParameters35_C15.dat
[13:23:04.669] <TB3> INFO: readTbmDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//tbmParameters_C0a.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//tbmParameters_C0b.dat
[13:23:04.669] <TB3> INFO: readMaskFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//defaultMaskFile.dat
[13:23:04.669] <TB3> INFO: readTrimFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//trimParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//trimParameters35_C15.dat
[13:23:04.744] <TB3> INFO: clk: 4
[13:23:04.744] <TB3> INFO: ctr: 4
[13:23:04.744] <TB3> INFO: sda: 19
[13:23:04.744] <TB3> INFO: tin: 9
[13:23:04.744] <TB3> INFO: level: 15
[13:23:04.744] <TB3> INFO: triggerdelay: 0
[13:23:04.744] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[13:23:04.744] <TB3> INFO: Log level: INFO
[13:23:05.756] <TB3> INFO: Found DTB DTB_WWV6Z5
[13:23:08.779] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[13:23:08.782] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[13:23:08.784] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[13:23:10.325] <TB3> INFO: DUT info:
[13:23:10.325] <TB3> INFO: The DUT currently contains the following objects:
[13:23:10.325] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[13:23:10.325] <TB3> INFO: TBM Core alpha (0): 7 registers set
[13:23:10.325] <TB3> INFO: TBM Core beta (1): 7 registers set
[13:23:10.325] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:23:10.325] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:10.325] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:10.325] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:10.325] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:10.325] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:10.325] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:10.325] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:10.325] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:10.325] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:10.325] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:10.325] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:10.325] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:10.325] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:10.325] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:10.325] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:10.325] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:23:10.727] <TB3> INFO: enter 'restricted' command line mode
[13:23:10.727] <TB3> INFO: enter test to run
[13:23:10.727] <TB3> INFO: test: delay setting parameters: ->5<-
[13:23:10.727] <TB3> INFO: delay test by 5 seconds...
[13:23:15.727] <TB3> INFO: enter test to run
[13:23:15.727] <TB3> INFO: test: highrate no parameter change
[13:23:15.727] <TB3> INFO: running: highrate
[13:23:15.728] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//phCalibrationFitErr35_C15.dat
[13:23:15.916] <TB3> INFO: ----------------------------------------------------------------------
[13:23:15.916] <TB3> INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:23:15.916] <TB3> INFO: ----------------------------------------------------------------------
[13:23:15.916] <TB3> INFO: THR = 150, corresponding to ~ 400 MHz/cm2
[13:23:15.916] <TB3> INFO: edge/corner pixel THR is adjusted
[13:23:15.916] <TB3> INFO: PixTestHighRate::trimHotPixels: step 0...
[13:23:16.879] <TB3> INFO: Collecting data for 1 seconds...
[13:23:17.881] <TB3> INFO: Done with hot pixel readout
[13:23:23.531] <TB3> INFO: PixTest:: pg_setup set to default.
[13:23:23.532] <TB3> INFO: 26 hot pixels found in step 0
[13:23:24.497] <TB3> INFO: Collecting data for 1 seconds...
[13:23:25.499] <TB3> INFO: Done with hot pixel readout
[13:23:31.573] <TB3> INFO: PixTest:: pg_setup set to default.
[13:23:31.574] <TB3> INFO: 16 hot pixels found in step 1
[13:23:32.536] <TB3> INFO: Collecting data for 1 seconds...
[13:23:33.538] <TB3> INFO: Done with hot pixel readout
[13:23:39.566] <TB3> INFO: PixTest:: pg_setup set to default.
[13:23:39.567] <TB3> INFO: 2 hot pixels found in step 2
[13:23:40.529] <TB3> INFO: Collecting data for 1 seconds...
[13:23:41.531] <TB3> INFO: Done with hot pixel readout
[13:23:47.282] <TB3> INFO: PixTest:: pg_setup set to default.
[13:23:47.283] <TB3> INFO: 0 hot pixels found in step 3
[13:23:47.290] <TB3> INFO: 0 hot pixels could not be trimmed and have been masked.
[13:23:47.318] <TB3> INFO: PixTest::trimHotPixels() done
[13:23:47.318] <TB3> INFO: write trim parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//trimParameters35_C0.dat
[13:23:47.324] <TB3> INFO: write trim parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//trimParameters35_C1.dat
[13:23:47.330] <TB3> INFO: write trim parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//trimParameters35_C2.dat
[13:23:47.336] <TB3> INFO: write trim parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//trimParameters35_C3.dat
[13:23:47.342] <TB3> INFO: write trim parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//trimParameters35_C4.dat
[13:23:47.348] <TB3> INFO: write trim parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//trimParameters35_C5.dat
[13:23:47.354] <TB3> INFO: write trim parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//trimParameters35_C6.dat
[13:23:47.360] <TB3> INFO: write trim parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//trimParameters35_C7.dat
[13:23:47.366] <TB3> INFO: write trim parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//trimParameters35_C8.dat
[13:23:47.372] <TB3> INFO: write trim parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//trimParameters35_C9.dat
[13:23:47.379] <TB3> INFO: write trim parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//trimParameters35_C10.dat
[13:23:47.385] <TB3> INFO: write trim parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//trimParameters35_C11.dat
[13:23:47.391] <TB3> INFO: write trim parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//trimParameters35_C12.dat
[13:23:47.397] <TB3> INFO: write trim parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//trimParameters35_C13.dat
[13:23:47.403] <TB3> INFO: write trim parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//trimParameters35_C14.dat
[13:23:47.409] <TB3> INFO: write trim parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//trimParameters35_C15.dat
[13:23:47.415] <TB3> INFO: write masked pixels into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//002_RetrimHotPixels_150//defaultMaskFile.dat
[13:23:47.446] <TB3> INFO: enter test to run
[13:23:47.446] <TB3> INFO: test: no parameter change
[13:23:47.599] <TB3> QUIET: Connection to board 121 closed.
[13:23:47.678] <TB3> INFO: pXar: this is the end, my friend
commander_HRData.log
[13:24:22.350] <TB3> INFO: *** Welcome to pxar ***
[13:24:22.350] <TB3> INFO: *** Today: 2015/08/11
[13:24:22.350] <TB3> INFO: readRocDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//003_HRData_50//dacParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//003_HRData_50//dacParameters35_C15.dat
[13:24:22.351] <TB3> INFO: readTbmDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//003_HRData_50//tbmParameters_C0a.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//003_HRData_50//tbmParameters_C0b.dat
[13:24:22.351] <TB3> INFO: readMaskFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//003_HRData_50//defaultMaskFile.dat
[13:24:22.352] <TB3> INFO: readTrimFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//003_HRData_50//trimParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//003_HRData_50//trimParameters35_C15.dat
[13:24:22.435] <TB3> INFO: clk: 4
[13:24:22.435] <TB3> INFO: ctr: 4
[13:24:22.435] <TB3> INFO: sda: 19
[13:24:22.435] <TB3> INFO: tin: 9
[13:24:22.435] <TB3> INFO: level: 15
[13:24:22.435] <TB3> INFO: triggerdelay: 0
[13:24:22.435] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[13:24:22.435] <TB3> INFO: Log level: INFO
[13:24:23.447] <TB3> INFO: Found DTB DTB_WWV6Z5
[13:24:26.471] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[13:24:26.474] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[13:24:26.477] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[13:24:28.005] <TB3> INFO: DUT info:
[13:24:28.005] <TB3> INFO: The DUT currently contains the following objects:
[13:24:28.005] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[13:24:28.005] <TB3> INFO: TBM Core alpha (0): 7 registers set
[13:24:28.005] <TB3> INFO: TBM Core beta (1): 7 registers set
[13:24:28.005] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:24:28.005] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:24:28.005] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:24:28.005] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:24:28.005] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:24:28.005] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:24:28.005] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:24:28.005] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:24:28.005] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:24:28.005] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:24:28.005] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:24:28.005] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:24:28.005] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:24:28.005] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:24:28.005] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:24:28.005] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:24:28.006] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:24:28.407] <TB3> INFO: enter 'restricted' command line mode
[13:24:28.407] <TB3> INFO: enter test to run
[13:24:28.407] <TB3> INFO: test: delay setting parameters: ->5<-
[13:24:28.407] <TB3> INFO: delay test by 5 seconds...
[13:24:33.407] <TB3> INFO: enter test to run
[13:24:33.407] <TB3> INFO: test: Xray no parameter change
[13:24:33.415] <TB3> INFO: running: xray
[13:24:33.416] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//003_HRData_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//003_HRData_50//phCalibrationFitErr35_C15.dat
[13:24:33.570] <TB3> INFO: ######################################################################
[13:24:33.570] <TB3> INFO: PixTestXray::doTest()
[13:24:33.570] <TB3> INFO: ######################################################################
[13:24:33.570] <TB3> INFO: ----------------------------------------------------------------------
[13:24:33.570] <TB3> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:24:33.570] <TB3> INFO: ----------------------------------------------------------------------
[13:24:34.538] <TB3> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:24:41.312] <TB3> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[13:25:05.840] <TB3> INFO: Resuming triggers.
[13:25:12.624] <TB3> INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[13:25:16.385] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (0) != Token Chain Length (4)

[13:25:33.990] <TB3> INFO: Resuming triggers.
[13:25:40.776] <TB3> INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[13:26:00.996] <TB3> INFO: Resuming triggers.
[13:26:07.779] <TB3> INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[13:26:29.175] <TB3> INFO: Resuming triggers.
[13:26:35.965] <TB3> INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[13:26:57.963] <TB3> INFO: Resuming triggers.
[13:27:04.758] <TB3> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[13:27:27.965] <TB3> INFO: Resuming triggers.
[13:27:34.756] <TB3> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[13:27:59.076] <TB3> INFO: Resuming triggers.
[13:28:05.869] <TB3> INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[13:28:27.313] <TB3> INFO: Resuming triggers.
[13:28:34.103] <TB3> INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[13:28:55.616] <TB3> INFO: Resuming triggers.
[13:29:02.403] <TB3> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[13:29:22.840] <TB3> INFO: Resuming triggers.
[13:29:29.627] <TB3> INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[13:29:54.248] <TB3> INFO: Resuming triggers.
[13:30:01.037] <TB3> INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[13:30:21.330] <TB3> INFO: Resuming triggers.
[13:30:28.112] <TB3> INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[13:30:47.966] <TB3> INFO: Resuming triggers.
[13:30:54.752] <TB3> INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[13:31:17.675] <TB3> INFO: Resuming triggers.
[13:31:22.785] <TB3> INFO: data taking finished, elapsed time: 100 seconds.
[13:31:38.466] <TB3> INFO: PixTest:: pg_setup set to default.
[13:31:38.468] <TB3> INFO: PixTestXray::doPhRun() done
[13:31:38.471] <TB3> INFO: PixTestXray::doTest() done
[13:31:38.645] <TB3> INFO: enter test to run
[13:31:38.645] <TB3> INFO: test: no parameter change
[13:31:38.807] <TB3> QUIET: Connection to board 121 closed.
[13:31:38.887] <TB3> INFO: pXar: this is the end, my friend
commander_HRData.log
[13:33:09.853] <TB3> INFO: *** Welcome to pxar ***
[13:33:09.853] <TB3> INFO: *** Today: 2015/08/11
[13:33:09.853] <TB3> INFO: readRocDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//004_HRData_150//dacParameters35_C15.dat
[13:33:09.854] <TB3> INFO: readTbmDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//004_HRData_150//tbmParameters_C0b.dat
[13:33:09.854] <TB3> INFO: readMaskFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//004_HRData_150//defaultMaskFile.dat
[13:33:09.854] <TB3> INFO: readTrimFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//004_HRData_150//trimParameters35_C15.dat
[13:33:09.924] <TB3> INFO: clk: 4
[13:33:09.924] <TB3> INFO: ctr: 4
[13:33:09.924] <TB3> INFO: sda: 19
[13:33:09.924] <TB3> INFO: tin: 9
[13:33:09.924] <TB3> INFO: level: 15
[13:33:09.924] <TB3> INFO: triggerdelay: 0
[13:33:09.924] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[13:33:09.924] <TB3> INFO: Log level: INFO
[13:33:10.936] <TB3> INFO: Found DTB DTB_WWV6Z5
[13:33:13.959] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[13:33:13.962] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[13:33:13.965] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[13:33:15.520] <TB3> INFO: DUT info:
[13:33:15.520] <TB3> INFO: The DUT currently contains the following objects:
[13:33:15.520] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[13:33:15.520] <TB3> INFO: TBM Core alpha (0): 7 registers set
[13:33:15.520] <TB3> INFO: TBM Core beta (1): 7 registers set
[13:33:15.520] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:33:15.520] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:15.521] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:15.521] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:15.521] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:15.521] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:15.521] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:15.521] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:15.521] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:15.521] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:15.521] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:15.521] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:15.521] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:15.521] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:15.521] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:15.521] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:15.521] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:33:15.922] <TB3> INFO: enter 'restricted' command line mode
[13:33:15.922] <TB3> INFO: enter test to run
[13:33:15.922] <TB3> INFO: test: delay setting parameters: ->5<-
[13:33:15.922] <TB3> INFO: delay test by 5 seconds...
[13:33:20.922] <TB3> INFO: enter test to run
[13:33:20.922] <TB3> INFO: test: Xray no parameter change
[13:33:20.922] <TB3> INFO: running: xray
[13:33:20.923] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//004_HRData_150//phCalibrationFitErr35_C15.dat
[13:33:21.086] <TB3> INFO: ######################################################################
[13:33:21.086] <TB3> INFO: PixTestXray::doTest()
[13:33:21.086] <TB3> INFO: ######################################################################
[13:33:21.086] <TB3> INFO: ----------------------------------------------------------------------
[13:33:21.086] <TB3> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:33:21.086] <TB3> INFO: ----------------------------------------------------------------------
[13:33:22.054] <TB3> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:33:25.804] <TB3> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[13:33:38.951] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[13:33:44.701] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[13:33:44.702] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:33:52.227] <TB3> INFO: Resuming triggers.
[13:33:55.985] <TB3> INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[13:33:59.569] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (228) != TBM ID (96)

[13:33:59.569] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (0) != Token Chain Length (4)

[13:33:59.569] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (97) != TBM ID (228)

[13:34:04.004] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[13:34:04.004] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[13:34:04.004] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:34:04.004] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:34:18.485] <TB3> INFO: Resuming triggers.
[13:34:22.241] <TB3> INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:34:48.102] <TB3> INFO: Resuming triggers.
[13:34:51.857] <TB3> INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[13:35:15.526] <TB3> INFO: Resuming triggers.
[13:35:19.279] <TB3> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[13:35:19.660] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (184) != TBM ID (185)

[13:35:19.660] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[13:35:19.660] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (184) != TBM ID (187)

[13:35:19.660] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (186) != TBM ID (185)

[13:35:19.660] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (188) != TBM ID (185)

[13:35:19.660] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:35:41.454] <TB3> INFO: Resuming triggers.
[13:35:45.209] <TB3> INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[13:35:45.535] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 10 readouts!

[13:35:45.535] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[13:35:45.535] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 6 readouts!

[13:35:45.535] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:36:06.048] <TB3> INFO: Resuming triggers.
[13:36:09.801] <TB3> INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[13:36:20.280] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (5) != Token Chain Length (4)

[13:36:20.280] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[13:36:24.838] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[13:36:24.838] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:36:33.507] <TB3> INFO: Resuming triggers.
[13:36:37.262] <TB3> INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[13:36:58.805] <TB3> INFO: Resuming triggers.
[13:37:02.559] <TB3> INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[13:37:10.576] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[13:37:10.576] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[13:37:10.576] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[13:37:25.698] <TB3> INFO: Resuming triggers.
[13:37:29.451] <TB3> INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[13:37:39.902] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (0) != Token Chain Length (4)

[13:37:39.902] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[13:37:39.902] <TB3> WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!

[13:37:39.902] <TB3> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

[13:37:39.902] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[13:37:39.902] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:37:41.873] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[13:37:41.873] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[13:37:41.873] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[13:37:41.873] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:37:52.715] <TB3> INFO: Resuming triggers.
[13:37:56.468] <TB3> INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[13:38:00.303] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[13:38:00.303] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:38:20.676] <TB3> INFO: Resuming triggers.
[13:38:24.427] <TB3> INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[13:38:32.170] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (3) != Token Chain Length (4)

[13:38:32.170] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[13:38:32.170] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:38:47.792] <TB3> INFO: Resuming triggers.
[13:38:51.543] <TB3> INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[13:39:07.887] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (3) != Token Chain Length (4)

[13:39:07.887] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[13:39:07.887] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:39:16.702] <TB3> INFO: Resuming triggers.
[13:39:20.452] <TB3> INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[13:39:28.415] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (5) != Token Chain Length (4)

[13:39:30.662] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[13:39:30.663] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[13:39:30.663] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (162) != TBM ID (255)

[13:39:30.663] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (0) != Token Chain Length (4)

[13:39:30.663] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (256) != TBM ID (162)

[13:39:30.663] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[13:39:30.663] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:39:32.859] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[13:39:42.764] <TB3> INFO: Resuming triggers.
[13:39:46.512] <TB3> INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[13:39:50.972] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[13:39:58.667] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[13:40:01.115] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (5) != Token Chain Length (4)

[13:40:01.116] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[13:40:01.116] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:40:11.347] <TB3> INFO: Resuming triggers.
[13:40:15.096] <TB3> INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[13:40:26.126] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[13:40:26.126] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 31 readouts!

[13:40:28.049] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (3) != Token Chain Length (4)

[13:40:35.585] <TB3> INFO: Resuming triggers.
[13:40:39.333] <TB3> INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[13:40:40.743] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[13:40:42.630] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[13:40:48.400] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (5) != Token Chain Length (4)

[13:40:48.400] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[13:40:48.400] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:40:50.308] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[13:40:59.259] <TB3> INFO: Resuming triggers.
[13:41:03.009] <TB3> INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[13:41:03.436] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (3) != Token Chain Length (4)

[13:41:03.436] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[13:41:03.436] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[13:41:03.436] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:41:03.436] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:41:07.891] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (0) != Token Chain Length (4)

[13:41:07.891] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[13:41:07.891] <TB3> WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!

[13:41:07.892] <TB3> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

[13:41:07.892] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[13:41:07.892] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:41:15.596] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[13:41:24.570] <TB3> INFO: Resuming triggers.
[13:41:28.317] <TB3> INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[13:41:32.068] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[13:41:32.068] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[13:41:32.068] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[13:41:32.068] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:41:34.448] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[13:41:34.448] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[13:41:34.448] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[13:41:34.448] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:41:36.932] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (1) != Token Chain Length (4)

[13:41:36.932] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[13:41:36.932] <TB3> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

[13:41:36.932] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[13:41:36.932] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:41:39.328] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[13:41:39.329] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[13:41:39.329] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[13:41:39.329] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:41:41.473] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (0) != Token Chain Length (4)

[13:41:41.474] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (0) != Token Chain Length (4)

[13:41:41.474] <TB3> WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!

[13:41:41.474] <TB3> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

[13:41:41.474] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[13:41:41.474] <TB3> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!

[13:41:41.474] <TB3> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!

[13:41:41.474] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:41:52.946] <TB3> INFO: Resuming triggers.
[13:41:56.694] <TB3> INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[13:42:10.279] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[13:42:19.360] <TB3> INFO: Resuming triggers.
[13:42:23.109] <TB3> INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[13:42:24.039] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[13:42:24.039] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[13:42:24.040] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[13:42:26.243] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 0 Event ID mismatch: local ID (169) != TBM ID (170)

[13:42:26.243] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (169) != TBM ID (170)

[13:42:26.243] <TB3> WARNING: Channel 0 ROC 0: Readback start marker after 15 readouts!

[13:42:26.243] <TB3> WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!

[13:42:26.243] <TB3> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

[13:42:26.243] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[13:42:26.243] <TB3> WARNING: Channel 1 ROC 0: Readback start marker after 15 readouts!

[13:42:26.243] <TB3> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!

[13:42:26.243] <TB3> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!

[13:42:26.243] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:42:44.854] <TB3> INFO: Resuming triggers.
[13:42:48.601] <TB3> INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[13:42:52.504] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (3) != Token Chain Length (4)

[13:42:52.504] <TB3> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!

[13:42:52.504] <TB3> WARNING: Channel 1 ROC 1: Readback start marker after 1 readouts!

[13:42:54.931] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (1) != Token Chain Length (4)

[13:42:54.931] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[13:42:54.950] <TB3> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

[13:42:54.950] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[13:42:54.950] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:42:59.467] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[13:42:59.467] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[13:42:59.468] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[13:42:59.468] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:43:11.190] <TB3> INFO: Resuming triggers.
[13:43:14.935] <TB3> INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[13:43:35.581] <TB3> INFO: Resuming triggers.
[13:43:39.326] <TB3> INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[13:43:45.369] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (3) != Token Chain Length (4)

[13:43:45.369] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[13:43:47.479] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[13:43:47.479] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[13:43:49.635] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[13:43:49.635] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[13:43:49.635] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[13:43:49.635] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:43:51.808] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (1) != Token Chain Length (4)

[13:43:51.808] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (1) != Token Chain Length (4)

[13:43:51.808] <TB3> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

[13:43:51.808] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[13:43:51.808] <TB3> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!

[13:43:51.808] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:43:54.084] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[13:43:54.084] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[13:43:54.084] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 31 readouts!

[13:44:02.036] <TB3> INFO: Resuming triggers.
[13:44:05.782] <TB3> INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[13:44:11.733] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[13:44:11.733] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[13:44:11.734] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[13:44:11.734] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:44:15.704] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[13:44:15.704] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[13:44:15.704] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[13:44:15.704] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[13:44:26.615] <TB3> INFO: Resuming triggers.
[13:44:30.361] <TB3> INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[13:44:41.385] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[13:44:41.385] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (41) != TBM ID (191)

[13:44:41.385] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (0) != Token Chain Length (4)

[13:44:41.385] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 1 Event ID mismatch: local ID (192) != TBM ID (41)

[13:44:52.091] <TB3> INFO: Resuming triggers.
[13:44:54.685] <TB3> INFO: data taking finished, elapsed time: 100 seconds.
[13:44:55.070] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 7 readouts!

[13:44:55.070] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (5) != Token Chain Length (4)

[13:44:56.948] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (3) != Token Chain Length (4)

[13:45:09.259] <TB3> INFO: PixTest:: pg_setup set to default.
[13:45:09.261] <TB3> INFO: PixTestXray::doPhRun() done
[13:45:09.264] <TB3> INFO: PixTestXray::doTest() done
[13:45:09.464] <TB3> INFO: enter test to run
[13:45:09.464] <TB3> INFO: test: no parameter change
[13:45:09.634] <TB3> QUIET: Connection to board 121 closed.
[13:45:09.714] <TB3> INFO: pXar: this is the end, my friend
commander_HRSCurves.log
[13:48:35.663] <TB3> INFO: *** Welcome to pxar ***
[13:48:35.663] <TB3> INFO: *** Today: 2015/08/11
[13:48:35.663] <TB3> INFO: readRocDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//005_HRSCurves_100//dacParameters35_C15.dat
[13:48:35.664] <TB3> INFO: readTbmDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//005_HRSCurves_100//tbmParameters_C0b.dat
[13:48:35.665] <TB3> INFO: readMaskFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//005_HRSCurves_100//defaultMaskFile.dat
[13:48:35.665] <TB3> INFO: readTrimFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//005_HRSCurves_100//trimParameters35_C15.dat
[13:48:35.742] <TB3> INFO: clk: 4
[13:48:35.742] <TB3> INFO: ctr: 4
[13:48:35.742] <TB3> INFO: sda: 19
[13:48:35.742] <TB3> INFO: tin: 9
[13:48:35.742] <TB3> INFO: level: 15
[13:48:35.742] <TB3> INFO: triggerdelay: 0
[13:48:35.742] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[13:48:35.742] <TB3> INFO: Log level: INFO
[13:48:36.755] <TB3> INFO: Found DTB DTB_WWV6Z5
[13:48:39.775] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[13:48:39.779] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[13:48:39.781] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[13:48:41.326] <TB3> INFO: DUT info:
[13:48:41.326] <TB3> INFO: The DUT currently contains the following objects:
[13:48:41.326] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[13:48:41.326] <TB3> INFO: TBM Core alpha (0): 7 registers set
[13:48:41.326] <TB3> INFO: TBM Core beta (1): 7 registers set
[13:48:41.326] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:48:41.326] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:41.326] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:41.326] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:41.326] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:41.326] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:41.326] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:41.326] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:41.326] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:41.326] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:41.326] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:41.326] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:41.326] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:41.326] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:41.326] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:41.326] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:41.326] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:48:41.727] <TB3> INFO: enter 'restricted' command line mode
[13:48:41.727] <TB3> INFO: enter test to run
[13:48:41.727] <TB3> INFO: test: Highrate no parameter change
[13:48:41.727] <TB3> INFO: running: highrate
[13:48:41.728] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[13:48:41.876] <TB3> INFO: ----------------------------------------------------------------------
[13:48:41.876] <TB3> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[13:48:41.876] <TB3> INFO: ----------------------------------------------------------------------
[13:48:41.881] <TB3> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 0 .. 100 (1) hits flags = 384 (plus default)
[13:48:41.893] <TB3> INFO: dacScan step from 0 .. 0
[13:48:55.658] <TB3> INFO: Test took 13764ms.
[13:48:56.952] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:57.180] <TB3> INFO: dacScan step from 1 .. 1
[13:49:10.747] <TB3> INFO: Test took 13566ms.
[13:49:12.238] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:12.481] <TB3> INFO: dacScan step from 2 .. 2
[13:49:25.995] <TB3> INFO: Test took 13513ms.
[13:49:27.619] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:27.883] <TB3> INFO: dacScan step from 3 .. 3
[13:49:41.024] <TB3> INFO: Test took 13141ms.
[13:49:42.478] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:42.735] <TB3> INFO: dacScan step from 4 .. 4
[13:49:53.837] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[13:49:53.837] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[13:49:56.117] <TB3> INFO: Test took 13381ms.
[13:49:57.449] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:57.676] <TB3> INFO: dacScan step from 5 .. 5
[13:50:10.850] <TB3> INFO: Test took 13174ms.
[13:50:12.189] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:12.417] <TB3> INFO: dacScan step from 6 .. 6
[13:50:25.863] <TB3> INFO: Test took 13446ms.
[13:50:27.210] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:27.445] <TB3> INFO: dacScan step from 7 .. 7
[13:50:41.048] <TB3> INFO: Test took 13603ms.
[13:50:42.483] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:42.723] <TB3> INFO: dacScan step from 8 .. 8
[13:50:52.720] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (198) != TBM ID (199)

[13:50:52.720] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (198) != TBM ID (199)

[13:50:52.720] <TB3> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[13:50:52.720] <TB3> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!

[13:50:52.720] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

[13:50:52.720] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:50:52.720] <TB3> WARNING: Channel 3 ROC 0: Readback start marker after 15 readouts!

[13:50:52.720] <TB3> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!

[13:50:52.720] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

[13:50:52.720] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:50:56.010] <TB3> INFO: Test took 13287ms.
[13:50:57.379] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:57.612] <TB3> INFO: dacScan step from 9 .. 9
[13:51:07.385] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[13:51:07.385] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[13:51:07.385] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:51:07.385] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:51:10.597] <TB3> INFO: Test took 12985ms.
[13:51:11.945] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:12.192] <TB3> INFO: dacScan step from 10 .. 10
[13:51:18.974] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (165) != TBM ID (166)

[13:51:18.974] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (165) != TBM ID (166)

[13:51:18.974] <TB3> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[13:51:18.974] <TB3> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!

[13:51:18.974] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

[13:51:18.974] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:51:18.974] <TB3> WARNING: Channel 3 ROC 0: Readback start marker after 15 readouts!

[13:51:18.974] <TB3> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!

[13:51:18.974] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

[13:51:18.974] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:51:19.878] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (0) != Token Chain Length (4)

[13:51:25.359] <TB3> INFO: Test took 13167ms.
[13:51:26.639] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:26.854] <TB3> INFO: dacScan step from 11 .. 11
[13:51:40.181] <TB3> INFO: Test took 13326ms.
[13:51:41.481] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:41.705] <TB3> INFO: dacScan step from 12 .. 12
[13:51:50.776] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[13:51:50.776] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:51:54.998] <TB3> INFO: Test took 13293ms.
[13:51:56.326] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:56.567] <TB3> INFO: dacScan step from 13 .. 13
[13:52:09.754] <TB3> INFO: Test took 13187ms.
[13:52:11.145] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:11.378] <TB3> INFO: dacScan step from 14 .. 14
[13:52:24.724] <TB3> INFO: Test took 13345ms.
[13:52:26.062] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:26.295] <TB3> INFO: dacScan step from 15 .. 15
[13:52:34.579] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[13:52:38.135] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (0) != Token Chain Length (4)

[13:52:38.135] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (1) != Token Chain Length (4)

[13:52:38.135] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (140) != TBM ID (255)

[13:52:38.135] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (1) != Token Chain Length (4)

[13:52:38.135] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (256) != TBM ID (140)

[13:52:38.135] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

[13:52:38.135] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:52:38.135] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

[13:52:38.135] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:52:39.450] <TB3> CRITICAL: <hal.cc/MultiRocAllPixelsDacScan:L1040> Incomplete DAQ data readout! Missing -1 Events.

[13:52:52.614] <TB3> INFO: Test took 13142ms.
[13:52:53.964] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:54.200] <TB3> INFO: dacScan step from 16 .. 16
[13:53:07.565] <TB3> INFO: Test took 13365ms.
[13:53:09.084] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:09.352] <TB3> INFO: dacScan step from 17 .. 17
[13:53:17.771] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[13:53:17.771] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[13:53:17.771] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:53:18.862] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (1) != Token Chain Length (4)

[13:53:18.862] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[13:53:18.862] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (188) != TBM ID (212)

[13:53:18.862] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[13:53:18.862] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (213) != TBM ID (188)

[13:53:18.862] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:53:18.862] <TB3> WARNING: Channel 2 ROC 0: Readback start marker after 17 readouts!

[13:53:18.862] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:53:22.794] <TB3> CRITICAL: <hal.cc/MultiRocAllPixelsDacScan:L1040> Incomplete DAQ data readout! Missing -1 Events.

[13:53:36.156] <TB3> INFO: Test took 13334ms.
[13:53:37.479] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:37.714] <TB3> INFO: dacScan step from 18 .. 18
[13:53:50.797] <TB3> INFO: Test took 13083ms.
[13:53:52.098] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:53:52.324] <TB3> INFO: dacScan step from 19 .. 19
[13:54:04.142] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[13:54:04.142] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:54:05.747] <TB3> INFO: Test took 13423ms.
[13:54:07.095] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:07.360] <TB3> INFO: dacScan step from 20 .. 20
[13:54:14.694] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (1) != Token Chain Length (4)

[13:54:14.694] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

[13:54:14.694] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:54:15.526] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[13:54:16.414] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (24) != TBM ID (25)

[13:54:16.415] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (24) != TBM ID (25)

[13:54:16.415] <TB3> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[13:54:16.415] <TB3> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!

[13:54:16.415] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

[13:54:16.415] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:54:16.415] <TB3> WARNING: Channel 3 ROC 0: Readback start marker after 15 readouts!

[13:54:16.415] <TB3> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!

[13:54:16.415] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

[13:54:16.415] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:54:17.996] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[13:54:17.996] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[13:54:17.996] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:54:20.677] <TB3> INFO: Test took 13316ms.
[13:54:22.016] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:22.252] <TB3> INFO: dacScan step from 21 .. 21
[13:54:30.095] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (1) != Token Chain Length (4)

[13:54:30.095] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (1) != Token Chain Length (4)

[13:54:30.100] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

[13:54:30.100] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:54:30.100] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

[13:54:30.100] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:54:35.778] <TB3> INFO: Test took 13526ms.
[13:54:37.326] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:37.571] <TB3> INFO: dacScan step from 22 .. 22
[13:54:51.183] <TB3> INFO: Test took 13612ms.
[13:54:52.570] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:52.824] <TB3> INFO: dacScan step from 23 .. 23
[13:55:01.458] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (162) != TBM ID (163)

[13:55:01.458] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (162) != TBM ID (163)

[13:55:01.458] <TB3> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[13:55:01.458] <TB3> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!

[13:55:01.458] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

[13:55:01.458] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:55:01.458] <TB3> WARNING: Channel 3 ROC 0: Readback start marker after 15 readouts!

[13:55:01.458] <TB3> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!

[13:55:01.458] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

[13:55:01.458] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:55:06.318] <TB3> INFO: Test took 13494ms.
[13:55:07.901] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:55:08.171] <TB3> INFO: dacScan step from 24 .. 24
[13:55:21.133] <TB3> INFO: Test took 12961ms.
[13:55:22.488] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:55:22.721] <TB3> INFO: dacScan step from 25 .. 25
[13:55:35.925] <TB3> INFO: Test took 13204ms.
[13:55:37.469] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:55:37.700] <TB3> INFO: dacScan step from 26 .. 26
[13:55:45.756] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[13:55:45.756] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:55:51.045] <TB3> INFO: Test took 13345ms.
[13:55:52.610] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:55:52.847] <TB3> INFO: dacScan step from 27 .. 27
[13:56:06.550] <TB3> INFO: Test took 13703ms.
[13:56:08.181] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:08.458] <TB3> INFO: dacScan step from 28 .. 28
[13:56:22.335] <TB3> INFO: Test took 13877ms.
[13:56:23.699] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:23.939] <TB3> INFO: dacScan step from 29 .. 29
[13:56:29.375] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[13:56:29.376] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[13:56:29.376] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (139) != TBM ID (133)

[13:56:29.376] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (0) != Token Chain Length (4)

[13:56:29.376] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (134) != TBM ID (139)

[13:56:29.376] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:56:29.376] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:56:30.766] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (190) != TBM ID (168)

[13:56:30.767] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

[13:56:30.767] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[13:56:30.767] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (169) != TBM ID (191)

[13:56:30.767] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:56:30.767] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (190) != TBM ID (168)

[13:56:30.767] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[13:56:30.767] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (169) != TBM ID (191)

[13:56:30.767] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:56:30.767] <TB3> WARNING: Channel 2 ROC 3: Readback start marker after 31 readouts!

[13:56:38.001] <TB3> CRITICAL: <hal.cc/MultiRocAllPixelsDacScan:L1040> Incomplete DAQ data readout! Missing -1 Events.

[13:56:44.744] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (168) != TBM ID (169)

[13:56:44.744] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (168) != TBM ID (169)

[13:56:44.744] <TB3> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[13:56:44.744] <TB3> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!

[13:56:44.744] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

[13:56:44.744] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:56:44.744] <TB3> WARNING: Channel 3 ROC 0: Readback start marker after 15 readouts!

[13:56:44.744] <TB3> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!

[13:56:44.744] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

[13:56:44.744] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:56:51.408] <TB3> INFO: Test took 13384ms.
[13:56:52.704] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:52.922] <TB3> INFO: dacScan step from 30 .. 30
[13:57:06.267] <TB3> INFO: Test took 13345ms.
[13:57:08.143] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:08.379] <TB3> INFO: dacScan step from 31 .. 31
[13:57:15.141] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[13:57:15.141] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:57:21.919] <TB3> INFO: Test took 13539ms.
[13:57:23.778] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:24.018] <TB3> INFO: dacScan step from 32 .. 32
[13:57:37.610] <TB3> INFO: Test took 13591ms.
[13:57:39.219] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:39.451] <TB3> INFO: dacScan step from 33 .. 33
[13:57:53.224] <TB3> INFO: Test took 13773ms.
[13:57:54.603] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:57:54.841] <TB3> INFO: dacScan step from 34 .. 34
[13:58:08.661] <TB3> INFO: Test took 13819ms.
[13:58:10.082] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:10.333] <TB3> INFO: dacScan step from 35 .. 35
[13:58:16.355] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (52) != TBM ID (33)

[13:58:16.355] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[13:58:16.355] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (52) != TBM ID (53)

[13:58:16.355] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (34) != TBM ID (53)

[13:58:16.355] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (54) != TBM ID (53)

[13:58:16.355] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:58:23.477] <TB3> INFO: Test took 13144ms.
[13:58:25.088] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:25.310] <TB3> INFO: dacScan step from 36 .. 36
[13:58:30.926] <TB3> WARNING: Channel 2 ROC 0: Readback start marker after 8 readouts!

[13:58:30.926] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[13:58:30.926] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (1) != Token Chain Length (4)

[13:58:30.926] <TB3> WARNING: Channel 2 ROC 0: Readback start marker after 8 readouts!

[13:58:30.926] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:58:30.926] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

[13:58:30.926] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:58:38.491] <TB3> INFO: Test took 13180ms.
[13:58:39.938] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:40.179] <TB3> INFO: dacScan step from 37 .. 37
[13:58:45.619] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:58:45.619] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (5) != Token Chain Length (4)

[13:58:45.638] <TB3> WARNING: Channel 2 ROC 3: Readback start marker after 32 readouts!

[13:58:54.041] <TB3> INFO: Test took 13862ms.
[13:58:55.733] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:58:56.019] <TB3> INFO: dacScan step from 38 .. 38
[13:59:02.666] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (179) != TBM ID (180)

[13:59:02.666] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (179) != TBM ID (180)

[13:59:02.666] <TB3> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[13:59:02.666] <TB3> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!

[13:59:02.666] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

[13:59:02.666] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:59:02.666] <TB3> WARNING: Channel 3 ROC 0: Readback start marker after 15 readouts!

[13:59:02.666] <TB3> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!

[13:59:02.667] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

[13:59:02.667] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:59:08.611] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[13:59:08.611] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[13:59:08.611] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (219) != TBM ID (84)

[13:59:08.611] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (0) != Token Chain Length (4)

[13:59:08.611] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (85) != TBM ID (219)

[13:59:08.611] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[13:59:08.611] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:59:09.913] <TB3> INFO: Test took 13893ms.
[13:59:11.432] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:59:11.683] <TB3> INFO: dacScan step from 39 .. 39
[13:59:25.598] <TB3> INFO: Test took 13914ms.
[13:59:27.022] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:59:27.275] <TB3> INFO: dacScan step from 40 .. 40
[13:59:32.069] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[13:59:32.069] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[13:59:32.069] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[13:59:41.620] <TB3> INFO: Test took 14344ms.
[13:59:43.030] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:59:43.303] <TB3> INFO: dacScan step from 41 .. 41
[13:59:57.752] <TB3> INFO: Test took 14448ms.
[13:59:59.205] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[13:59:59.433] <TB3> INFO: dacScan step from 42 .. 42
[14:00:08.708] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[14:00:08.708] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 31 readouts!

[14:00:09.039] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[14:00:09.039] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[14:00:09.039] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:00:14.802] <TB3> INFO: Test took 15369ms.
[14:00:16.345] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:00:16.579] <TB3> INFO: dacScan step from 43 .. 43
[14:00:25.782] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[14:00:25.782] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:00:31.942] <TB3> INFO: Test took 15363ms.
[14:00:33.420] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:00:33.659] <TB3> INFO: dacScan step from 44 .. 44
[14:00:47.036] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

[14:00:47.036] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[14:00:47.036] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (1) != Token Chain Length (4)

[14:00:47.036] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:00:47.036] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

[14:00:47.037] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[14:00:47.037] <TB3> WARNING: Channel 2 ROC 3: Readback start marker after 31 readouts!

[14:00:48.968] <TB3> INFO: Test took 15308ms.
[14:00:50.505] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:00:50.735] <TB3> INFO: dacScan step from 45 .. 45
[14:01:06.685] <TB3> INFO: Test took 15949ms.
[14:01:08.202] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:08.441] <TB3> INFO: dacScan step from 46 .. 46
[14:01:16.321] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[14:01:24.312] <TB3> INFO: Test took 15870ms.
[14:01:25.887] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:26.128] <TB3> INFO: dacScan step from 47 .. 47
[14:01:33.660] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[14:01:33.661] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[14:01:33.666] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[14:01:41.874] <TB3> INFO: Test took 15745ms.
[14:01:43.397] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:01:43.646] <TB3> INFO: dacScan step from 48 .. 48
[14:01:59.548] <TB3> INFO: Test took 15902ms.
[14:02:01.086] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:02:01.340] <TB3> INFO: dacScan step from 49 .. 49
[14:02:13.419] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[14:02:13.419] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (1) != Token Chain Length (4)

[14:02:13.419] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:02:13.419] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

[14:02:13.419] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[14:02:18.189] <TB3> INFO: Test took 16848ms.
[14:02:19.872] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:02:20.140] <TB3> INFO: dacScan step from 50 .. 50
[14:02:33.254] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[14:02:33.254] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:02:36.477] <TB3> INFO: Test took 16336ms.
[14:02:38.028] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:02:38.260] <TB3> INFO: dacScan step from 51 .. 51
[14:02:54.743] <TB3> INFO: Test took 16482ms.
[14:02:56.304] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:02:56.570] <TB3> INFO: dacScan step from 52 .. 52
[14:03:02.464] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (232) != TBM ID (233)

[14:03:02.464] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[14:03:02.464] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (232) != TBM ID (235)

[14:03:02.464] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[14:03:02.464] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (234) != TBM ID (233)

[14:03:02.464] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (236) != TBM ID (233)

[14:03:02.464] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:03:02.464] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[14:03:03.262] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[14:03:03.262] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[14:03:13.181] <TB3> INFO: Test took 16611ms.
[14:03:14.767] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:15.014] <TB3> INFO: dacScan step from 53 .. 53
[14:03:20.971] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[14:03:31.511] <TB3> INFO: Test took 16496ms.
[14:03:33.238] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:33.508] <TB3> INFO: dacScan step from 54 .. 54
[14:03:42.227] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[14:03:42.228] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (1) != Token Chain Length (4)

[14:03:42.228] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:03:42.228] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

[14:03:42.228] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[14:03:50.275] <TB3> INFO: Test took 16766ms.
[14:03:51.755] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:03:52.012] <TB3> INFO: dacScan step from 55 .. 55
[14:04:08.904] <TB3> INFO: Test took 16891ms.
[14:04:10.437] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:10.668] <TB3> INFO: dacScan step from 56 .. 56
[14:04:17.346] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[14:04:17.346] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[14:04:17.346] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:04:17.346] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[14:04:27.295] <TB3> INFO: Test took 16626ms.
[14:04:29.253] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:29.488] <TB3> INFO: dacScan step from 57 .. 57
[14:04:45.826] <TB3> INFO: Test took 16337ms.
[14:04:47.304] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:47.540] <TB3> INFO: dacScan step from 58 .. 58
[14:04:52.795] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[14:04:52.795] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:05:03.981] <TB3> INFO: Test took 16440ms.
[14:05:05.766] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:06.041] <TB3> INFO: dacScan step from 59 .. 59
[14:05:23.006] <TB3> INFO: Test took 16964ms.
[14:05:24.562] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:24.788] <TB3> INFO: dacScan step from 60 .. 60
[14:05:32.320] <TB3> WARNING: Channel 3 ROC 0: Readback start marker after 8 readouts!

[14:05:32.320] <TB3> WARNING: Channel 3 ROC 0: Readback start marker after 8 readouts!

[14:05:41.526] <TB3> INFO: Test took 16737ms.
[14:05:43.065] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:43.328] <TB3> INFO: dacScan step from 61 .. 61
[14:06:00.119] <TB3> INFO: Test took 16790ms.
[14:06:01.800] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:02.075] <TB3> INFO: dacScan step from 62 .. 62
[14:06:09.187] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[14:06:09.187] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[14:06:09.187] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:06:18.531] <TB3> INFO: Test took 16455ms.
[14:06:20.174] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:20.430] <TB3> INFO: dacScan step from 63 .. 63
[14:06:36.987] <TB3> INFO: Test took 16557ms.
[14:06:38.587] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:38.826] <TB3> INFO: dacScan step from 64 .. 64
[14:06:54.970] <TB3> INFO: Test took 16143ms.
[14:06:56.850] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:57.143] <TB3> INFO: dacScan step from 65 .. 65
[14:07:03.442] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[14:07:03.442] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 31 readouts!

[14:07:06.048] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[14:07:06.049] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[14:07:06.049] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:07:13.761] <TB3> INFO: Test took 16617ms.
[14:07:15.460] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:07:15.740] <TB3> INFO: dacScan step from 66 .. 66
[14:07:22.067] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[14:07:22.067] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[14:07:32.748] <TB3> INFO: Test took 17007ms.
[14:07:34.317] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:07:34.545] <TB3> INFO: dacScan step from 67 .. 67
[14:07:51.168] <TB3> INFO: Test took 16622ms.
[14:07:52.810] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:07:53.079] <TB3> INFO: dacScan step from 68 .. 68
[14:08:07.984] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[14:08:07.984] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[14:08:09.439] <TB3> INFO: Test took 16359ms.
[14:08:10.979] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:08:11.223] <TB3> INFO: dacScan step from 69 .. 69
[14:08:21.350] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[14:08:27.985] <TB3> INFO: Test took 16762ms.
[14:08:29.430] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:08:29.657] <TB3> INFO: dacScan step from 70 .. 70
[14:08:45.739] <TB3> INFO: Test took 16081ms.
[14:08:47.217] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:08:47.452] <TB3> INFO: dacScan step from 71 .. 71
[14:08:54.776] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[14:08:54.777] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[14:08:54.777] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:08:54.777] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[14:09:04.224] <TB3> INFO: Test took 16771ms.
[14:09:05.823] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:06.085] <TB3> INFO: dacScan step from 72 .. 72
[14:09:22.509] <TB3> INFO: Test took 16423ms.
[14:09:24.271] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:24.636] <TB3> INFO: dacScan step from 73 .. 73
[14:09:41.295] <TB3> INFO: Test took 16658ms.
[14:09:43.023] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:09:43.284] <TB3> INFO: dacScan step from 74 .. 74
[14:09:49.856] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 5 readouts!

[14:09:49.856] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[14:09:49.856] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (1) != Token Chain Length (4)

[14:09:49.857] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 11 readouts!

[14:09:49.857] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:09:49.857] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

[14:09:49.857] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[14:09:59.667] <TB3> INFO: Test took 16382ms.
[14:10:01.180] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:01.424] <TB3> INFO: dacScan step from 75 .. 75
[14:10:17.725] <TB3> INFO: Test took 16300ms.
[14:10:19.371] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:19.629] <TB3> INFO: dacScan step from 76 .. 76
[14:10:25.508] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[14:10:25.508] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[14:10:25.508] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:10:35.964] <TB3> INFO: Test took 16334ms.
[14:10:37.685] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:37.942] <TB3> INFO: dacScan step from 77 .. 77
[14:10:54.452] <TB3> INFO: Test took 16509ms.
[14:10:55.902] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:10:56.175] <TB3> INFO: dacScan step from 78 .. 78
[14:11:01.967] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[14:11:01.967] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[14:11:01.968] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:11:01.968] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[14:11:12.708] <TB3> INFO: Test took 16532ms.
[14:11:14.356] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:11:14.592] <TB3> INFO: dacScan step from 79 .. 79
[14:11:31.222] <TB3> INFO: Test took 16629ms.
[14:11:32.700] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:11:32.929] <TB3> INFO: dacScan step from 80 .. 80
[14:11:49.848] <TB3> INFO: Test took 16918ms.
[14:11:51.633] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:11:51.908] <TB3> INFO: dacScan step from 81 .. 81
[14:12:08.186] <TB3> INFO: Test took 16278ms.
[14:12:09.958] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:10.211] <TB3> INFO: dacScan step from 82 .. 82
[14:12:17.941] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (196) != TBM ID (197)

[14:12:17.941] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (196) != TBM ID (197)

[14:12:17.941] <TB3> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[14:12:17.941] <TB3> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!

[14:12:17.941] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

[14:12:17.941] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:12:17.941] <TB3> WARNING: Channel 3 ROC 0: Readback start marker after 15 readouts!

[14:12:17.941] <TB3> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!

[14:12:17.941] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

[14:12:17.941] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[14:12:20.961] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[14:12:20.961] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[14:12:20.961] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:12:20.961] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[14:12:23.058] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[14:12:26.481] <TB3> INFO: Test took 16269ms.
[14:12:28.310] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:28.598] <TB3> INFO: dacScan step from 83 .. 83
[14:12:43.661] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[14:12:43.661] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[14:12:43.666] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[14:12:44.664] <TB3> INFO: Test took 16065ms.
[14:12:46.834] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:12:47.120] <TB3> INFO: dacScan step from 84 .. 84
[14:13:03.355] <TB3> INFO: Test took 16234ms.
[14:13:04.975] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:05.214] <TB3> INFO: dacScan step from 85 .. 85
[14:13:21.427] <TB3> INFO: Test took 16211ms.
[14:13:23.172] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:23.463] <TB3> INFO: dacScan step from 86 .. 86
[14:13:28.154] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (1) != Token Chain Length (4)

[14:13:28.154] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (1) != Token Chain Length (4)

[14:13:28.154] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

[14:13:28.154] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:13:28.154] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

[14:13:28.155] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[14:13:39.792] <TB3> INFO: Test took 16328ms.
[14:13:41.646] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:13:41.921] <TB3> INFO: dacScan step from 87 .. 87
[14:13:58.895] <TB3> INFO: Test took 16973ms.
[14:14:00.653] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:14:00.903] <TB3> INFO: dacScan step from 88 .. 88
[14:14:10.247] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[14:14:10.247] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[14:14:17.440] <TB3> INFO: Test took 16536ms.
[14:14:19.104] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:14:19.333] <TB3> INFO: dacScan step from 89 .. 89
[14:14:35.669] <TB3> INFO: Test took 16335ms.
[14:14:37.416] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:14:37.659] <TB3> INFO: dacScan step from 90 .. 90
[14:14:47.818] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[14:14:47.818] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[14:14:47.818] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:14:50.782] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[14:14:50.782] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[14:14:50.782] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[14:14:53.753] <TB3> INFO: Test took 16093ms.
[14:14:55.375] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:14:55.622] <TB3> INFO: dacScan step from 91 .. 91
[14:15:12.330] <TB3> INFO: Test took 16706ms.
[14:15:13.848] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:14.076] <TB3> INFO: dacScan step from 92 .. 92
[14:15:29.842] <TB3> INFO: Test took 15765ms.
[14:15:31.497] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:31.747] <TB3> INFO: dacScan step from 93 .. 93
[14:15:48.209] <TB3> INFO: Test took 16461ms.
[14:15:49.806] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:15:50.107] <TB3> INFO: dacScan step from 94 .. 94
[14:16:01.218] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[14:16:01.218] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:16:06.376] <TB3> INFO: Test took 16268ms.
[14:16:08.023] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:08.268] <TB3> INFO: dacScan step from 95 .. 95
[14:16:24.573] <TB3> INFO: Test took 16304ms.
[14:16:26.074] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:26.312] <TB3> INFO: dacScan step from 96 .. 96
[14:16:42.369] <TB3> INFO: Test took 16056ms.
[14:16:43.940] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:16:44.193] <TB3> INFO: dacScan step from 97 .. 97
[14:16:52.204] <TB3> WARNING: Channel 2 ROC 3: Readback start marker after 2 readouts!

[14:16:52.204] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[14:16:52.204] <TB3> WARNING: Channel 2 ROC 3: Readback start marker after 14 readouts!

[14:16:52.204] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[14:17:00.097] <TB3> INFO: Test took 15903ms.
[14:17:01.677] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:01.914] <TB3> INFO: dacScan step from 98 .. 98
[14:17:18.020] <TB3> INFO: Test took 16105ms.
[14:17:19.457] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:19.687] <TB3> INFO: dacScan step from 99 .. 99
[14:17:36.406] <TB3> INFO: Test took 16718ms.
[14:17:38.222] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:38.457] <TB3> INFO: dacScan step from 100 .. 100
[14:17:54.969] <TB3> INFO: Test took 16511ms.
[14:17:56.707] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:17:56.952] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[14:17:58.375] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[14:17:59.697] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[14:18:00.970] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[14:18:02.296] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[14:18:03.634] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[14:18:05.011] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[14:18:06.359] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[14:18:07.718] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[14:18:09.124] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[14:18:10.500] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[14:18:11.901] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[14:18:14.526] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[14:18:20.022] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[14:18:24.919] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[14:18:26.536] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[14:18:27.808] <TB3> INFO: PixTest:: pg_setup set to default.
[14:18:27.810] <TB3> INFO: vcal mean: 38.35 38.65 37.71 40.40 41.38 41.72 42.29 41.98 42.01 41.53 42.54 40.82 39.87 38.25 37.88 38.30
[14:18:27.810] <TB3> INFO: vcal RMS: 1.27 1.27 1.22 1.44 1.41 1.40 1.57 1.53 1.45 1.45 1.59 1.51 2.04 1.23 1.15 1.24
[14:18:27.810] <TB3> INFO: number of X-ray hits detected: 168429 177384 163594 280397 312195 358947 374502 351482 359602 342312 366627 284805 268584 164058 158925 174165
[14:18:27.810] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:18:27.810] <TB3> INFO: X-ray hit rate [MHz/cm2]: 49.4 52.0 48.0 82.2 91.5 105.2 109.8 103.0 105.4 100.3 107.5 83.5 78.7 48.1 46.6 51.0
[14:18:27.810] <TB3> INFO: PixTestHighRate::doXNoiseMaps() done
[14:18:27.906] <TB3> INFO: enter test to run
[14:18:27.906] <TB3> INFO: test: no parameter change
[14:18:28.072] <TB3> QUIET: Connection to board 121 closed.
[14:18:28.152] <TB3> INFO: pXar: this is the end, my friend
commander_CalDelScanAndSaveDacs.log
[14:38:44.358] <TB3> INFO: *** Welcome to pxar ***
[14:38:44.358] <TB3> INFO: *** Today: 2015/08/11
[14:38:44.358] <TB3> INFO: readRocDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[14:38:44.359] <TB3> INFO: readTbmDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0a.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0b.dat
[14:38:44.359] <TB3> INFO: readMaskFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//defaultMaskFile.dat
[14:38:44.359] <TB3> INFO: readTrimFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C15.dat
[14:38:44.438] <TB3> INFO: clk: 4
[14:38:44.438] <TB3> INFO: ctr: 4
[14:38:44.438] <TB3> INFO: sda: 19
[14:38:44.438] <TB3> INFO: tin: 9
[14:38:44.438] <TB3> INFO: level: 15
[14:38:44.438] <TB3> INFO: triggerdelay: 0
[14:38:44.438] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[14:38:44.438] <TB3> INFO: Log level: INFO
[14:38:45.448] <TB3> INFO: Found DTB DTB_WWV6Z5
[14:38:48.471] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[14:38:48.474] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[14:38:48.477] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[14:38:50.006] <TB3> INFO: DUT info:
[14:38:50.006] <TB3> INFO: The DUT currently contains the following objects:
[14:38:50.006] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:38:50.006] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:38:50.006] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:38:50.006] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:38:50.006] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:38:50.006] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:38:50.006] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:38:50.006] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:38:50.006] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:38:50.007] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:38:50.008] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:38:50.008] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:38:50.008] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:38:50.008] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:38:50.008] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:38:50.008] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:38:50.008] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:38:50.008] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:38:50.008] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:38:50.008] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:38:50.409] <TB3> INFO: enter 'restricted' command line mode
[14:38:50.409] <TB3> INFO: enter test to run
[14:38:50.410] <TB3> INFO: test: HighRate no parameter change
[14:38:50.410] <TB3> INFO: running: highrate
[14:38:50.410] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C15.dat
[14:38:50.567] <TB3> INFO: ----------------------------------------------------------------------
[14:38:50.567] <TB3> INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:38:50.567] <TB3> INFO: ----------------------------------------------------------------------
[14:38:51.650] <TB3> INFO: Test took 1078ms.
[14:38:55.668] <TB3> INFO: Test took 4013ms.
[14:38:55.799] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:38:59.601] <TB3> INFO: Test took 3777ms.
[14:38:59.765] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:03.710] <TB3> INFO: Test took 3911ms.
[14:39:03.843] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:07.708] <TB3> INFO: Test took 3838ms.
[14:39:07.852] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:11.641] <TB3> INFO: Test took 3761ms.
[14:39:11.785] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:15.687] <TB3> INFO: Test took 3868ms.
[14:39:15.839] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:19.749] <TB3> INFO: Test took 3881ms.
[14:39:19.887] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:23.667] <TB3> INFO: Test took 3755ms.
[14:39:23.809] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:27.676] <TB3> INFO: Test took 3841ms.
[14:39:27.835] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:31.745] <TB3> INFO: Test took 3878ms.
[14:39:31.890] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:35.821] <TB3> INFO: Test took 3904ms.
[14:39:35.970] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:39.866] <TB3> INFO: Test took 3871ms.
[14:39:40.025] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:43.958] <TB3> INFO: Test took 3903ms.
[14:39:44.096] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:47.930] <TB3> INFO: Test took 3809ms.
[14:39:48.075] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:52.110] <TB3> INFO: Test took 4009ms.
[14:39:52.261] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:56.218] <TB3> INFO: Test took 3929ms.
[14:39:56.377] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:00.167] <TB3> INFO: Test took 3756ms.
[14:40:00.295] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:04.033] <TB3> INFO: Test took 3714ms.
[14:40:04.162] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:07.800] <TB3> INFO: Test took 3613ms.
[14:40:07.929] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:11.560] <TB3> INFO: Test took 3607ms.
[14:40:11.681] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:11.976] <TB3> INFO: enter test to run
[14:40:11.976] <TB3> INFO: test: Pretest no parameter change
[14:40:11.976] <TB3> INFO: running: pretest
[14:40:11.976] <TB3> INFO: write dac parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat
[14:40:11.976] <TB3> INFO: write dac parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C1.dat
[14:40:11.976] <TB3> INFO: write dac parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C2.dat
[14:40:11.976] <TB3> INFO: write dac parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C3.dat
[14:40:11.976] <TB3> INFO: write dac parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C4.dat
[14:40:11.977] <TB3> INFO: write dac parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C5.dat
[14:40:11.977] <TB3> INFO: write dac parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C6.dat
[14:40:11.977] <TB3> INFO: write dac parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C7.dat
[14:40:11.977] <TB3> INFO: write dac parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C8.dat
[14:40:11.977] <TB3> INFO: write dac parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C9.dat
[14:40:11.977] <TB3> INFO: write dac parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C10.dat
[14:40:11.977] <TB3> INFO: write dac parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C11.dat
[14:40:11.977] <TB3> INFO: write dac parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C12.dat
[14:40:11.977] <TB3> INFO: write dac parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C13.dat
[14:40:11.977] <TB3> INFO: write dac parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C14.dat
[14:40:11.977] <TB3> INFO: write dac parameters into /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[14:40:11.977] <TB3> INFO: enter test to run
[14:40:11.977] <TB3> INFO: test: no parameter change
[14:40:12.152] <TB3> QUIET: Connection to board 121 closed.
[14:40:12.232] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[14:40:46.356] <TB3> INFO: *** Welcome to pxar ***
[14:40:46.356] <TB3> INFO: *** Today: 2015/08/11
[14:40:46.357] <TB3> INFO: readRocDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//011_HREfficiency_50//dacParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//011_HREfficiency_50//dacParameters35_C15.dat
[14:40:46.360] <TB3> INFO: readTbmDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//011_HREfficiency_50//tbmParameters_C0a.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//011_HREfficiency_50//tbmParameters_C0b.dat
[14:40:46.360] <TB3> INFO: readMaskFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//011_HREfficiency_50//defaultMaskFile.dat
[14:40:46.360] <TB3> INFO: readTrimFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//011_HREfficiency_50//trimParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//011_HREfficiency_50//trimParameters35_C15.dat
[14:40:46.438] <TB3> INFO: clk: 4
[14:40:46.438] <TB3> INFO: ctr: 4
[14:40:46.438] <TB3> INFO: sda: 19
[14:40:46.438] <TB3> INFO: tin: 9
[14:40:46.438] <TB3> INFO: level: 15
[14:40:46.438] <TB3> INFO: triggerdelay: 0
[14:40:46.438] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[14:40:46.438] <TB3> INFO: Log level: INFO
[14:40:47.450] <TB3> INFO: Found DTB DTB_WWV6Z5
[14:40:50.473] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[14:40:50.476] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[14:40:50.479] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[14:40:51.998] <TB3> INFO: DUT info:
[14:40:51.998] <TB3> INFO: The DUT currently contains the following objects:
[14:40:51.998] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:40:51.998] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:40:51.998] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:40:51.998] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:40:51.998] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:40:51.998] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:40:51.998] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:40:51.998] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:40:51.998] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:40:51.998] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:40:51.998] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:40:51.998] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:40:51.998] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:40:51.998] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:40:51.998] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:40:51.998] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:40:51.998] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:40:51.998] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:40:51.998] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:40:51.998] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:40:52.399] <TB3> INFO: enter 'restricted' command line mode
[14:40:52.400] <TB3> INFO: enter test to run
[14:40:52.400] <TB3> INFO: test: HighRate no parameter change
[14:40:52.400] <TB3> INFO: running: highrate
[14:40:52.400] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//011_HREfficiency_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//011_HREfficiency_50//phCalibrationFitErr35_C15.dat
[14:40:52.541] <TB3> INFO: ----------------------------------------------------------------------
[14:40:52.541] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:40:52.541] <TB3> INFO: ----------------------------------------------------------------------
[14:41:05.424] <TB3> INFO: Test took 12877ms.
[14:41:06.092] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:06.391] <TB3> INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 2 0 0 0
[14:41:06.391] <TB3> INFO: number of red-efficiency pixels: 118 115 116 183 287 346 327 256 295 279 284 183 195 115 82 121
[14:41:06.391] <TB3> INFO: number of X-ray hits detected: 83136 86905 80018 140127 156707 182222 187504 176352 180950 171697 186659 141684 134984 80506 77829 87024
[14:41:06.391] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:41:06.391] <TB3> INFO: number of Vcal hits detected: 207833 207881 207883 207812 207702 207648 207658 207740 207692 207710 207707 207808 207694 207881 207917 207876
[14:41:06.391] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 99.9
[14:41:06.391] <TB3> INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9
[14:41:06.391] <TB3> INFO: X-ray hit rate [MHz/cm2]: 24.4 25.5 23.5 41.1 45.9 53.4 55.0 51.7 53.0 50.3 54.7 41.5 39.6 23.6 22.8 25.5
[14:41:06.391] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[14:41:06.417] <TB3> INFO: PixTest:: pg_setup set to default.
[14:41:06.448] <TB3> INFO: enter test to run
[14:41:06.448] <TB3> INFO: test: no parameter change
[14:41:06.573] <TB3> QUIET: Connection to board 121 closed.
[14:41:06.661] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[14:41:42.751] <TB3> INFO: *** Welcome to pxar ***
[14:41:42.751] <TB3> INFO: *** Today: 2015/08/11
[14:41:42.751] <TB3> INFO: readRocDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//012_HREfficiency_100//dacParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//012_HREfficiency_100//dacParameters35_C15.dat
[14:41:42.753] <TB3> INFO: readTbmDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//012_HREfficiency_100//tbmParameters_C0a.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//012_HREfficiency_100//tbmParameters_C0b.dat
[14:41:42.753] <TB3> INFO: readMaskFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//012_HREfficiency_100//defaultMaskFile.dat
[14:41:42.753] <TB3> INFO: readTrimFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//012_HREfficiency_100//trimParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//012_HREfficiency_100//trimParameters35_C15.dat
[14:41:42.844] <TB3> INFO: clk: 4
[14:41:42.844] <TB3> INFO: ctr: 4
[14:41:42.844] <TB3> INFO: sda: 19
[14:41:42.844] <TB3> INFO: tin: 9
[14:41:42.844] <TB3> INFO: level: 15
[14:41:42.844] <TB3> INFO: triggerdelay: 0
[14:41:42.844] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[14:41:42.844] <TB3> INFO: Log level: INFO
[14:41:43.855] <TB3> INFO: Found DTB DTB_WWV6Z5
[14:41:46.876] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[14:41:46.879] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[14:41:46.882] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[14:41:48.490] <TB3> INFO: DUT info:
[14:41:48.490] <TB3> INFO: The DUT currently contains the following objects:
[14:41:48.490] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:41:48.490] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:41:48.490] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:41:48.490] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:41:48.490] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:41:48.490] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:41:48.490] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:41:48.490] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:41:48.490] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:41:48.490] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:41:48.490] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:41:48.490] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:41:48.490] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:41:48.490] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:41:48.490] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:41:48.490] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:41:48.490] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:41:48.490] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:41:48.491] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:41:48.491] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:41:48.892] <TB3> INFO: enter 'restricted' command line mode
[14:41:48.892] <TB3> INFO: enter test to run
[14:41:48.892] <TB3> INFO: test: HighRate no parameter change
[14:41:48.892] <TB3> INFO: running: highrate
[14:41:48.892] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//012_HREfficiency_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//012_HREfficiency_100//phCalibrationFitErr35_C15.dat
[14:41:49.039] <TB3> INFO: ----------------------------------------------------------------------
[14:41:49.039] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:41:49.039] <TB3> INFO: ----------------------------------------------------------------------
[14:42:04.530] <TB3> INFO: Test took 15485ms.
[14:42:06.315] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:42:06.686] <TB3> INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 2 0 0 0
[14:42:06.686] <TB3> INFO: number of red-efficiency pixels: 291 315 315 647 1013 1137 1112 917 1065 848 1047 685 582 353 258 343
[14:42:06.686] <TB3> INFO: number of X-ray hits detected: 168912 177901 163210 280272 314292 361061 375400 351878 359846 343705 369649 286845 271541 164731 160164 174558
[14:42:06.686] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:42:06.686] <TB3> INFO: number of Vcal hits detected: 207638 207672 207654 207270 206715 206533 206540 206917 206713 206995 206695 207170 207250 207588 207734 207637
[14:42:06.686] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.7 99.5 99.4 99.4 99.5 99.4 99.6 99.4 99.6 99.7 99.8 99.9 99.8
[14:42:06.686] <TB3> INFO: Vcal hit overall efficiency (%): 99.8 99.8 99.8 99.6 99.4 99.3 99.3 99.5 99.4 99.5 99.4 99.6 99.6 99.8 99.9 99.8
[14:42:06.686] <TB3> INFO: X-ray hit rate [MHz/cm2]: 49.5 52.1 47.8 82.1 92.1 105.8 110.0 103.1 105.5 100.7 108.3 84.1 79.6 48.3 46.9 51.2
[14:42:06.686] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[14:42:06.714] <TB3> INFO: PixTest:: pg_setup set to default.
[14:42:06.753] <TB3> INFO: enter test to run
[14:42:06.753] <TB3> INFO: test: no parameter change
[14:42:06.891] <TB3> QUIET: Connection to board 121 closed.
[14:42:06.971] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[14:42:42.977] <TB3> INFO: *** Welcome to pxar ***
[14:42:42.977] <TB3> INFO: *** Today: 2015/08/11
[14:42:42.977] <TB3> INFO: readRocDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//013_HREfficiency_150//dacParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//013_HREfficiency_150//dacParameters35_C15.dat
[14:42:42.979] <TB3> INFO: readTbmDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//013_HREfficiency_150//tbmParameters_C0a.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//013_HREfficiency_150//tbmParameters_C0b.dat
[14:42:42.979] <TB3> INFO: readMaskFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//013_HREfficiency_150//defaultMaskFile.dat
[14:42:42.980] <TB3> INFO: readTrimFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//013_HREfficiency_150//trimParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//013_HREfficiency_150//trimParameters35_C15.dat
[14:42:43.075] <TB3> INFO: clk: 4
[14:42:43.075] <TB3> INFO: ctr: 4
[14:42:43.075] <TB3> INFO: sda: 19
[14:42:43.075] <TB3> INFO: tin: 9
[14:42:43.075] <TB3> INFO: level: 15
[14:42:43.075] <TB3> INFO: triggerdelay: 0
[14:42:43.075] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[14:42:43.075] <TB3> INFO: Log level: INFO
[14:42:44.088] <TB3> INFO: Found DTB DTB_WWV6Z5
[14:42:47.110] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[14:42:47.113] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[14:42:47.116] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[14:42:48.754] <TB3> INFO: DUT info:
[14:42:48.754] <TB3> INFO: The DUT currently contains the following objects:
[14:42:48.754] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:42:48.754] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:42:48.754] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:42:48.754] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:42:48.754] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:48.754] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:48.754] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:48.754] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:48.754] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:48.754] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:48.755] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:48.755] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:48.755] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:48.755] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:48.755] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:48.755] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:48.755] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:48.755] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:48.755] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:48.755] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:42:49.162] <TB3> INFO: enter 'restricted' command line mode
[14:42:49.162] <TB3> INFO: enter test to run
[14:42:49.162] <TB3> INFO: test: HighRate no parameter change
[14:42:49.162] <TB3> INFO: running: highrate
[14:42:49.162] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//013_HREfficiency_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//013_HREfficiency_150//phCalibrationFitErr35_C15.dat
[14:42:49.317] <TB3> INFO: ----------------------------------------------------------------------
[14:42:49.317] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:42:49.317] <TB3> INFO: ----------------------------------------------------------------------
[14:43:06.808] <TB3> INFO: Test took 17485ms.
[14:43:09.998] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:43:10.433] <TB3> INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 2 0 0 0
[14:43:10.433] <TB3> INFO: number of red-efficiency pixels: 536 674 587 1294 2070 2258 2246 1999 2135 1818 2108 1342 1246 650 554 716
[14:43:10.433] <TB3> INFO: number of X-ray hits detected: 245514 259988 238364 404027 453360 516234 544564 506560 519261 493096 528882 414359 390763 239444 233620 254272
[14:43:10.433] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:43:10.433] <TB3> INFO: number of Vcal hits detected: 207367 207241 207192 206286 204445 203937 203752 204964 204636 205029 204542 206042 206280 207135 207401 207185
[14:43:10.433] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.7 99.7 99.7 99.3 98.5 98.3 98.2 98.7 98.6 98.8 98.6 99.2 99.3 99.6 99.7 99.7
[14:43:10.433] <TB3> INFO: Vcal hit overall efficiency (%): 99.7 99.6 99.6 99.2 98.3 98.0 98.0 98.5 98.4 98.6 98.3 99.1 99.2 99.6 99.7 99.6
[14:43:10.433] <TB3> INFO: X-ray hit rate [MHz/cm2]: 72.0 76.2 69.9 118.4 132.9 151.3 159.6 148.5 152.2 144.5 155.0 121.5 114.5 70.2 68.5 74.5
[14:43:10.433] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[14:43:10.459] <TB3> INFO: PixTest:: pg_setup set to default.
[14:43:10.493] <TB3> INFO: enter test to run
[14:43:10.493] <TB3> INFO: test: no parameter change
[14:43:10.612] <TB3> QUIET: Connection to board 121 closed.
[14:43:10.692] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[14:43:44.631] <TB3> INFO: *** Welcome to pxar ***
[14:43:44.631] <TB3> INFO: *** Today: 2015/08/11
[14:43:44.631] <TB3> INFO: readRocDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//014_HREfficiency_200//dacParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//014_HREfficiency_200//dacParameters35_C15.dat
[14:43:44.632] <TB3> INFO: readTbmDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//014_HREfficiency_200//tbmParameters_C0a.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//014_HREfficiency_200//tbmParameters_C0b.dat
[14:43:44.632] <TB3> INFO: readMaskFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//014_HREfficiency_200//defaultMaskFile.dat
[14:43:44.632] <TB3> INFO: readTrimFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//014_HREfficiency_200//trimParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//014_HREfficiency_200//trimParameters35_C15.dat
[14:43:44.744] <TB3> INFO: clk: 4
[14:43:44.744] <TB3> INFO: ctr: 4
[14:43:44.745] <TB3> INFO: sda: 19
[14:43:44.745] <TB3> INFO: tin: 9
[14:43:44.745] <TB3> INFO: level: 15
[14:43:44.745] <TB3> INFO: triggerdelay: 0
[14:43:44.745] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[14:43:44.745] <TB3> INFO: Log level: INFO
[14:43:45.755] <TB3> INFO: Found DTB DTB_WWV6Z5
[14:43:48.777] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[14:43:48.780] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[14:43:48.783] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[14:43:50.305] <TB3> INFO: DUT info:
[14:43:50.305] <TB3> INFO: The DUT currently contains the following objects:
[14:43:50.305] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:43:50.305] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:43:50.305] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:43:50.305] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:43:50.305] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:50.306] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:50.306] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:50.306] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:50.306] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:50.306] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:50.306] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:50.306] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:50.306] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:50.306] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:50.306] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:50.306] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:50.306] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:50.306] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:50.306] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:50.306] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:43:50.707] <TB3> INFO: enter 'restricted' command line mode
[14:43:50.707] <TB3> INFO: enter test to run
[14:43:50.707] <TB3> INFO: test: HighRate no parameter change
[14:43:50.707] <TB3> INFO: running: highrate
[14:43:50.708] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//014_HREfficiency_200//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//014_HREfficiency_200//phCalibrationFitErr35_C15.dat
[14:43:50.849] <TB3> INFO: ----------------------------------------------------------------------
[14:43:50.849] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:43:50.849] <TB3> INFO: ----------------------------------------------------------------------
[14:43:56.895] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[14:43:56.896] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[14:43:56.896] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (241) != TBM ID (0)

[14:43:56.896] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (0) != Token Chain Length (4)

[14:43:56.896] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (1) != TBM ID (241)

[14:43:56.896] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[14:43:56.896] <TB3> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[14:43:57.609] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (2) != Token Chain Length (4)

[14:43:57.609] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (3) != Token Chain Length (4)

[14:43:57.609] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (151) != TBM ID (65)

[14:43:57.609] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (0) != Token Chain Length (4)

[14:43:57.609] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (66) != TBM ID (151)

[14:43:57.614] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[14:44:11.277] <TB3> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L797> Incomplete DAQ data readout! Missing -2 Events.

[14:44:31.340] <TB3> INFO: Test took 20041ms.
[14:44:36.569] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:44:37.065] <TB3> INFO: number of dead pixels (per ROC): 1 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:44:37.065] <TB3> INFO: number of red-efficiency pixels: 940 1215 1054 2211 3151 3340 3410 3233 3328 2984 3261 2322 2188 1107 1091 1235
[14:44:37.065] <TB3> INFO: number of X-ray hits detected: 335758 352190 322948 544516 607637 687168 738000 683584 694394 663717 707243 558148 527129 324572 317945 343493
[14:44:37.065] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:44:37.065] <TB3> INFO: number of Vcal hits detected: 206816 206512 206028 204109 199748 198678 197555 200549 199935 200824 199480 203219 204228 205981 206681 206351
[14:44:37.065] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.5 99.3 99.2 98.4 96.5 96.1 95.6 96.7 96.5 97.0 96.4 98.0 98.4 99.2 99.4 99.3
[14:44:37.065] <TB3> INFO: Vcal hit overall efficiency (%): 99.4 99.3 99.1 98.1 96.0 95.5 95.0 96.4 96.1 96.5 95.9 97.7 98.2 99.0 99.4 99.2
[14:44:37.065] <TB3> INFO: X-ray hit rate [MHz/cm2]: 98.4 103.2 94.7 159.6 178.1 201.4 216.3 200.4 203.5 194.5 207.3 163.6 154.5 95.1 93.2 100.7
[14:44:37.065] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[14:44:37.091] <TB3> INFO: PixTest:: pg_setup set to default.
[14:44:37.125] <TB3> INFO: enter test to run
[14:44:37.125] <TB3> INFO: test: no parameter change
[14:44:37.266] <TB3> QUIET: Connection to board 121 closed.
[14:44:37.352] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[14:45:12.751] <TB3> INFO: *** Welcome to pxar ***
[14:45:12.751] <TB3> INFO: *** Today: 2015/08/11
[14:45:12.751] <TB3> INFO: readRocDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//015_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//015_HREfficiency_250//dacParameters35_C15.dat
[14:45:12.752] <TB3> INFO: readTbmDacs: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//015_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//015_HREfficiency_250//tbmParameters_C0b.dat
[14:45:12.752] <TB3> INFO: readMaskFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//015_HREfficiency_250//defaultMaskFile.dat
[14:45:12.752] <TB3> INFO: readTrimFile: /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//015_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//015_HREfficiency_250//trimParameters35_C15.dat
[14:45:12.826] <TB3> INFO: clk: 4
[14:45:12.827] <TB3> INFO: ctr: 4
[14:45:12.827] <TB3> INFO: sda: 19
[14:45:12.827] <TB3> INFO: tin: 9
[14:45:12.827] <TB3> INFO: level: 15
[14:45:12.827] <TB3> INFO: triggerdelay: 0
[14:45:12.827] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[14:45:12.827] <TB3> INFO: Log level: INFO
[14:45:13.836] <TB3> INFO: Found DTB DTB_WWV6Z5
[14:45:16.858] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[14:45:16.861] <TB3> INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 121
HW version: DTB1.2
FW version: 4.0
SW version: 4.0
USB id: DTB_WWV6Z5
MAC address: 40D855118079
Hostname: pixelDTB121
Comment:
------------------------------------------------------
[14:45:16.864] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[14:45:18.390] <TB3> INFO: DUT info:
[14:45:18.390] <TB3> INFO: The DUT currently contains the following objects:
[14:45:18.390] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[14:45:18.390] <TB3> INFO: TBM Core alpha (0): 7 registers set
[14:45:18.390] <TB3> INFO: TBM Core beta (1): 7 registers set
[14:45:18.390] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:45:18.390] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:45:18.390] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:45:18.390] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:45:18.390] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:45:18.390] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:45:18.390] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:45:18.390] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:45:18.390] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:45:18.390] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:45:18.390] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:45:18.391] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:45:18.391] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:45:18.391] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:45:18.391] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:45:18.391] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:45:18.391] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:45:18.792] <TB3> INFO: enter 'restricted' command line mode
[14:45:18.792] <TB3> INFO: enter test to run
[14:45:18.792] <TB3> INFO: test: HighRate no parameter change
[14:45:18.792] <TB3> INFO: running: highrate
[14:45:18.793] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//015_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2067_XrayQualification_2015-08-11_15h16m_1439299004//015_HREfficiency_250//phCalibrationFitErr35_C15.dat
[14:45:18.934] <TB3> INFO: ----------------------------------------------------------------------
[14:45:18.934] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:45:18.934] <TB3> INFO: ----------------------------------------------------------------------
[14:45:23.195] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[14:45:23.195] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (1) != Token Chain Length (4)

[14:45:23.195] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (3) != Token Chain Length (4)

[14:45:23.195] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[14:45:23.195] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

[14:45:23.195] <TB3> WARNING: Channel 2 ROC 2: Readback start marker after 15 readouts!

[14:45:30.184] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (3) != Token Chain Length (4)

[14:45:39.688] <TB3> INFO: Test took 20748ms.
[14:45:45.494] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[14:45:46.012] <TB3> INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0
[14:45:46.012] <TB3> INFO: number of red-efficiency pixels: 1057 1339 1060 2362 3222 3446 3451 3365 3492 3085 3390 2504 2296 1173 1180 1382
[14:45:46.012] <TB3> INFO: number of X-ray hits detected: 346974 366408 336917 561773 626917 711101 760707 706926 723359 686371 728561 577214 545416 339276 332988 356143
[14:45:46.012] <TB3> INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:45:46.012] <TB3> INFO: number of Vcal hits detected: 206692 206320 205967 203755 199128 197829 196633 199931 198996 200074 198709 202715 203845 205835 206532 206138
[14:45:46.012] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.4 99.2 99.2 98.2 96.2 95.7 95.2 96.5 96.0 96.7 96.1 97.8 98.2 99.1 99.4 99.2
[14:45:46.012] <TB3> INFO: Vcal hit overall efficiency (%): 99.4 99.2 99.0 98.0 95.7 95.1 94.5 96.1 95.7 96.2 95.5 97.5 98.0 99.0 99.3 99.1
[14:45:46.012] <TB3> INFO: X-ray hit rate [MHz/cm2]: 101.7 107.4 98.8 164.7 183.8 208.4 223.0 207.2 212.0 201.2 213.5 169.2 159.9 99.4 97.6 104.4
[14:45:46.012] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[14:45:46.038] <TB3> INFO: PixTest:: pg_setup set to default.
[14:45:46.074] <TB3> INFO: enter test to run
[14:45:46.074] <TB3> INFO: test: no parameter change
[14:45:46.249] <TB3> QUIET: Connection to board 121 closed.
[14:45:46.328] <TB3> INFO: pXar: this is the end, my friend
MoReWeb-v1.0.0-5-g82d9ff6 on branch psi46master