Test Date: 2015-08-11 09:36
Analysis date: 2016-05-26 00:23
Logfile
commander_PixelAlive.log
[07:37:32.378] <TB3> INFO: *** Welcome to pxar ***
[07:37:32.378] <TB3> INFO: *** Today: 2015/08/11
[07:37:32.378] <TB3> INFO: readRocDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//000_PixelAlive_p17//dacParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//000_PixelAlive_p17//dacParameters35_C15.dat
[07:37:32.379] <TB3> INFO: readTbmDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//000_PixelAlive_p17//tbmParameters_C0a.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//000_PixelAlive_p17//tbmParameters_C0b.dat
[07:37:32.379] <TB3> INFO: readMaskFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//000_PixelAlive_p17//defaultMaskFile.dat
[07:37:32.379] <TB3> INFO: readTrimFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//000_PixelAlive_p17//trimParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//000_PixelAlive_p17//trimParameters35_C15.dat
[07:37:32.454] <TB3> INFO: clk: 4
[07:37:32.454] <TB3> INFO: ctr: 4
[07:37:32.454] <TB3> INFO: sda: 19
[07:37:32.454] <TB3> INFO: tin: 9
[07:37:32.454] <TB3> INFO: level: 15
[07:37:32.454] <TB3> INFO: triggerdelay: 0
[07:37:32.454] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[07:37:32.454] <TB3> INFO: Log level: INFO
[07:37:32.466] <TB3> INFO: Found DTB DTB_WWV6Z5
[07:37:32.485] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[07:37:32.488] <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:
------------------------------------------------------
[07:37:32.491] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[07:37:34.016] <TB3> INFO: DUT info:
[07:37:34.016] <TB3> INFO: The DUT currently contains the following objects:
[07:37:34.016] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[07:37:34.016] <TB3> INFO: TBM Core alpha (0): 7 registers set
[07:37:34.016] <TB3> INFO: TBM Core beta (1): 7 registers set
[07:37:34.016] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:37:34.016] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:34.016] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:34.016] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:34.016] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:34.016] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:34.016] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:34.016] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:34.016] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:34.016] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:34.016] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:34.017] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:34.017] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:34.017] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:34.017] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:34.017] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:34.017] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:37:34.418] <TB3> INFO: enter 'restricted' command line mode
[07:37:34.418] <TB3> INFO: enter test to run
[07:37:34.418] <TB3> INFO: test: PixelAlive no parameter change
[07:37:34.418] <TB3> INFO: running: pixelalive
[07:37:34.423] <TB3> INFO: ######################################################################
[07:37:34.423] <TB3> INFO: PixTestAlive::doTest()
[07:37:34.423] <TB3> INFO: ######################################################################
[07:37:34.424] <TB3> INFO: ----------------------------------------------------------------------
[07:37:34.424] <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)
[07:37:34.424] <TB3> INFO: ----------------------------------------------------------------------
[07:37:38.029] <TB3> INFO: Test took 3603ms.
[07:37:38.046] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:37:38.291] <TB3> INFO: PixTestAlive::aliveTest() done
[07:37:38.291] <TB3> INFO: number of dead pixels (per ROC): 1 0 1 2 1 0 0 3 1 2 0 1 1 0 1 2
[07:37:38.292] <TB3> INFO: ----------------------------------------------------------------------
[07:37:38.292] <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)
[07:37:38.292] <TB3> INFO: ----------------------------------------------------------------------
[07:37:41.049] <TB3> INFO: Test took 2755ms.
[07:37:41.050] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:37:41.050] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[07:37:41.269] <TB3> INFO: PixTestAlive::maskTest() done
[07:37:41.269] <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
[07:37:41.271] <TB3> INFO: ----------------------------------------------------------------------
[07:37:41.271] <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)
[07:37:41.271] <TB3> INFO: ----------------------------------------------------------------------
[07:37:44.885] <TB3> INFO: Test took 3613ms.
[07:37:44.902] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[07:37:45.123] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[07:37:45.123] <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
[07:37:45.124] <TB3> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[07:37:45.152] <TB3> INFO: enter test to run
[07:37:45.152] <TB3> INFO: test: no parameter change
[07:37:45.276] <TB3> QUIET: Connection to board 121 closed.
[07:37:45.356] <TB3> INFO: pXar: this is the end, my friend
commander_RetrimHotPixels.log
[07:43:00.345] <TB3> INFO: *** Welcome to pxar ***
[07:43:00.345] <TB3> INFO: *** Today: 2015/08/11
[07:43:00.345] <TB3> INFO: readRocDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//dacParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//dacParameters35_C15.dat
[07:43:00.346] <TB3> INFO: readTbmDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//tbmParameters_C0a.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//tbmParameters_C0b.dat
[07:43:00.346] <TB3> INFO: readMaskFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//defaultMaskFile.dat
[07:43:00.346] <TB3> INFO: readTrimFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//trimParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//trimParameters35_C15.dat
[07:43:00.431] <TB3> INFO: clk: 4
[07:43:00.432] <TB3> INFO: ctr: 4
[07:43:00.432] <TB3> INFO: sda: 19
[07:43:00.432] <TB3> INFO: tin: 9
[07:43:00.432] <TB3> INFO: level: 15
[07:43:00.432] <TB3> INFO: triggerdelay: 0
[07:43:00.432] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[07:43:00.432] <TB3> INFO: Log level: INFO
[07:43:01.441] <TB3> INFO: Found DTB DTB_WWV6Z5
[07:43:04.464] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[07:43:04.467] <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:
------------------------------------------------------
[07:43:04.471] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[07:43:06.001] <TB3> INFO: DUT info:
[07:43:06.001] <TB3> INFO: The DUT currently contains the following objects:
[07:43:06.001] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[07:43:06.001] <TB3> INFO: TBM Core alpha (0): 7 registers set
[07:43:06.002] <TB3> INFO: TBM Core beta (1): 7 registers set
[07:43:06.002] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:43:06.002] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:43:06.002] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:43:06.002] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:43:06.002] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:43:06.002] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:43:06.002] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:43:06.002] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:43:06.002] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:43:06.002] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:43:06.002] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:43:06.002] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:43:06.002] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:43:06.002] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:43:06.002] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:43:06.002] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:43:06.002] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:43:06.403] <TB3> INFO: enter 'restricted' command line mode
[07:43:06.403] <TB3> INFO: enter test to run
[07:43:06.403] <TB3> INFO: test: delay setting parameters: ->5<-
[07:43:06.403] <TB3> INFO: delay test by 5 seconds...
[07:43:11.403] <TB3> INFO: enter test to run
[07:43:11.404] <TB3> INFO: test: highrate no parameter change
[07:43:11.404] <TB3> INFO: running: highrate
[07:43:11.404] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//phCalibrationFitErr35_C15.dat
[07:43:11.555] <TB3> INFO: ----------------------------------------------------------------------
[07:43:11.555] <TB3> INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[07:43:11.555] <TB3> INFO: ----------------------------------------------------------------------
[07:43:11.555] <TB3> INFO: THR = 150, corresponding to ~ 400 MHz/cm2
[07:43:11.555] <TB3> INFO: edge/corner pixel THR is adjusted
[07:43:11.555] <TB3> INFO: PixTestHighRate::trimHotPixels: step 0...
[07:43:12.518] <TB3> INFO: Collecting data for 1 seconds...
[07:43:13.519] <TB3> INFO: Done with hot pixel readout
[07:43:19.189] <TB3> INFO: PixTest:: pg_setup set to default.
[07:43:19.190] <TB3> INFO: 9 hot pixels found in step 0
[07:43:20.153] <TB3> INFO: Collecting data for 1 seconds...
[07:43:21.154] <TB3> INFO: Done with hot pixel readout
[07:43:26.978] <TB3> INFO: PixTest:: pg_setup set to default.
[07:43:26.979] <TB3> INFO: 8 hot pixels found in step 1
[07:43:27.941] <TB3> INFO: Collecting data for 1 seconds...
[07:43:28.943] <TB3> INFO: Done with hot pixel readout
[07:43:34.707] <TB3> INFO: PixTest:: pg_setup set to default.
[07:43:34.708] <TB3> INFO: 6 hot pixels found in step 2
[07:43:35.670] <TB3> INFO: Collecting data for 1 seconds...
[07:43:36.672] <TB3> INFO: Done with hot pixel readout
[07:43:42.806] <TB3> INFO: PixTest:: pg_setup set to default.
[07:43:42.806] <TB3> INFO: 0 hot pixels found in step 3
[07:43:42.814] <TB3> INFO: 0 hot pixels could not be trimmed and have been masked.
[07:43:42.843] <TB3> INFO: PixTest::trimHotPixels() done
[07:43:42.843] <TB3> INFO: write trim parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//trimParameters35_C0.dat
[07:43:42.856] <TB3> INFO: write trim parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//trimParameters35_C1.dat
[07:43:42.869] <TB3> INFO: write trim parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//trimParameters35_C2.dat
[07:43:42.882] <TB3> INFO: write trim parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//trimParameters35_C3.dat
[07:43:42.895] <TB3> INFO: write trim parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//trimParameters35_C4.dat
[07:43:42.908] <TB3> INFO: write trim parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//trimParameters35_C5.dat
[07:43:42.921] <TB3> INFO: write trim parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//trimParameters35_C6.dat
[07:43:42.934] <TB3> INFO: write trim parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//trimParameters35_C7.dat
[07:43:42.947] <TB3> INFO: write trim parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//trimParameters35_C8.dat
[07:43:42.953] <TB3> INFO: write trim parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//trimParameters35_C9.dat
[07:43:42.959] <TB3> INFO: write trim parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//trimParameters35_C10.dat
[07:43:42.965] <TB3> INFO: write trim parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//trimParameters35_C11.dat
[07:43:42.971] <TB3> INFO: write trim parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//trimParameters35_C12.dat
[07:43:42.977] <TB3> INFO: write trim parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//trimParameters35_C13.dat
[07:43:42.984] <TB3> INFO: write trim parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//trimParameters35_C14.dat
[07:43:42.990] <TB3> INFO: write trim parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//trimParameters35_C15.dat
[07:43:43.001] <TB3> INFO: write masked pixels into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//002_RetrimHotPixels_150//defaultMaskFile.dat
[07:43:43.055] <TB3> INFO: enter test to run
[07:43:43.055] <TB3> INFO: test: no parameter change
[07:43:43.241] <TB3> QUIET: Connection to board 121 closed.
[07:43:43.321] <TB3> INFO: pXar: this is the end, my friend
commander_HRData.log
[07:44:23.266] <TB3> INFO: *** Welcome to pxar ***
[07:44:23.266] <TB3> INFO: *** Today: 2015/08/11
[07:44:23.266] <TB3> INFO: readRocDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//003_HRData_50//dacParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//003_HRData_50//dacParameters35_C15.dat
[07:44:23.267] <TB3> INFO: readTbmDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//003_HRData_50//tbmParameters_C0a.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//003_HRData_50//tbmParameters_C0b.dat
[07:44:23.267] <TB3> INFO: readMaskFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//003_HRData_50//defaultMaskFile.dat
[07:44:23.267] <TB3> INFO: readTrimFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//003_HRData_50//trimParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//003_HRData_50//trimParameters35_C15.dat
[07:44:23.335] <TB3> INFO: clk: 4
[07:44:23.335] <TB3> INFO: ctr: 4
[07:44:23.335] <TB3> INFO: sda: 19
[07:44:23.335] <TB3> INFO: tin: 9
[07:44:23.335] <TB3> INFO: level: 15
[07:44:23.335] <TB3> INFO: triggerdelay: 0
[07:44:23.335] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[07:44:23.335] <TB3> INFO: Log level: INFO
[07:44:24.347] <TB3> INFO: Found DTB DTB_WWV6Z5
[07:44:27.370] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[07:44:27.373] <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:
------------------------------------------------------
[07:44:27.376] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[07:44:28.905] <TB3> INFO: DUT info:
[07:44:28.905] <TB3> INFO: The DUT currently contains the following objects:
[07:44:28.905] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[07:44:28.905] <TB3> INFO: TBM Core alpha (0): 7 registers set
[07:44:28.905] <TB3> INFO: TBM Core beta (1): 7 registers set
[07:44:28.905] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:44:28.905] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:44:28.905] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:44:28.905] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:44:28.905] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:44:28.905] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:44:28.905] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:44:28.905] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:44:28.905] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:44:28.906] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:44:28.906] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:44:28.906] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:44:28.906] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:44:28.906] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:44:28.906] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:44:28.906] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:44:28.906] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:44:29.307] <TB3> INFO: enter 'restricted' command line mode
[07:44:29.307] <TB3> INFO: enter test to run
[07:44:29.307] <TB3> INFO: test: delay setting parameters: ->5<-
[07:44:29.307] <TB3> INFO: delay test by 5 seconds...
[07:44:34.307] <TB3> INFO: enter test to run
[07:44:34.307] <TB3> INFO: test: Xray no parameter change
[07:44:34.307] <TB3> INFO: running: xray
[07:44:34.308] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//003_HRData_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//003_HRData_50//phCalibrationFitErr35_C15.dat
[07:44:34.472] <TB3> INFO: ######################################################################
[07:44:34.472] <TB3> INFO: PixTestXray::doTest()
[07:44:34.472] <TB3> INFO: ######################################################################
[07:44:34.472] <TB3> INFO: ----------------------------------------------------------------------
[07:44:34.472] <TB3> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[07:44:34.472] <TB3> INFO: ----------------------------------------------------------------------
[07:44:35.440] <TB3> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[07:44:42.127] <TB3> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[07:45:05.896] <TB3> INFO: Resuming triggers.
[07:45:12.590] <TB3> INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[07:45:15.478] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (8) != Token Chain Length (4)

[07:45:15.478] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (242) != TBM ID (243)

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

[07:45:15.478] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

[07:45:15.478] <TB3> WARNING: Channel 3 ROC 3: Readback start marker after 15 readouts!

[07:45:31.692] <TB3> INFO: Resuming triggers.
[07:45:38.389] <TB3> INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[07:46:00.719] <TB3> INFO: Resuming triggers.
[07:46:07.415] <TB3> INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[07:46:26.324] <TB3> INFO: Resuming triggers.
[07:46:33.021] <TB3> INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[07:46:52.844] <TB3> INFO: Resuming triggers.
[07:46:59.540] <TB3> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[07:47:20.793] <TB3> INFO: Resuming triggers.
[07:47:27.490] <TB3> INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[07:47:51.653] <TB3> INFO: Resuming triggers.
[07:47:58.351] <TB3> INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[07:48:21.475] <TB3> INFO: Resuming triggers.
[07:48:28.173] <TB3> INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[07:48:50.808] <TB3> INFO: Resuming triggers.
[07:48:57.508] <TB3> INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[07:49:19.194] <TB3> INFO: Resuming triggers.
[07:49:25.893] <TB3> INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[07:49:47.268] <TB3> INFO: Resuming triggers.
[07:49:53.969] <TB3> INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[07:50:12.915] <TB3> INFO: Resuming triggers.
[07:50:19.615] <TB3> INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[07:50:38.270] <TB3> INFO: Resuming triggers.
[07:50:44.973] <TB3> INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[07:51:05.538] <TB3> INFO: Resuming triggers.
[07:51:11.898] <TB3> INFO: data taking finished, elapsed time: 100 seconds.
[07:51:33.231] <TB3> INFO: PixTest:: pg_setup set to default.
[07:51:33.232] <TB3> INFO: PixTestXray::doPhRun() done
[07:51:33.232] <TB3> INFO: PixTestXray::doTest() done
[07:51:33.419] <TB3> INFO: enter test to run
[07:51:33.419] <TB3> INFO: test: no parameter change
[07:51:33.722] <TB3> QUIET: Connection to board 121 closed.
[07:51:33.801] <TB3> INFO: pXar: this is the end, my friend
commander_HRData.log
[07:53:16.092] <TB3> INFO: *** Welcome to pxar ***
[07:53:16.092] <TB3> INFO: *** Today: 2015/08/11
[07:53:16.092] <TB3> INFO: readRocDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//004_HRData_150//dacParameters35_C15.dat
[07:53:16.093] <TB3> INFO: readTbmDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//004_HRData_150//tbmParameters_C0b.dat
[07:53:16.093] <TB3> INFO: readMaskFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//004_HRData_150//defaultMaskFile.dat
[07:53:16.093] <TB3> INFO: readTrimFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//004_HRData_150//trimParameters35_C15.dat
[07:53:16.186] <TB3> INFO: clk: 4
[07:53:16.186] <TB3> INFO: ctr: 4
[07:53:16.186] <TB3> INFO: sda: 19
[07:53:16.186] <TB3> INFO: tin: 9
[07:53:16.186] <TB3> INFO: level: 15
[07:53:16.186] <TB3> INFO: triggerdelay: 0
[07:53:16.186] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[07:53:16.186] <TB3> INFO: Log level: INFO
[07:53:17.199] <TB3> INFO: Found DTB DTB_WWV6Z5
[07:53:20.223] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[07:53:20.226] <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:
------------------------------------------------------
[07:53:20.229] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[07:53:21.799] <TB3> INFO: DUT info:
[07:53:21.799] <TB3> INFO: The DUT currently contains the following objects:
[07:53:21.799] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[07:53:21.799] <TB3> INFO: TBM Core alpha (0): 7 registers set
[07:53:21.799] <TB3> INFO: TBM Core beta (1): 7 registers set
[07:53:21.799] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[07:53:21.799] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[07:53:21.799] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[07:53:21.799] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[07:53:21.799] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[07:53:21.799] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[07:53:21.799] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[07:53:21.799] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[07:53:21.800] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[07:53:21.800] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[07:53:21.800] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[07:53:21.800] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[07:53:21.800] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[07:53:21.800] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[07:53:21.800] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[07:53:21.800] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[07:53:21.800] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[07:53:22.201] <TB3> INFO: enter 'restricted' command line mode
[07:53:22.201] <TB3> INFO: enter test to run
[07:53:22.201] <TB3> INFO: test: delay setting parameters: ->5<-
[07:53:22.201] <TB3> INFO: delay test by 5 seconds...
[07:53:27.201] <TB3> INFO: enter test to run
[07:53:27.201] <TB3> INFO: test: Xray no parameter change
[07:53:27.202] <TB3> INFO: running: xray
[07:53:27.202] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//004_HRData_150//phCalibrationFitErr35_C15.dat
[07:53:27.405] <TB3> INFO: ######################################################################
[07:53:27.405] <TB3> INFO: PixTestXray::doTest()
[07:53:27.405] <TB3> INFO: ######################################################################
[07:53:27.405] <TB3> INFO: ----------------------------------------------------------------------
[07:53:27.405] <TB3> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[07:53:27.405] <TB3> INFO: ----------------------------------------------------------------------
[07:53:28.373] <TB3> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[07:53:32.058] <TB3> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[07:53:32.258] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

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

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

[07:53:32.258] <TB3> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[07:53:32.258] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

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

[07:53:57.880] <TB3> INFO: Resuming triggers.
[07:54:01.574] <TB3> INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[07:54:04.210] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (6) != Token Chain Length (4)

[07:54:23.469] <TB3> INFO: Resuming triggers.
[07:54:27.164] <TB3> INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[07:54:50.190] <TB3> INFO: Resuming triggers.
[07:54:53.883] <TB3> INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[07:54:53.928] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[07:54:53.928] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 31 readouts!

[07:55:17.124] <TB3> INFO: Resuming triggers.
[07:55:20.816] <TB3> INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[07:55:44.514] <TB3> INFO: Resuming triggers.
[07:55:48.205] <TB3> INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[07:56:10.605] <TB3> INFO: Resuming triggers.
[07:56:14.296] <TB3> INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[07:56:36.817] <TB3> INFO: Resuming triggers.
[07:56:40.506] <TB3> INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[07:57:05.059] <TB3> INFO: Resuming triggers.
[07:57:08.746] <TB3> INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[07:57:29.848] <TB3> INFO: Resuming triggers.
[07:57:33.535] <TB3> INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[07:57:57.045] <TB3> INFO: Resuming triggers.
[07:58:00.730] <TB3> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[07:58:26.449] <TB3> INFO: Resuming triggers.
[07:58:30.134] <TB3> INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[07:58:30.490] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

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

[07:58:30.491] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 31 readouts!

[07:58:30.491] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 31 readouts!

[07:58:54.048] <TB3> INFO: Resuming triggers.
[07:58:57.733] <TB3> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[07:59:19.373] <TB3> INFO: Resuming triggers.
[07:59:23.056] <TB3> INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[07:59:46.329] <TB3> INFO: Resuming triggers.
[07:59:50.010] <TB3> INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[08:00:15.484] <TB3> INFO: Resuming triggers.
[08:00:19.166] <TB3> INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[08:00:40.300] <TB3> INFO: Resuming triggers.
[08:00:43.982] <TB3> INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[08:01:06.406] <TB3> INFO: Resuming triggers.
[08:01:10.087] <TB3> INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[08:01:33.448] <TB3> INFO: Resuming triggers.
[08:01:37.128] <TB3> INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[08:01:57.728] <TB3> INFO: Resuming triggers.
[08:02:01.410] <TB3> INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[08:02:23.310] <TB3> INFO: Resuming triggers.
[08:02:26.988] <TB3> INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[08:02:46.968] <TB3> INFO: Resuming triggers.
[08:02:50.646] <TB3> INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[08:03:12.202] <TB3> INFO: Resuming triggers.
[08:03:15.878] <TB3> INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[08:03:41.071] <TB3> INFO: Resuming triggers.
[08:03:44.748] <TB3> INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[08:04:08.862] <TB3> INFO: Resuming triggers.
[08:04:12.541] <TB3> INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[08:04:12.676] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[08:04:12.676] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[08:04:35.754] <TB3> INFO: Resuming triggers.
[08:04:39.434] <TB3> INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[08:05:05.404] <TB3> INFO: Resuming triggers.
[08:05:09.085] <TB3> INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[08:05:33.835] <TB3> INFO: Resuming triggers.
[08:05:34.465] <TB3> INFO: data taking finished, elapsed time: 100 seconds.
[08:05:38.256] <TB3> INFO: PixTest:: pg_setup set to default.
[08:05:38.258] <TB3> INFO: PixTestXray::doPhRun() done
[08:05:38.258] <TB3> INFO: PixTestXray::doTest() done
[08:05:38.461] <TB3> INFO: enter test to run
[08:05:38.461] <TB3> INFO: test: no parameter change
[08:05:38.633] <TB3> QUIET: Connection to board 121 closed.
[08:05:38.713] <TB3> INFO: pXar: this is the end, my friend
commander_HRSCurves.log
[08:08:39.412] <TB3> INFO: *** Welcome to pxar ***
[08:08:39.412] <TB3> INFO: *** Today: 2015/08/11
[08:08:39.412] <TB3> INFO: readRocDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//005_HRSCurves_100//dacParameters35_C15.dat
[08:08:39.414] <TB3> INFO: readTbmDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//005_HRSCurves_100//tbmParameters_C0b.dat
[08:08:39.414] <TB3> INFO: readMaskFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//005_HRSCurves_100//defaultMaskFile.dat
[08:08:39.414] <TB3> INFO: readTrimFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//005_HRSCurves_100//trimParameters35_C15.dat
[08:08:39.488] <TB3> INFO: clk: 4
[08:08:39.488] <TB3> INFO: ctr: 4
[08:08:39.488] <TB3> INFO: sda: 19
[08:08:39.488] <TB3> INFO: tin: 9
[08:08:39.488] <TB3> INFO: level: 15
[08:08:39.488] <TB3> INFO: triggerdelay: 0
[08:08:39.488] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[08:08:39.488] <TB3> INFO: Log level: INFO
[08:08:40.500] <TB3> INFO: Found DTB DTB_WWV6Z5
[08:08:43.521] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[08:08:43.524] <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:
------------------------------------------------------
[08:08:43.527] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[08:08:45.051] <TB3> INFO: DUT info:
[08:08:45.051] <TB3> INFO: The DUT currently contains the following objects:
[08:08:45.051] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[08:08:45.051] <TB3> INFO: TBM Core alpha (0): 7 registers set
[08:08:45.051] <TB3> INFO: TBM Core beta (1): 7 registers set
[08:08:45.051] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:08:45.051] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.051] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.051] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.051] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.051] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.051] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.051] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.051] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.052] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.052] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.052] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.052] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.052] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.052] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.052] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.052] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:08:45.452] <TB3> INFO: enter 'restricted' command line mode
[08:08:45.452] <TB3> INFO: enter test to run
[08:08:45.453] <TB3> INFO: test: Highrate no parameter change
[08:08:45.453] <TB3> INFO: running: highrate
[08:08:45.453] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[08:08:45.583] <TB3> INFO: ----------------------------------------------------------------------
[08:08:45.583] <TB3> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[08:08:45.583] <TB3> INFO: ----------------------------------------------------------------------
[08:08:45.588] <TB3> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 0 .. 100 (1) hits flags = 384 (plus default)
[08:08:45.599] <TB3> INFO: dacScan step from 0 .. 0
[08:08:59.104] <TB3> INFO: Test took 13504ms.
[08:09:00.611] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:09:00.853] <TB3> INFO: dacScan step from 1 .. 1
[08:09:14.453] <TB3> INFO: Test took 13600ms.
[08:09:15.767] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:09:16.020] <TB3> INFO: dacScan step from 2 .. 2
[08:09:29.280] <TB3> INFO: Test took 13260ms.
[08:09:30.754] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:09:30.987] <TB3> INFO: dacScan step from 3 .. 3
[08:09:44.291] <TB3> INFO: Test took 13304ms.
[08:09:45.732] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:09:45.962] <TB3> INFO: dacScan step from 4 .. 4
[08:09:59.159] <TB3> INFO: Test took 13197ms.
[08:10:00.513] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:10:00.741] <TB3> INFO: dacScan step from 5 .. 5
[08:10:13.976] <TB3> INFO: Test took 13235ms.
[08:10:15.471] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:10:15.710] <TB3> INFO: dacScan step from 6 .. 6
[08:10:28.919] <TB3> INFO: Test took 13209ms.
[08:10:30.273] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:10:30.548] <TB3> INFO: dacScan step from 7 .. 7
[08:10:43.995] <TB3> INFO: Test took 13447ms.
[08:10:45.330] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:10:45.552] <TB3> INFO: dacScan step from 8 .. 8
[08:10:58.651] <TB3> INFO: Test took 13099ms.
[08:11:00.284] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:11:00.595] <TB3> INFO: dacScan step from 9 .. 9
[08:11:13.789] <TB3> INFO: Test took 13194ms.
[08:11:15.139] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:11:15.371] <TB3> INFO: dacScan step from 10 .. 10
[08:11:28.558] <TB3> INFO: Test took 13187ms.
[08:11:29.864] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:11:30.092] <TB3> INFO: dacScan step from 11 .. 11
[08:11:43.373] <TB3> INFO: Test took 13281ms.
[08:11:44.800] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:11:45.052] <TB3> INFO: dacScan step from 12 .. 12
[08:11:58.206] <TB3> INFO: Test took 13153ms.
[08:11:59.579] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:11:59.823] <TB3> INFO: dacScan step from 13 .. 13
[08:12:13.419] <TB3> INFO: Test took 13595ms.
[08:12:14.820] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:12:15.056] <TB3> INFO: dacScan step from 14 .. 14
[08:12:28.499] <TB3> INFO: Test took 13442ms.
[08:12:29.925] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:12:30.166] <TB3> INFO: dacScan step from 15 .. 15
[08:12:43.113] <TB3> INFO: Test took 12946ms.
[08:12:44.551] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:12:44.781] <TB3> INFO: dacScan step from 16 .. 16
[08:12:57.667] <TB3> INFO: Test took 12886ms.
[08:12:59.132] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:12:59.435] <TB3> INFO: dacScan step from 17 .. 17
[08:13:12.946] <TB3> INFO: Test took 13511ms.
[08:13:14.275] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:13:14.499] <TB3> INFO: dacScan step from 18 .. 18
[08:13:27.908] <TB3> INFO: Test took 13409ms.
[08:13:29.357] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:13:29.601] <TB3> INFO: dacScan step from 19 .. 19
[08:13:34.210] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (3) != Token Chain Length (4)

[08:13:34.210] <TB3> WARNING: Channel 1 ROC 1: Readback start marker after 9 readouts!

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

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

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

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

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

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

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

[08:13:42.829] <TB3> INFO: Test took 13227ms.
[08:13:44.270] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:13:44.504] <TB3> INFO: dacScan step from 20 .. 20
[08:13:57.605] <TB3> INFO: Test took 13101ms.
[08:13:59.150] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:13:59.379] <TB3> INFO: dacScan step from 21 .. 21
[08:14:12.175] <TB3> INFO: Test took 12796ms.
[08:14:13.574] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:14:13.799] <TB3> INFO: dacScan step from 22 .. 22
[08:14:26.901] <TB3> INFO: Test took 13102ms.
[08:14:28.224] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:14:28.448] <TB3> INFO: dacScan step from 23 .. 23
[08:14:41.740] <TB3> INFO: Test took 13292ms.
[08:14:43.086] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:14:43.325] <TB3> INFO: dacScan step from 24 .. 24
[08:14:56.363] <TB3> INFO: Test took 13038ms.
[08:14:57.916] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:14:58.155] <TB3> INFO: dacScan step from 25 .. 25
[08:15:02.582] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

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

[08:15:11.279] <TB3> INFO: Test took 13124ms.
[08:15:12.852] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:15:13.092] <TB3> INFO: dacScan step from 26 .. 26
[08:15:26.468] <TB3> INFO: Test took 13375ms.
[08:15:27.817] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:15:28.048] <TB3> INFO: dacScan step from 27 .. 27
[08:15:41.320] <TB3> INFO: Test took 13271ms.
[08:15:42.774] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:15:43.000] <TB3> INFO: dacScan step from 28 .. 28
[08:15:56.378] <TB3> INFO: Test took 13377ms.
[08:15:57.880] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:15:58.129] <TB3> INFO: dacScan step from 29 .. 29
[08:16:11.606] <TB3> INFO: Test took 13477ms.
[08:16:13.041] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:16:13.274] <TB3> INFO: dacScan step from 30 .. 30
[08:16:26.396] <TB3> INFO: Test took 13121ms.
[08:16:27.724] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:16:27.971] <TB3> INFO: dacScan step from 31 .. 31
[08:16:40.934] <TB3> INFO: Test took 12962ms.
[08:16:42.334] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:16:42.569] <TB3> INFO: dacScan step from 32 .. 32
[08:16:55.373] <TB3> INFO: Test took 12803ms.
[08:16:56.778] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:16:57.024] <TB3> INFO: dacScan step from 33 .. 33
[08:17:10.252] <TB3> INFO: Test took 13228ms.
[08:17:11.640] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:17:11.868] <TB3> INFO: dacScan step from 34 .. 34
[08:17:25.269] <TB3> INFO: Test took 13400ms.
[08:17:26.870] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:17:27.101] <TB3> INFO: dacScan step from 35 .. 35
[08:17:40.742] <TB3> INFO: Test took 13640ms.
[08:17:42.149] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:17:42.378] <TB3> INFO: dacScan step from 36 .. 36
[08:17:56.008] <TB3> INFO: Test took 13629ms.
[08:17:57.514] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:17:57.823] <TB3> INFO: dacScan step from 37 .. 37
[08:18:11.647] <TB3> INFO: Test took 13824ms.
[08:18:13.187] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:13.426] <TB3> INFO: dacScan step from 38 .. 38
[08:18:27.081] <TB3> INFO: Test took 13655ms.
[08:18:28.520] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:28.751] <TB3> INFO: dacScan step from 39 .. 39
[08:18:42.848] <TB3> INFO: Test took 14096ms.
[08:18:44.412] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:18:44.714] <TB3> INFO: dacScan step from 40 .. 40
[08:18:58.966] <TB3> INFO: Test took 14252ms.
[08:19:00.435] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:00.666] <TB3> INFO: dacScan step from 41 .. 41
[08:19:05.214] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 2 Number of ROCs (2) != Token Chain Length (4)

[08:19:05.214] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (83) != TBM ID (53)

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

[08:19:05.214] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (54) != TBM ID (83)

[08:19:05.219] <TB3> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[08:19:05.219] <TB3> WARNING: Channel 2 ROC 0: Readback start marker after 17 readouts!

[08:19:15.320] <TB3> CRITICAL: <hal.cc/MultiRocAllPixelsDacScan:L1040> Incomplete DAQ data readout! Missing -1 Events.

[08:19:29.932] <TB3> INFO: Test took 14587ms.
[08:19:31.447] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:31.688] <TB3> INFO: dacScan step from 42 .. 42
[08:19:46.859] <TB3> INFO: Test took 15170ms.
[08:19:48.450] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:19:48.688] <TB3> INFO: dacScan step from 43 .. 43
[08:20:03.677] <TB3> INFO: Test took 14988ms.
[08:20:05.169] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:20:05.417] <TB3> INFO: dacScan step from 44 .. 44
[08:20:20.947] <TB3> INFO: Test took 15530ms.
[08:20:22.562] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:20:22.836] <TB3> INFO: dacScan step from 45 .. 45
[08:20:38.585] <TB3> INFO: Test took 15748ms.
[08:20:40.147] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:20:40.417] <TB3> INFO: dacScan step from 46 .. 46
[08:20:56.278] <TB3> INFO: Test took 15860ms.
[08:20:57.794] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:20:58.068] <TB3> INFO: dacScan step from 47 .. 47
[08:21:14.405] <TB3> INFO: Test took 16336ms.
[08:21:15.934] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:21:16.173] <TB3> INFO: dacScan step from 48 .. 48
[08:21:32.918] <TB3> INFO: Test took 16744ms.
[08:21:34.397] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:21:34.627] <TB3> INFO: dacScan step from 49 .. 49
[08:21:51.469] <TB3> INFO: Test took 16841ms.
[08:21:53.101] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:21:53.373] <TB3> INFO: dacScan step from 50 .. 50
[08:22:10.164] <TB3> INFO: Test took 16790ms.
[08:22:11.937] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:12.180] <TB3> INFO: dacScan step from 51 .. 51
[08:22:28.841] <TB3> INFO: Test took 16660ms.
[08:22:30.317] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:30.551] <TB3> INFO: dacScan step from 52 .. 52
[08:22:47.074] <TB3> INFO: Test took 16523ms.
[08:22:48.648] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:22:48.886] <TB3> INFO: dacScan step from 53 .. 53
[08:23:05.437] <TB3> INFO: Test took 16550ms.
[08:23:07.040] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:23:07.271] <TB3> INFO: dacScan step from 54 .. 54
[08:23:23.574] <TB3> INFO: Test took 16302ms.
[08:23:25.435] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:23:25.724] <TB3> INFO: dacScan step from 55 .. 55
[08:23:42.301] <TB3> INFO: Test took 16576ms.
[08:23:43.914] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:23:44.160] <TB3> INFO: dacScan step from 56 .. 56
[08:24:00.123] <TB3> INFO: Test took 15962ms.
[08:24:01.648] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:24:01.882] <TB3> INFO: dacScan step from 57 .. 57
[08:24:18.691] <TB3> INFO: Test took 16808ms.
[08:24:20.222] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:24:20.453] <TB3> INFO: dacScan step from 58 .. 58
[08:24:37.229] <TB3> INFO: Test took 16775ms.
[08:24:38.813] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:24:39.062] <TB3> INFO: dacScan step from 59 .. 59
[08:24:55.683] <TB3> INFO: Test took 16621ms.
[08:24:57.383] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:24:57.630] <TB3> INFO: dacScan step from 60 .. 60
[08:25:13.892] <TB3> INFO: Test took 16261ms.
[08:25:15.402] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:15.636] <TB3> INFO: dacScan step from 61 .. 61
[08:25:32.461] <TB3> INFO: Test took 16824ms.
[08:25:33.941] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:34.173] <TB3> INFO: dacScan step from 62 .. 62
[08:25:49.951] <TB3> INFO: Test took 15778ms.
[08:25:51.464] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:25:51.766] <TB3> INFO: dacScan step from 63 .. 63
[08:26:08.310] <TB3> INFO: Test took 16543ms.
[08:26:09.845] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:10.095] <TB3> INFO: dacScan step from 64 .. 64
[08:26:26.445] <TB3> INFO: Test took 16349ms.
[08:26:28.439] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:28.688] <TB3> INFO: dacScan step from 65 .. 65
[08:26:45.654] <TB3> INFO: Test took 16965ms.
[08:26:47.245] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:26:47.495] <TB3> INFO: dacScan step from 66 .. 66
[08:27:03.770] <TB3> INFO: Test took 16274ms.
[08:27:05.490] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:27:05.814] <TB3> INFO: dacScan step from 67 .. 67
[08:27:22.321] <TB3> INFO: Test took 16506ms.
[08:27:23.835] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:27:24.072] <TB3> INFO: dacScan step from 68 .. 68
[08:27:40.195] <TB3> INFO: Test took 16122ms.
[08:27:41.668] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:27:41.901] <TB3> INFO: dacScan step from 69 .. 69
[08:27:58.517] <TB3> INFO: Test took 16615ms.
[08:28:00.344] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:28:00.584] <TB3> INFO: dacScan step from 70 .. 70
[08:28:16.741] <TB3> INFO: Test took 16156ms.
[08:28:18.224] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:28:18.457] <TB3> INFO: dacScan step from 71 .. 71
[08:28:35.299] <TB3> INFO: Test took 16841ms.
[08:28:36.850] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:28:37.096] <TB3> INFO: dacScan step from 72 .. 72
[08:28:41.604] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

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

[08:28:41.604] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 31 readouts!

[08:28:53.565] <TB3> INFO: Test took 16468ms.
[08:28:55.160] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:28:55.397] <TB3> INFO: dacScan step from 73 .. 73
[08:29:11.613] <TB3> INFO: Test took 16215ms.
[08:29:13.272] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:29:13.537] <TB3> INFO: dacScan step from 74 .. 74
[08:29:29.865] <TB3> INFO: Test took 16327ms.
[08:29:31.349] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:29:31.581] <TB3> INFO: dacScan step from 75 .. 75
[08:29:48.417] <TB3> INFO: Test took 16835ms.
[08:29:49.952] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:29:50.194] <TB3> INFO: dacScan step from 76 .. 76
[08:30:07.215] <TB3> INFO: Test took 17020ms.
[08:30:08.917] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:09.171] <TB3> INFO: dacScan step from 77 .. 77
[08:30:26.137] <TB3> INFO: Test took 16965ms.
[08:30:27.737] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:27.986] <TB3> INFO: dacScan step from 78 .. 78
[08:30:44.183] <TB3> INFO: Test took 16196ms.
[08:30:45.704] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:30:45.942] <TB3> INFO: dacScan step from 79 .. 79
[08:31:02.229] <TB3> INFO: Test took 16286ms.
[08:31:03.866] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:31:04.150] <TB3> INFO: dacScan step from 80 .. 80
[08:31:20.545] <TB3> INFO: Test took 16394ms.
[08:31:22.090] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:31:22.326] <TB3> INFO: dacScan step from 81 .. 81
[08:31:38.766] <TB3> INFO: Test took 16439ms.
[08:31:40.434] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:31:40.667] <TB3> INFO: dacScan step from 82 .. 82
[08:31:57.539] <TB3> INFO: Test took 16871ms.
[08:31:59.136] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:31:59.370] <TB3> INFO: dacScan step from 83 .. 83
[08:32:16.000] <TB3> INFO: Test took 16629ms.
[08:32:17.598] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:32:17.886] <TB3> INFO: dacScan step from 84 .. 84
[08:32:34.521] <TB3> INFO: Test took 16634ms.
[08:32:36.296] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:32:36.597] <TB3> INFO: dacScan step from 85 .. 85
[08:32:52.739] <TB3> INFO: Test took 16141ms.
[08:32:54.444] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:32:54.688] <TB3> INFO: dacScan step from 86 .. 86
[08:32:59.342] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 2 Event ID mismatch: local ID (38) != TBM ID (39)

[08:32:59.342] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (38) != TBM ID (39)

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

[08:32:59.342] <TB3> WARNING: Channel 2 ROC 3: Readback start marker after 15 readouts!

[08:32:59.342] <TB3> WARNING: Channel 2 ROC 0: Readback start marker after 15 readouts!

[08:32:59.342] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

[08:32:59.342] <TB3> WARNING: Channel 3 ROC 0: Readback start marker after 15 readouts!

[08:32:59.342] <TB3> WARNING: Channel 3 ROC 1: Readback start marker after 15 readouts!

[08:32:59.342] <TB3> WARNING: Channel 3 ROC 2: Readback start marker after 15 readouts!

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

[08:33:11.155] <TB3> INFO: Test took 16466ms.
[08:33:13.133] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:13.433] <TB3> INFO: dacScan step from 87 .. 87
[08:33:30.193] <TB3> INFO: Test took 16759ms.
[08:33:31.829] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:32.091] <TB3> INFO: dacScan step from 88 .. 88
[08:33:49.001] <TB3> INFO: Test took 16909ms.
[08:33:50.558] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:33:50.821] <TB3> INFO: dacScan step from 89 .. 89
[08:34:07.934] <TB3> INFO: Test took 17112ms.
[08:34:09.610] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:09.862] <TB3> INFO: dacScan step from 90 .. 90
[08:34:26.534] <TB3> INFO: Test took 16671ms.
[08:34:28.589] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:28.866] <TB3> INFO: dacScan step from 91 .. 91
[08:34:44.984] <TB3> INFO: Test took 16117ms.
[08:34:46.682] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:34:46.916] <TB3> INFO: dacScan step from 92 .. 92
[08:35:03.076] <TB3> INFO: Test took 16159ms.
[08:35:04.751] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:35:04.996] <TB3> INFO: dacScan step from 93 .. 93
[08:35:21.263] <TB3> INFO: Test took 16266ms.
[08:35:22.974] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:35:23.240] <TB3> INFO: dacScan step from 94 .. 94
[08:35:39.899] <TB3> INFO: Test took 16658ms.
[08:35:41.760] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:35:42.024] <TB3> INFO: dacScan step from 95 .. 95
[08:35:59.070] <TB3> INFO: Test took 17045ms.
[08:36:00.843] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:01.096] <TB3> INFO: dacScan step from 96 .. 96
[08:36:17.218] <TB3> INFO: Test took 16121ms.
[08:36:18.801] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:19.033] <TB3> INFO: dacScan step from 97 .. 97
[08:36:35.660] <TB3> INFO: Test took 16626ms.
[08:36:37.484] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:37.725] <TB3> INFO: dacScan step from 98 .. 98
[08:36:54.216] <TB3> INFO: Test took 16490ms.
[08:36:55.944] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:36:56.175] <TB3> INFO: dacScan step from 99 .. 99
[08:37:12.836] <TB3> INFO: Test took 16660ms.
[08:37:14.450] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:14.682] <TB3> INFO: dacScan step from 100 .. 100
[08:37:30.869] <TB3> INFO: Test took 16186ms.
[08:37:32.530] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:37:32.772] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:34.221] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:35.493] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:36.747] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:38.067] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:39.393] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:40.759] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:42.102] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:43.453] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:44.822] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:46.186] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:47.562] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:48.887] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:50.202] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:51.454] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:52.743] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[08:37:54.006] <TB3> INFO: PixTest:: pg_setup set to default.
[08:37:54.007] <TB3> INFO: vcal mean: 38.02 38.22 37.79 41.15 41.40 42.69 41.38 41.83 42.78 42.99 43.11 42.22 40.96 38.54 38.72 38.65
[08:37:54.007] <TB3> INFO: vcal RMS: 1.31 1.25 1.26 1.46 1.43 1.48 1.40 1.86 1.52 1.69 1.55 1.65 1.49 1.27 1.28 1.40
[08:37:54.007] <TB3> INFO: number of X-ray hits detected: 152282 159232 148530 297860 316316 362085 328501 338970 384649 376528 392038 336381 290082 168124 180841 182789
[08:37:54.007] <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
[08:37:54.007] <TB3> INFO: X-ray hit rate [MHz/cm2]: 44.6 46.7 43.5 87.3 92.7 106.1 96.3 99.4 112.7 110.4 114.9 98.6 85.0 49.3 53.0 53.6
[08:37:54.007] <TB3> INFO: PixTestHighRate::doXNoiseMaps() done
[08:37:54.104] <TB3> INFO: enter test to run
[08:37:54.104] <TB3> INFO: test: no parameter change
[08:37:54.274] <TB3> QUIET: Connection to board 121 closed.
[08:37:54.354] <TB3> INFO: pXar: this is the end, my friend
commander_CalDelScanAndSaveDacs.log
[08:59:09.823] <TB3> INFO: *** Welcome to pxar ***
[08:59:09.823] <TB3> INFO: *** Today: 2015/08/11
[08:59:09.823] <TB3> INFO: readRocDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[08:59:09.824] <TB3> INFO: readTbmDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0a.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0b.dat
[08:59:09.824] <TB3> INFO: readMaskFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//defaultMaskFile.dat
[08:59:09.824] <TB3> INFO: readTrimFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C15.dat
[08:59:09.902] <TB3> INFO: clk: 4
[08:59:09.902] <TB3> INFO: ctr: 4
[08:59:09.902] <TB3> INFO: sda: 19
[08:59:09.902] <TB3> INFO: tin: 9
[08:59:09.902] <TB3> INFO: level: 15
[08:59:09.902] <TB3> INFO: triggerdelay: 0
[08:59:09.903] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[08:59:09.903] <TB3> INFO: Log level: INFO
[08:59:10.912] <TB3> INFO: Found DTB DTB_WWV6Z5
[08:59:13.936] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[08:59:13.939] <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:
------------------------------------------------------
[08:59:13.942] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[08:59:15.460] <TB3> INFO: DUT info:
[08:59:15.460] <TB3> INFO: The DUT currently contains the following objects:
[08:59:15.460] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[08:59:15.460] <TB3> INFO: TBM Core alpha (0): 7 registers set
[08:59:15.460] <TB3> INFO: TBM Core beta (1): 7 registers set
[08:59:15.460] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:59:15.460] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:15.460] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:15.460] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:15.460] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:15.460] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:15.460] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:15.460] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:15.461] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:15.461] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:15.461] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:15.461] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:15.461] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:15.461] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:15.461] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:15.461] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:15.461] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:59:15.862] <TB3> INFO: enter 'restricted' command line mode
[08:59:15.862] <TB3> INFO: enter test to run
[08:59:15.862] <TB3> INFO: test: HighRate no parameter change
[08:59:15.862] <TB3> INFO: running: highrate
[08:59:15.863] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C15.dat
[08:59:16.027] <TB3> INFO: ----------------------------------------------------------------------
[08:59:16.027] <TB3> INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[08:59:16.027] <TB3> INFO: ----------------------------------------------------------------------
[08:59:17.112] <TB3> INFO: Test took 1079ms.
[08:59:20.816] <TB3> INFO: Test took 3699ms.
[08:59:20.954] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:59:24.828] <TB3> INFO: Test took 3848ms.
[08:59:24.966] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:59:27.011] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (2) != Token Chain Length (4)

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

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

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

[08:59:27.012] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (136) != TBM ID (20)

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

[08:59:27.012] <TB3> ERROR: <datapipe.cc/CheckEventID:L420> Channel 3 Event ID mismatch: local ID (21) != TBM ID (136)

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

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

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

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

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

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

[08:59:28.793] <TB3> CRITICAL: <hal.cc/MultiRocAllPixelsCalibrate:L797> Incomplete DAQ data readout! Missing -1 Events.

[08:59:32.829] <TB3> INFO: Test took 4028ms.
[08:59:33.013] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:59:36.985] <TB3> INFO: Test took 3944ms.
[08:59:37.131] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:59:41.045] <TB3> INFO: Test took 3886ms.
[08:59:41.193] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:59:44.981] <TB3> INFO: Test took 3761ms.
[08:59:45.143] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:59:49.227] <TB3> INFO: Test took 4051ms.
[08:59:49.368] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:59:53.230] <TB3> INFO: Test took 3837ms.
[08:59:53.385] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[08:59:57.181] <TB3> INFO: Test took 3768ms.
[08:59:57.332] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:00:01.350] <TB3> INFO: Test took 3984ms.
[09:00:01.505] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:00:05.504] <TB3> INFO: Test took 3965ms.
[09:00:05.648] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:00:09.376] <TB3> INFO: Test took 3700ms.
[09:00:09.523] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:00:11.550] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[09:00:11.550] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[09:00:13.587] <TB3> INFO: Test took 4035ms.
[09:00:13.737] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:00:17.661] <TB3> INFO: Test took 3895ms.
[09:00:17.812] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:00:21.668] <TB3> INFO: Test took 3829ms.
[09:00:22.105] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:00:26.252] <TB3> INFO: Test took 4056ms.
[09:00:26.783] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:00:31.162] <TB3> INFO: Test took 4239ms.
[09:00:31.651] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:00:35.582] <TB3> INFO: Test took 3835ms.
[09:00:35.716] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:00:39.492] <TB3> INFO: Test took 3751ms.
[09:00:39.625] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:00:43.192] <TB3> INFO: Test took 3542ms.
[09:00:43.323] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:00:43.620] <TB3> INFO: enter test to run
[09:00:43.620] <TB3> INFO: test: Pretest no parameter change
[09:00:43.621] <TB3> INFO: running: pretest
[09:00:43.621] <TB3> INFO: write dac parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat
[09:00:43.621] <TB3> INFO: write dac parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C1.dat
[09:00:43.621] <TB3> INFO: write dac parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C2.dat
[09:00:43.621] <TB3> INFO: write dac parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C3.dat
[09:00:43.621] <TB3> INFO: write dac parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C4.dat
[09:00:43.621] <TB3> INFO: write dac parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C5.dat
[09:00:43.621] <TB3> INFO: write dac parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C6.dat
[09:00:43.621] <TB3> INFO: write dac parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C7.dat
[09:00:43.622] <TB3> INFO: write dac parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C8.dat
[09:00:43.622] <TB3> INFO: write dac parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C9.dat
[09:00:43.622] <TB3> INFO: write dac parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C10.dat
[09:00:43.622] <TB3> INFO: write dac parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C11.dat
[09:00:43.622] <TB3> INFO: write dac parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C12.dat
[09:00:43.622] <TB3> INFO: write dac parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C13.dat
[09:00:43.622] <TB3> INFO: write dac parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C14.dat
[09:00:43.622] <TB3> INFO: write dac parameters into /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//010_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[09:00:43.622] <TB3> INFO: enter test to run
[09:00:43.622] <TB3> INFO: test: no parameter change
[09:00:43.802] <TB3> QUIET: Connection to board 121 closed.
[09:00:43.882] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[09:01:17.946] <TB3> INFO: *** Welcome to pxar ***
[09:01:17.946] <TB3> INFO: *** Today: 2015/08/11
[09:01:17.947] <TB3> INFO: readRocDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//011_HREfficiency_50//dacParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//011_HREfficiency_50//dacParameters35_C15.dat
[09:01:17.948] <TB3> INFO: readTbmDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//011_HREfficiency_50//tbmParameters_C0a.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//011_HREfficiency_50//tbmParameters_C0b.dat
[09:01:17.948] <TB3> INFO: readMaskFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//011_HREfficiency_50//defaultMaskFile.dat
[09:01:17.948] <TB3> INFO: readTrimFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//011_HREfficiency_50//trimParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//011_HREfficiency_50//trimParameters35_C15.dat
[09:01:18.027] <TB3> INFO: clk: 4
[09:01:18.027] <TB3> INFO: ctr: 4
[09:01:18.027] <TB3> INFO: sda: 19
[09:01:18.027] <TB3> INFO: tin: 9
[09:01:18.027] <TB3> INFO: level: 15
[09:01:18.027] <TB3> INFO: triggerdelay: 0
[09:01:18.027] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[09:01:18.027] <TB3> INFO: Log level: INFO
[09:01:19.036] <TB3> INFO: Found DTB DTB_WWV6Z5
[09:01:22.059] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[09:01:22.062] <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:
------------------------------------------------------
[09:01:22.065] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[09:01:23.621] <TB3> INFO: DUT info:
[09:01:23.621] <TB3> INFO: The DUT currently contains the following objects:
[09:01:23.621] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:01:23.621] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:01:23.621] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:01:23.621] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:01:23.621] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:23.621] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:23.621] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:23.622] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:23.622] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:23.622] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:23.622] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:23.622] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:23.622] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:23.622] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:23.622] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:23.622] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:23.622] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:23.622] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:23.622] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:23.622] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:01:24.023] <TB3> INFO: enter 'restricted' command line mode
[09:01:24.023] <TB3> INFO: enter test to run
[09:01:24.023] <TB3> INFO: test: HighRate no parameter change
[09:01:24.023] <TB3> INFO: running: highrate
[09:01:24.023] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//011_HREfficiency_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//011_HREfficiency_50//phCalibrationFitErr35_C15.dat
[09:01:24.160] <TB3> INFO: ----------------------------------------------------------------------
[09:01:24.160] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:01:24.160] <TB3> INFO: ----------------------------------------------------------------------
[09:01:28.041] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 0 Number of ROCs (0) != Token Chain Length (4)

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

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

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

[09:01:28.041] <TB3> WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!

[09:01:28.041] <TB3> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

[09:01:28.041] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[09:01:28.041] <TB3> WARNING: Channel 1 ROC 1: Readback start marker after 15 readouts!

[09:01:28.041] <TB3> WARNING: Channel 1 ROC 2: Readback start marker after 15 readouts!

[09:01:28.041] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[09:01:28.041] <TB3> WARNING: Channel 2 ROC 1: Readback start marker after 15 readouts!

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

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

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

[09:01:36.380] <TB3> INFO: Test took 12214ms.
[09:01:37.065] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:01:37.363] <TB3> INFO: number of dead pixels (per ROC): 1 0 1 0 1 0 0 3 0 1 0 0 0 0 0 1
[09:01:37.363] <TB3> INFO: number of red-efficiency pixels: 90 104 104 223 332 269 249 276 307 293 302 254 181 105 112 112
[09:01:37.363] <TB3> INFO: number of X-ray hits detected: 75453 78015 72464 148834 159458 184178 164741 170484 192669 189396 199196 168851 144489 81765 88949 90241
[09:01:37.363] <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
[09:01:37.363] <TB3> INFO: number of Vcal hits detected: 207858 207892 207844 207766 207604 207721 207741 207567 207685 207647 207681 207732 207816 207891 207885 207838
[09:01:37.363] <TB3> INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9 100.0
[09:01:37.363] <TB3> INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.9 99.9
[09:01:37.363] <TB3> INFO: X-ray hit rate [MHz/cm2]: 22.1 22.9 21.2 43.6 46.7 54.0 48.3 50.0 56.5 55.5 58.4 49.5 42.4 24.0 26.1 26.5
[09:01:37.363] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[09:01:37.390] <TB3> INFO: PixTest:: pg_setup set to default.
[09:01:37.427] <TB3> INFO: enter test to run
[09:01:37.427] <TB3> INFO: test: no parameter change
[09:01:37.617] <TB3> QUIET: Connection to board 121 closed.
[09:01:37.696] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[09:02:11.670] <TB3> INFO: *** Welcome to pxar ***
[09:02:11.670] <TB3> INFO: *** Today: 2015/08/11
[09:02:11.670] <TB3> INFO: readRocDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//012_HREfficiency_100//dacParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//012_HREfficiency_100//dacParameters35_C15.dat
[09:02:11.671] <TB3> INFO: readTbmDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//012_HREfficiency_100//tbmParameters_C0a.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//012_HREfficiency_100//tbmParameters_C0b.dat
[09:02:11.671] <TB3> INFO: readMaskFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//012_HREfficiency_100//defaultMaskFile.dat
[09:02:11.671] <TB3> INFO: readTrimFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//012_HREfficiency_100//trimParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//012_HREfficiency_100//trimParameters35_C15.dat
[09:02:11.741] <TB3> INFO: clk: 4
[09:02:11.741] <TB3> INFO: ctr: 4
[09:02:11.741] <TB3> INFO: sda: 19
[09:02:11.741] <TB3> INFO: tin: 9
[09:02:11.741] <TB3> INFO: level: 15
[09:02:11.741] <TB3> INFO: triggerdelay: 0
[09:02:11.741] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[09:02:11.741] <TB3> INFO: Log level: INFO
[09:02:12.752] <TB3> INFO: Found DTB DTB_WWV6Z5
[09:02:15.784] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[09:02:15.787] <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:
------------------------------------------------------
[09:02:15.790] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[09:02:17.925] <TB3> INFO: DUT info:
[09:02:17.925] <TB3> INFO: The DUT currently contains the following objects:
[09:02:17.925] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:02:17.925] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:02:17.925] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:02:17.925] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:02:17.925] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:02:17.925] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:02:17.925] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:02:17.925] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:02:17.925] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:02:17.925] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:02:17.925] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:02:17.925] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:02:17.925] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:02:17.925] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:02:17.925] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:02:17.925] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:02:17.925] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:02:17.925] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:02:17.925] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:02:17.925] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:02:18.326] <TB3> INFO: enter 'restricted' command line mode
[09:02:18.326] <TB3> INFO: enter test to run
[09:02:18.326] <TB3> INFO: test: HighRate no parameter change
[09:02:18.327] <TB3> INFO: running: highrate
[09:02:18.327] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//012_HREfficiency_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//012_HREfficiency_100//phCalibrationFitErr35_C15.dat
[09:02:19.162] <TB3> INFO: ----------------------------------------------------------------------
[09:02:19.163] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:02:19.163] <TB3> INFO: ----------------------------------------------------------------------
[09:02:26.451] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L437> Channel 3 Number of ROCs (1) != Token Chain Length (4)

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

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

[09:02:35.445] <TB3> INFO: Test took 16266ms.
[09:02:37.332] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:02:37.707] <TB3> INFO: number of dead pixels (per ROC): 1 0 1 0 1 0 0 3 0 1 0 0 0 0 0 1
[09:02:37.707] <TB3> INFO: number of red-efficiency pixels: 215 258 292 709 1111 967 840 916 1178 1061 1219 903 618 323 306 291
[09:02:37.707] <TB3> INFO: number of X-ray hits detected: 153038 159538 148171 298150 318845 364511 327963 337875 384793 375121 392082 338010 289888 168479 181811 183511
[09:02:37.707] <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
[09:02:37.707] <TB3> INFO: number of Vcal hits detected: 207725 207725 207621 207188 206529 206798 206973 206800 206518 206583 206391 206876 207301 207634 207680 207644
[09:02:37.707] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.7 99.4 99.5 99.6 99.5 99.4 99.4 99.3 99.5 99.7 99.9 99.9 99.9
[09:02:37.707] <TB3> INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.6 99.3 99.4 99.5 99.4 99.3 99.3 99.2 99.5 99.7 99.8 99.8 99.8
[09:02:37.707] <TB3> INFO: X-ray hit rate [MHz/cm2]: 44.9 46.8 43.4 87.4 93.5 106.8 96.1 99.0 112.8 110.0 114.9 99.1 85.0 49.4 53.3 53.8
[09:02:37.707] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[09:02:37.732] <TB3> INFO: PixTest:: pg_setup set to default.
[09:02:37.764] <TB3> INFO: enter test to run
[09:02:37.764] <TB3> INFO: test: no parameter change
[09:02:37.932] <TB3> QUIET: Connection to board 121 closed.
[09:02:38.012] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[09:03:13.161] <TB3> INFO: *** Welcome to pxar ***
[09:03:13.161] <TB3> INFO: *** Today: 2015/08/11
[09:03:13.161] <TB3> INFO: readRocDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//013_HREfficiency_150//dacParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//013_HREfficiency_150//dacParameters35_C15.dat
[09:03:13.161] <TB3> INFO: readTbmDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//013_HREfficiency_150//tbmParameters_C0a.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//013_HREfficiency_150//tbmParameters_C0b.dat
[09:03:13.161] <TB3> INFO: readMaskFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//013_HREfficiency_150//defaultMaskFile.dat
[09:03:13.162] <TB3> INFO: readTrimFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//013_HREfficiency_150//trimParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//013_HREfficiency_150//trimParameters35_C15.dat
[09:03:13.231] <TB3> INFO: clk: 4
[09:03:13.231] <TB3> INFO: ctr: 4
[09:03:13.231] <TB3> INFO: sda: 19
[09:03:13.231] <TB3> INFO: tin: 9
[09:03:13.231] <TB3> INFO: level: 15
[09:03:13.231] <TB3> INFO: triggerdelay: 0
[09:03:13.231] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[09:03:13.231] <TB3> INFO: Log level: INFO
[09:03:14.241] <TB3> INFO: Found DTB DTB_WWV6Z5
[09:03:17.263] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[09:03:17.267] <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:
------------------------------------------------------
[09:03:17.269] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[09:03:18.813] <TB3> INFO: DUT info:
[09:03:18.813] <TB3> INFO: The DUT currently contains the following objects:
[09:03:18.813] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:03:18.813] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:03:18.813] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:03:18.814] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:03:18.814] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:18.814] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:18.814] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:18.814] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:18.814] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:18.814] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:18.814] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:18.814] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:18.814] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:18.814] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:18.814] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:18.814] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:18.814] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:18.814] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:18.814] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:18.814] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:03:19.215] <TB3> INFO: enter 'restricted' command line mode
[09:03:19.215] <TB3> INFO: enter test to run
[09:03:19.215] <TB3> INFO: test: HighRate no parameter change
[09:03:19.215] <TB3> INFO: running: highrate
[09:03:19.215] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//013_HREfficiency_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//013_HREfficiency_150//phCalibrationFitErr35_C15.dat
[09:03:19.360] <TB3> INFO: ----------------------------------------------------------------------
[09:03:19.360] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:03:19.360] <TB3> INFO: ----------------------------------------------------------------------
[09:03:36.872] <TB3> INFO: Test took 17506ms.
[09:03:40.256] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:40.696] <TB3> INFO: number of dead pixels (per ROC): 1 0 1 0 1 0 0 3 0 1 0 0 0 0 0 1
[09:03:40.696] <TB3> INFO: number of red-efficiency pixels: 411 432 547 1439 2185 2007 1689 1827 2312 2184 2300 1829 1409 616 719 584
[09:03:40.696] <TB3> INFO: number of X-ray hits detected: 223401 233475 218273 431520 461606 521771 472315 488411 554811 541559 564516 487653 417730 246428 265090 265997
[09:03:40.696] <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
[09:03:40.696] <TB3> INFO: number of Vcal hits detected: 207496 207542 207254 206004 204095 204699 205294 205160 204038 204105 203755 204988 206092 207141 207189 207310
[09:03:40.696] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.7 99.2 98.4 98.6 98.9 98.8 98.3 98.4 98.3 98.8 99.2 99.7 99.6 99.7
[09:03:40.696] <TB3> INFO: Vcal hit overall efficiency (%): 99.8 99.8 99.6 99.0 98.1 98.4 98.7 98.6 98.1 98.1 98.0 98.6 99.1 99.6 99.6 99.7
[09:03:40.697] <TB3> INFO: X-ray hit rate [MHz/cm2]: 65.5 68.4 64.0 126.5 135.3 152.9 138.4 143.2 162.6 158.7 165.5 142.9 122.4 72.2 77.7 78.0
[09:03:40.697] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[09:03:40.722] <TB3> INFO: PixTest:: pg_setup set to default.
[09:03:40.764] <TB3> INFO: enter test to run
[09:03:40.764] <TB3> INFO: test: no parameter change
[09:03:40.931] <TB3> QUIET: Connection to board 121 closed.
[09:03:41.010] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[09:04:16.349] <TB3> INFO: *** Welcome to pxar ***
[09:04:16.349] <TB3> INFO: *** Today: 2015/08/11
[09:04:16.349] <TB3> INFO: readRocDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//014_HREfficiency_200//dacParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//014_HREfficiency_200//dacParameters35_C15.dat
[09:04:16.350] <TB3> INFO: readTbmDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//014_HREfficiency_200//tbmParameters_C0a.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//014_HREfficiency_200//tbmParameters_C0b.dat
[09:04:16.350] <TB3> INFO: readMaskFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//014_HREfficiency_200//defaultMaskFile.dat
[09:04:16.350] <TB3> INFO: readTrimFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//014_HREfficiency_200//trimParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//014_HREfficiency_200//trimParameters35_C15.dat
[09:04:16.428] <TB3> INFO: clk: 4
[09:04:16.428] <TB3> INFO: ctr: 4
[09:04:16.428] <TB3> INFO: sda: 19
[09:04:16.428] <TB3> INFO: tin: 9
[09:04:16.428] <TB3> INFO: level: 15
[09:04:16.428] <TB3> INFO: triggerdelay: 0
[09:04:16.428] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[09:04:16.428] <TB3> INFO: Log level: INFO
[09:04:17.438] <TB3> INFO: Found DTB DTB_WWV6Z5
[09:04:20.460] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[09:04:20.463] <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:
------------------------------------------------------
[09:04:20.465] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[09:04:22.014] <TB3> INFO: DUT info:
[09:04:22.014] <TB3> INFO: The DUT currently contains the following objects:
[09:04:22.014] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:04:22.014] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:04:22.015] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:04:22.015] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:04:22.015] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:04:22.015] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:04:22.015] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:04:22.015] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:04:22.015] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:04:22.015] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:04:22.015] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:04:22.015] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:04:22.015] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:04:22.015] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:04:22.015] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:04:22.015] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:04:22.015] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:04:22.015] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:04:22.015] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:04:22.015] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:04:22.416] <TB3> INFO: enter 'restricted' command line mode
[09:04:22.416] <TB3> INFO: enter test to run
[09:04:22.416] <TB3> INFO: test: HighRate no parameter change
[09:04:22.416] <TB3> INFO: running: highrate
[09:04:22.416] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//014_HREfficiency_200//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//014_HREfficiency_200//phCalibrationFitErr35_C15.dat
[09:04:22.558] <TB3> INFO: ----------------------------------------------------------------------
[09:04:22.558] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:04:22.558] <TB3> INFO: ----------------------------------------------------------------------
[09:04:42.607] <TB3> INFO: Test took 20043ms.
[09:04:48.620] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:04:49.141] <TB3> INFO: number of dead pixels (per ROC): 1 0 1 0 1 0 0 3 0 1 0 0 0 0 0 1
[09:04:49.141] <TB3> INFO: number of red-efficiency pixels: 787 797 841 2446 3182 3154 2792 3118 3539 3295 3477 2968 2425 989 1331 1021
[09:04:49.141] <TB3> INFO: number of X-ray hits detected: 304527 318440 297600 582089 622643 699374 635068 656315 747234 728494 757403 659156 564046 335150 361503 360835
[09:04:49.141] <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
[09:04:49.141] <TB3> INFO: number of Vcal hits detected: 207035 207080 206515 203233 199018 200030 201616 201112 198246 198454 197719 200587 203623 205940 206328 206671
[09:04:49.141] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.6 99.6 99.4 98.0 96.2 96.7 97.4 97.1 95.8 96.0 95.7 96.9 98.2 99.2 99.3 99.5
[09:04:49.141] <TB3> INFO: Vcal hit overall efficiency (%): 99.5 99.6 99.3 97.7 95.7 96.2 96.9 96.7 95.3 95.4 95.1 96.4 97.9 99.0 99.2 99.4
[09:04:49.141] <TB3> INFO: X-ray hit rate [MHz/cm2]: 89.3 93.3 87.2 170.6 182.5 205.0 186.1 192.4 219.0 213.5 222.0 193.2 165.3 98.2 106.0 105.8
[09:04:49.141] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[09:04:49.167] <TB3> INFO: PixTest:: pg_setup set to default.
[09:04:49.207] <TB3> INFO: enter test to run
[09:04:49.207] <TB3> INFO: test: no parameter change
[09:04:49.365] <TB3> QUIET: Connection to board 121 closed.
[09:04:49.444] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[09:05:22.519] <TB3> INFO: *** Welcome to pxar ***
[09:05:22.519] <TB3> INFO: *** Today: 2015/08/11
[09:05:22.519] <TB3> INFO: readRocDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//015_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//015_HREfficiency_250//dacParameters35_C15.dat
[09:05:22.519] <TB3> INFO: readTbmDacs: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//015_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//015_HREfficiency_250//tbmParameters_C0b.dat
[09:05:22.520] <TB3> INFO: readMaskFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//015_HREfficiency_250//defaultMaskFile.dat
[09:05:22.520] <TB3> INFO: readTrimFile: /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//015_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//015_HREfficiency_250//trimParameters35_C15.dat
[09:05:22.596] <TB3> INFO: clk: 4
[09:05:22.596] <TB3> INFO: ctr: 4
[09:05:22.596] <TB3> INFO: sda: 19
[09:05:22.596] <TB3> INFO: tin: 9
[09:05:22.596] <TB3> INFO: level: 15
[09:05:22.596] <TB3> INFO: triggerdelay: 0
[09:05:22.596] <TB3> QUIET: Instanciating API for pxar v2.2.5+88~g694c14c
[09:05:22.596] <TB3> INFO: Log level: INFO
[09:05:23.606] <TB3> INFO: Found DTB DTB_WWV6Z5
[09:05:26.626] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[09:05:26.630] <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:
------------------------------------------------------
[09:05:26.632] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[09:05:28.173] <TB3> INFO: DUT info:
[09:05:28.173] <TB3> INFO: The DUT currently contains the following objects:
[09:05:28.173] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[09:05:28.173] <TB3> INFO: TBM Core alpha (0): 7 registers set
[09:05:28.173] <TB3> INFO: TBM Core beta (1): 7 registers set
[09:05:28.173] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:05:28.173] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:05:28.173] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:05:28.173] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:05:28.174] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:05:28.174] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:05:28.174] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:05:28.174] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:05:28.174] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:05:28.174] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:05:28.174] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:05:28.174] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:05:28.174] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:05:28.174] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:05:28.174] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:05:28.174] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:05:28.174] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:05:28.575] <TB3> INFO: enter 'restricted' command line mode
[09:05:28.575] <TB3> INFO: enter test to run
[09:05:28.575] <TB3> INFO: test: HighRate no parameter change
[09:05:28.575] <TB3> INFO: running: highrate
[09:05:28.575] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//015_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2059_XrayQualification_2015-08-11_09h36m_1439278608//015_HREfficiency_250//phCalibrationFitErr35_C15.dat
[09:05:28.726] <TB3> INFO: ----------------------------------------------------------------------
[09:05:28.726] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:05:28.726] <TB3> INFO: ----------------------------------------------------------------------
[09:05:49.153] <TB3> INFO: Test took 20421ms.
[09:05:55.175] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[09:05:55.714] <TB3> INFO: number of dead pixels (per ROC): 1 0 1 0 1 0 0 3 0 1 0 0 0 0 0 1
[09:05:55.714] <TB3> INFO: number of red-efficiency pixels: 823 851 917 2619 3353 3255 2896 3183 3637 3483 3575 3142 2394 1094 1442 1121
[09:05:55.725] <TB3> INFO: number of X-ray hits detected: 315163 332101 309089 600178 641025 717362 652556 675283 770678 753603 776775 678270 580410 349814 374640 372886
[09:05:55.725] <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
[09:05:55.725] <TB3> INFO: number of Vcal hits detected: 206997 207016 206411 202783 198297 199558 201001 200770 197237 197377 196927 200044 203515 205864 206140 206541
[09:05:55.725] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.6 99.6 99.4 97.8 95.9 96.4 97.1 96.9 95.4 95.5 95.3 96.7 98.1 99.2 99.2 99.4
[09:05:55.725] <TB3> INFO: Vcal hit overall efficiency (%): 99.5 99.5 99.2 97.5 95.3 95.9 96.6 96.5 94.8 94.9 94.7 96.2 97.8 99.0 99.1 99.3
[09:05:55.725] <TB3> INFO: X-ray hit rate [MHz/cm2]: 92.4 97.3 90.6 175.9 187.9 210.3 191.3 197.9 225.9 220.9 227.7 198.8 170.1 102.5 109.8 109.3
[09:05:55.725] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[09:05:55.751] <TB3> INFO: PixTest:: pg_setup set to default.
[09:05:55.788] <TB3> INFO: enter test to run
[09:05:55.788] <TB3> INFO: test: no parameter change
[09:05:55.933] <TB3> QUIET: Connection to board 121 closed.
[09:05:56.012] <TB3> INFO: pXar: this is the end, my friend
MoReWeb-v1.0.0-5-g82d9ff6 on branch psi46master