Test Date: 2015-08-24 17:49
Analysis date: 2016-05-26 02:42
Logfile
commander_PixelAlive.log
[15:51:05.569] <TB3> INFO: *** Welcome to pxar ***
[15:51:05.569] <TB3> INFO: *** Today: 2015/08/24
[15:51:05.569] <TB3> INFO: readRocDacs: /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//dacParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//dacParameters35_C15.dat
[15:51:05.569] <TB3> INFO: readTbmDacs: /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//tbmParameters_C0a.dat .. /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//tbmParameters_C0b.dat
[15:51:05.570] <TB3> INFO: readMaskFile: /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//defaultMaskFile.dat
[15:51:05.570] <TB3> INFO: readTrimFile: /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//trimParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//000_PixelAlive_p17//trimParameters35_C15.dat
[15:51:05.638] <TB3> INFO: clk: 4
[15:51:05.638] <TB3> INFO: ctr: 4
[15:51:05.638] <TB3> INFO: sda: 19
[15:51:05.638] <TB3> INFO: tin: 9
[15:51:05.638] <TB3> INFO: level: 15
[15:51:05.638] <TB3> INFO: triggerdelay: 0
[15:51:05.638] <TB3> QUIET: Instanciating API for pxar prod-01+86~g1838649
[15:51:05.638] <TB3> INFO: Log level: INFO
[15:51:05.653] <TB3> INFO: Found DTB DTB_WWV6Z5
[15:51:05.664] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[15:51:05.667] <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:
------------------------------------------------------
[15:51:05.670] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[15:51:07.193] <TB3> INFO: DUT info:
[15:51:07.193] <TB3> INFO: The DUT currently contains the following objects:
[15:51:07.193] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[15:51:07.193] <TB3> INFO: TBM Core alpha (0): 7 registers set
[15:51:07.193] <TB3> INFO: TBM Core beta (1): 7 registers set
[15:51:07.193] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:51:07.194] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:07.194] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:07.194] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:07.194] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:07.194] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:07.194] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:07.194] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:07.194] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:07.194] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:07.194] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:07.194] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:07.194] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:07.194] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:07.194] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:07.194] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:07.194] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:51:07.596] <TB3> INFO: enter 'restricted' command line mode
[15:51:07.596] <TB3> INFO: enter test to run
[15:51:07.596] <TB3> INFO: test: PixelAlive no parameter change
[15:51:07.596] <TB3> INFO: running: pixelalive
[15:51:07.601] <TB3> INFO: ######################################################################
[15:51:07.601] <TB3> INFO: PixTestAlive::doTest()
[15:51:07.601] <TB3> INFO: ######################################################################
[15:51:07.603] <TB3> INFO: ----------------------------------------------------------------------
[15:51:07.603] <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)
[15:51:07.603] <TB3> INFO: ----------------------------------------------------------------------
[15:51:11.235] <TB3> INFO: Test took 3631ms.
[15:51:11.253] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:11.498] <TB3> INFO: PixTestAlive::aliveTest() done
[15:51:11.498] <TB3> INFO: number of dead pixels (per ROC): 5 2 0 0 0 0 0 0 0 1 0 0 2 0 0 2
[15:51:11.500] <TB3> INFO: ----------------------------------------------------------------------
[15:51:11.500] <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)
[15:51:11.500] <TB3> INFO: ----------------------------------------------------------------------
[15:51:14.287] <TB3> INFO: Test took 2786ms.
[15:51:14.289] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:14.289] <TB3> INFO: mask vs. old pixelAlive PixelAlive_C0_V0 .. PixelAlive_C15_V0
[15:51:14.508] <TB3> INFO: PixTestAlive::maskTest() done
[15:51:14.508] <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
[15:51:14.509] <TB3> INFO: ----------------------------------------------------------------------
[15:51:14.509] <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)
[15:51:14.509] <TB3> INFO: ----------------------------------------------------------------------
[15:51:18.020] <TB3> INFO: Test took 3509ms.
[15:51:18.038] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:18.259] <TB3> INFO: PixTestAlive::addressDecodingTest() done
[15:51:18.259] <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
[15:51:18.259] <TB3> INFO: PixTestAlive::doTest() done, duration: 10 seconds
[15:51:18.288] <TB3> INFO: enter test to run
[15:51:18.288] <TB3> INFO: test: no parameter change
[15:51:18.459] <TB3> QUIET: Connection to board 121 closed.
[15:51:18.538] <TB3> INFO: pXar: this is the end, my friend
commander_RetrimHotPixels.log
[15:56:35.527] <TB3> INFO: *** Welcome to pxar ***
[15:56:35.527] <TB3> INFO: *** Today: 2015/08/24
[15:56:35.527] <TB3> INFO: readRocDacs: /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//dacParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//dacParameters35_C15.dat
[15:56:35.528] <TB3> INFO: readTbmDacs: /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//tbmParameters_C0a.dat .. /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//tbmParameters_C0b.dat
[15:56:35.529] <TB3> INFO: readMaskFile: /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//defaultMaskFile.dat
[15:56:35.529] <TB3> INFO: readTrimFile: /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C15.dat
[15:56:35.608] <TB3> INFO: clk: 4
[15:56:35.608] <TB3> INFO: ctr: 4
[15:56:35.608] <TB3> INFO: sda: 19
[15:56:35.608] <TB3> INFO: tin: 9
[15:56:35.608] <TB3> INFO: level: 15
[15:56:35.608] <TB3> INFO: triggerdelay: 0
[15:56:35.608] <TB3> QUIET: Instanciating API for pxar prod-01+86~g1838649
[15:56:35.608] <TB3> INFO: Log level: INFO
[15:56:35.621] <TB3> INFO: Found DTB DTB_WWV6Z5
[15:56:35.633] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[15:56:35.636] <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:
------------------------------------------------------
[15:56:35.639] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[15:56:37.182] <TB3> INFO: DUT info:
[15:56:37.182] <TB3> INFO: The DUT currently contains the following objects:
[15:56:37.182] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[15:56:37.182] <TB3> INFO: TBM Core alpha (0): 7 registers set
[15:56:37.182] <TB3> INFO: TBM Core beta (1): 7 registers set
[15:56:37.182] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:56:37.182] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:37.182] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:37.182] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:37.182] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:37.182] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:37.182] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:37.182] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:37.182] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:37.182] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:37.183] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:37.183] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:37.183] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:37.183] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:37.183] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:37.183] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:37.183] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:56:37.584] <TB3> INFO: enter 'restricted' command line mode
[15:56:37.584] <TB3> INFO: enter test to run
[15:56:37.584] <TB3> INFO: test: delay setting parameters: ->5<-
[15:56:37.584] <TB3> INFO: delay test by 5 seconds...
[15:56:42.584] <TB3> INFO: enter test to run
[15:56:42.584] <TB3> INFO: test: highrate no parameter change
[15:56:42.584] <TB3> INFO: running: highrate
[15:56:42.585] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//phCalibrationFitErr35_C15.dat
[15:56:42.727] <TB3> INFO: ----------------------------------------------------------------------
[15:56:42.727] <TB3> INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[15:56:42.727] <TB3> INFO: ----------------------------------------------------------------------
[15:56:42.727] <TB3> INFO: THR = 150, corresponding to ~ 400 MHz/cm2
[15:56:42.727] <TB3> INFO: edge/corner pixel THR is adjusted
[15:56:42.727] <TB3> INFO: PixTestHighRate::trimHotPixels: step 0...
[15:56:43.689] <TB3> INFO: Collecting data for 1 seconds...
[15:56:44.691] <TB3> INFO: Done with hot pixel readout
[15:56:50.662] <TB3> INFO: PixTest:: pg_setup set to default.
[15:56:50.663] <TB3> INFO: 16 hot pixels found in step 0
[15:56:51.627] <TB3> INFO: Collecting data for 1 seconds...
[15:56:52.629] <TB3> INFO: Done with hot pixel readout
[15:56:58.536] <TB3> INFO: PixTest:: pg_setup set to default.
[15:56:58.537] <TB3> INFO: 10 hot pixels found in step 1
[15:56:59.500] <TB3> INFO: Collecting data for 1 seconds...
[15:57:00.516] <TB3> INFO: Done with hot pixel readout
[15:57:06.658] <TB3> INFO: PixTest:: pg_setup set to default.
[15:57:06.659] <TB3> INFO: 6 hot pixels found in step 2
[15:57:07.624] <TB3> INFO: Collecting data for 1 seconds...
[15:57:08.626] <TB3> INFO: Done with hot pixel readout
[15:57:14.511] <TB3> INFO: PixTest:: pg_setup set to default.
[15:57:14.512] <TB3> INFO: 1 hot pixels found in step 3
[15:57:15.477] <TB3> INFO: Collecting data for 1 seconds...
[15:57:16.479] <TB3> INFO: Done with hot pixel readout
[15:57:22.127] <TB3> INFO: PixTest:: pg_setup set to default.
[15:57:22.128] <TB3> INFO: 0 hot pixels found in step 4
[15:57:22.135] <TB3> INFO: 0 hot pixels could not be trimmed and have been masked.
[15:57:22.164] <TB3> INFO: PixTest::trimHotPixels() done
[15:57:22.164] <TB3> INFO: write trim parameters into /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C0.dat
[15:57:22.172] <TB3> INFO: write trim parameters into /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C1.dat
[15:57:22.180] <TB3> INFO: write trim parameters into /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C2.dat
[15:57:22.187] <TB3> INFO: write trim parameters into /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C3.dat
[15:57:22.193] <TB3> INFO: write trim parameters into /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C4.dat
[15:57:22.199] <TB3> INFO: write trim parameters into /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C5.dat
[15:57:22.205] <TB3> INFO: write trim parameters into /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C6.dat
[15:57:22.211] <TB3> INFO: write trim parameters into /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C7.dat
[15:57:22.218] <TB3> INFO: write trim parameters into /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C8.dat
[15:57:22.224] <TB3> INFO: write trim parameters into /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C9.dat
[15:57:22.230] <TB3> INFO: write trim parameters into /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C10.dat
[15:57:22.236] <TB3> INFO: write trim parameters into /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C11.dat
[15:57:22.242] <TB3> INFO: write trim parameters into /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C12.dat
[15:57:22.248] <TB3> INFO: write trim parameters into /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C13.dat
[15:57:22.255] <TB3> INFO: write trim parameters into /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C14.dat
[15:57:22.261] <TB3> INFO: write trim parameters into /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//trimParameters35_C15.dat
[15:57:22.267] <TB3> INFO: write masked pixels into /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//002_RetrimHotPixels_150//defaultMaskFile.dat
[15:57:22.300] <TB3> INFO: enter test to run
[15:57:22.300] <TB3> INFO: test: no parameter change
[15:57:22.451] <TB3> QUIET: Connection to board 121 closed.
[15:57:22.531] <TB3> INFO: pXar: this is the end, my friend
commander_HRData.log
[15:58:30.341] <TB3> INFO: *** Welcome to pxar ***
[15:58:30.341] <TB3> INFO: *** Today: 2015/08/24
[15:58:30.341] <TB3> INFO: readRocDacs: /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//dacParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//dacParameters35_C15.dat
[15:58:30.342] <TB3> INFO: readTbmDacs: /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//tbmParameters_C0a.dat .. /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//tbmParameters_C0b.dat
[15:58:30.342] <TB3> INFO: readMaskFile: /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//defaultMaskFile.dat
[15:58:30.342] <TB3> INFO: readTrimFile: /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//trimParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//trimParameters35_C15.dat
[15:58:30.418] <TB3> INFO: clk: 4
[15:58:30.418] <TB3> INFO: ctr: 4
[15:58:30.418] <TB3> INFO: sda: 19
[15:58:30.418] <TB3> INFO: tin: 9
[15:58:30.418] <TB3> INFO: level: 15
[15:58:30.418] <TB3> INFO: triggerdelay: 0
[15:58:30.418] <TB3> QUIET: Instanciating API for pxar prod-01+86~g1838649
[15:58:30.418] <TB3> INFO: Log level: INFO
[15:58:30.432] <TB3> INFO: Found DTB DTB_WWV6Z5
[15:58:30.443] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[15:58:30.446] <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:
------------------------------------------------------
[15:58:30.448] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[15:58:31.980] <TB3> INFO: DUT info:
[15:58:31.980] <TB3> INFO: The DUT currently contains the following objects:
[15:58:31.980] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[15:58:31.980] <TB3> INFO: TBM Core alpha (0): 7 registers set
[15:58:31.980] <TB3> INFO: TBM Core beta (1): 7 registers set
[15:58:31.980] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:58:31.980] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:31.980] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:31.980] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:31.980] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:31.980] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:31.980] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:31.980] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:31.981] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:31.981] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:31.981] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:31.981] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:31.981] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:31.981] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:31.981] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:31.981] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:31.981] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:58:32.382] <TB3> INFO: enter 'restricted' command line mode
[15:58:32.382] <TB3> INFO: enter test to run
[15:58:32.383] <TB3> INFO: test: delay setting parameters: ->5<-
[15:58:32.383] <TB3> INFO: delay test by 5 seconds...
[15:58:37.383] <TB3> INFO: enter test to run
[15:58:37.383] <TB3> INFO: test: Xray no parameter change
[15:58:37.383] <TB3> INFO: running: xray
[15:58:37.383] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//003_HRData_50//phCalibrationFitErr35_C15.dat
[15:58:37.536] <TB3> INFO: ######################################################################
[15:58:37.536] <TB3> INFO: PixTestXray::doTest()
[15:58:37.536] <TB3> INFO: ######################################################################
[15:58:37.536] <TB3> INFO: ----------------------------------------------------------------------
[15:58:37.536] <TB3> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:58:37.536] <TB3> INFO: ----------------------------------------------------------------------
[15:58:38.504] <TB3> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:58:45.324] <TB3> INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:59:10.525] <TB3> INFO: Resuming triggers.
[15:59:17.362] <TB3> INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[15:59:20.677] <TB3> ERROR: <datapipe.cc/CheckEventID:L453> Channel 3 Event ID mismatch: local ID (123) != TBM ID (15)

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

[15:59:20.677] <TB3> ERROR: <datapipe.cc/CheckEventID:L453> Channel 3 Event ID mismatch: local ID (16) != TBM ID (123)

[15:59:40.010] <TB3> INFO: Resuming triggers.
[15:59:46.841] <TB3> INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[16:00:07.384] <TB3> INFO: Resuming triggers.
[16:00:14.215] <TB3> INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[16:00:34.927] <TB3> INFO: Resuming triggers.
[16:00:41.758] <TB3> INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[16:01:06.174] <TB3> INFO: Resuming triggers.
[16:01:13.004] <TB3> INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[16:01:33.799] <TB3> INFO: Resuming triggers.
[16:01:40.629] <TB3> INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[16:02:03.973] <TB3> INFO: Resuming triggers.
[16:02:10.807] <TB3> INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[16:02:32.301] <TB3> INFO: Resuming triggers.
[16:02:39.133] <TB3> INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[16:03:01.556] <TB3> INFO: Resuming triggers.
[16:03:08.388] <TB3> INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[16:03:08.603] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 0 Number of ROCs (1) != Token Chain Length (4)

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

[16:03:08.603] <TB3> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

[16:03:08.603] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[16:03:32.210] <TB3> INFO: Resuming triggers.
[16:03:39.047] <TB3> INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[16:04:00.853] <TB3> INFO: Resuming triggers.
[16:04:07.688] <TB3> INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[16:04:30.695] <TB3> INFO: Resuming triggers.
[16:04:37.534] <TB3> INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[16:04:59.719] <TB3> INFO: Resuming triggers.
[16:05:06.555] <TB3> INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[16:05:26.191] <TB3> INFO: Resuming triggers.
[16:05:30.642] <TB3> INFO: data taking finished, elapsed time: 100 seconds.
[16:05:43.615] <TB3> INFO: PixTest:: pg_setup set to default.
[16:05:43.617] <TB3> INFO: PixTestXray::doPhRun() done
[16:05:43.617] <TB3> INFO: PixTestXray::doTest() done
[16:05:43.818] <TB3> INFO: enter test to run
[16:05:43.818] <TB3> INFO: test: no parameter change
[16:05:44.102] <TB3> QUIET: Connection to board 121 closed.
[16:05:44.181] <TB3> INFO: pXar: this is the end, my friend
commander_HRData.log
[16:07:24.363] <TB3> INFO: *** Welcome to pxar ***
[16:07:24.363] <TB3> INFO: *** Today: 2015/08/24
[16:07:24.363] <TB3> INFO: readRocDacs: /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//dacParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//dacParameters35_C15.dat
[16:07:24.365] <TB3> INFO: readTbmDacs: /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//tbmParameters_C0a.dat .. /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//tbmParameters_C0b.dat
[16:07:24.365] <TB3> INFO: readMaskFile: /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//defaultMaskFile.dat
[16:07:24.365] <TB3> INFO: readTrimFile: /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//trimParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//trimParameters35_C15.dat
[16:07:24.441] <TB3> INFO: clk: 4
[16:07:24.441] <TB3> INFO: ctr: 4
[16:07:24.441] <TB3> INFO: sda: 19
[16:07:24.441] <TB3> INFO: tin: 9
[16:07:24.441] <TB3> INFO: level: 15
[16:07:24.441] <TB3> INFO: triggerdelay: 0
[16:07:24.441] <TB3> QUIET: Instanciating API for pxar prod-01+86~g1838649
[16:07:24.441] <TB3> INFO: Log level: INFO
[16:07:25.455] <TB3> INFO: Found DTB DTB_WWV6Z5
[16:07:26.466] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[16:07:26.469] <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:
------------------------------------------------------
[16:07:26.472] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[16:07:28.006] <TB3> INFO: DUT info:
[16:07:28.006] <TB3> INFO: The DUT currently contains the following objects:
[16:07:28.006] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:07:28.006] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:07:28.006] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:07:28.006] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:07:28.006] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:28.006] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:28.006] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:28.006] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:28.006] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:28.007] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:28.007] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:28.007] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:28.007] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:28.007] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:28.007] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:28.007] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:28.007] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:28.007] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:28.007] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:28.007] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:07:28.408] <TB3> INFO: enter 'restricted' command line mode
[16:07:28.408] <TB3> INFO: enter test to run
[16:07:28.408] <TB3> INFO: test: delay setting parameters: ->5<-
[16:07:28.408] <TB3> INFO: delay test by 5 seconds...
[16:07:33.408] <TB3> INFO: enter test to run
[16:07:33.408] <TB3> INFO: test: Xray no parameter change
[16:07:33.408] <TB3> INFO: running: xray
[16:07:33.408] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//004_HRData_150//phCalibrationFitErr35_C15.dat
[16:07:33.561] <TB3> INFO: ######################################################################
[16:07:33.561] <TB3> INFO: PixTestXray::doTest()
[16:07:33.561] <TB3> INFO: ######################################################################
[16:07:33.561] <TB3> INFO: ----------------------------------------------------------------------
[16:07:33.561] <TB3> INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:07:33.561] <TB3> INFO: ----------------------------------------------------------------------
[16:07:34.530] <TB3> INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:07:38.351] <TB3> INFO: run duration 3 seconds, buffer almost full (81%), pausing triggers.
[16:08:05.163] <TB3> INFO: Resuming triggers.
[16:08:08.999] <TB3> INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[16:08:11.746] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 3 Number of ROCs (2) != Token Chain Length (4)

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

[16:08:30.808] <TB3> INFO: Resuming triggers.
[16:08:34.648] <TB3> INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[16:08:56.761] <TB3> INFO: Resuming triggers.
[16:09:00.597] <TB3> INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[16:09:23.823] <TB3> INFO: Resuming triggers.
[16:09:27.661] <TB3> INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[16:09:53.571] <TB3> INFO: Resuming triggers.
[16:09:57.404] <TB3> INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[16:10:19.780] <TB3> INFO: Resuming triggers.
[16:10:23.611] <TB3> INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[16:10:46.083] <TB3> INFO: Resuming triggers.
[16:10:49.910] <TB3> INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[16:11:15.069] <TB3> INFO: Resuming triggers.
[16:11:18.900] <TB3> INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[16:11:41.596] <TB3> INFO: Resuming triggers.
[16:11:45.422] <TB3> INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[16:12:10.898] <TB3> INFO: Resuming triggers.
[16:12:14.729] <TB3> INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[16:12:41.337] <TB3> INFO: Resuming triggers.
[16:12:45.161] <TB3> INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[16:13:10.806] <TB3> INFO: Resuming triggers.
[16:13:14.634] <TB3> INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[16:13:36.913] <TB3> INFO: Resuming triggers.
[16:13:40.734] <TB3> INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[16:14:04.318] <TB3> INFO: Resuming triggers.
[16:14:08.143] <TB3> INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[16:14:31.241] <TB3> INFO: Resuming triggers.
[16:14:35.065] <TB3> INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[16:14:56.849] <TB3> INFO: Resuming triggers.
[16:15:00.671] <TB3> INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[16:15:22.212] <TB3> INFO: Resuming triggers.
[16:15:26.033] <TB3> INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[16:15:47.538] <TB3> INFO: Resuming triggers.
[16:15:51.361] <TB3> INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[16:16:15.049] <TB3> INFO: Resuming triggers.
[16:16:18.869] <TB3> INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[16:16:45.402] <TB3> INFO: Resuming triggers.
[16:16:49.223] <TB3> INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[16:17:10.732] <TB3> INFO: Resuming triggers.
[16:17:14.554] <TB3> INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[16:17:38.177] <TB3> INFO: Resuming triggers.
[16:17:41.993] <TB3> INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[16:18:04.268] <TB3> INFO: Resuming triggers.
[16:18:08.087] <TB3> INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[16:18:33.699] <TB3> INFO: Resuming triggers.
[16:18:37.519] <TB3> INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[16:19:02.495] <TB3> INFO: Resuming triggers.
[16:19:06.314] <TB3> INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[16:19:28.751] <TB3> INFO: Resuming triggers.
[16:19:29.393] <TB3> INFO: data taking finished, elapsed time: 100 seconds.
[16:19:33.767] <TB3> INFO: PixTest:: pg_setup set to default.
[16:19:33.768] <TB3> INFO: PixTestXray::doPhRun() done
[16:19:33.768] <TB3> INFO: PixTestXray::doTest() done
[16:19:33.980] <TB3> INFO: enter test to run
[16:19:33.980] <TB3> INFO: test: no parameter change
[16:19:34.192] <TB3> QUIET: Connection to board 121 closed.
[16:19:34.271] <TB3> INFO: pXar: this is the end, my friend
commander_HRSCurves.log
[16:20:59.834] <TB3> INFO: *** Welcome to pxar ***
[16:20:59.834] <TB3> INFO: *** Today: 2015/08/24
[16:20:59.834] <TB3> INFO: readRocDacs: /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//dacParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//dacParameters35_C15.dat
[16:20:59.835] <TB3> INFO: readTbmDacs: /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//tbmParameters_C0a.dat .. /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//tbmParameters_C0b.dat
[16:20:59.835] <TB3> INFO: readMaskFile: /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//defaultMaskFile.dat
[16:20:59.835] <TB3> INFO: readTrimFile: /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//trimParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//trimParameters35_C15.dat
[16:20:59.906] <TB3> INFO: clk: 4
[16:20:59.906] <TB3> INFO: ctr: 4
[16:20:59.906] <TB3> INFO: sda: 19
[16:20:59.906] <TB3> INFO: tin: 9
[16:20:59.906] <TB3> INFO: level: 15
[16:20:59.906] <TB3> INFO: triggerdelay: 0
[16:20:59.906] <TB3> QUIET: Instanciating API for pxar prod-01+86~g1838649
[16:20:59.906] <TB3> INFO: Log level: INFO
[16:21:00.918] <TB3> INFO: Found DTB DTB_WWV6Z5
[16:21:01.929] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[16:21:01.932] <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:
------------------------------------------------------
[16:21:01.935] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[16:21:03.461] <TB3> INFO: DUT info:
[16:21:03.461] <TB3> INFO: The DUT currently contains the following objects:
[16:21:03.461] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[16:21:03.461] <TB3> INFO: TBM Core alpha (0): 7 registers set
[16:21:03.461] <TB3> INFO: TBM Core beta (1): 7 registers set
[16:21:03.461] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:21:03.461] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:03.461] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:03.461] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:03.461] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:03.461] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:03.462] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:03.462] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:03.462] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:03.462] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:03.462] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:03.462] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:03.462] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:03.462] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:03.462] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:03.462] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:03.462] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:21:03.863] <TB3> INFO: enter 'restricted' command line mode
[16:21:03.863] <TB3> INFO: enter test to run
[16:21:03.863] <TB3> INFO: test: Highrate no parameter change
[16:21:03.863] <TB3> INFO: running: highrate
[16:21:03.864] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2083_XrayQualification_2015-08-24_17h49m_1440431385//005_HRSCurves_100//phCalibrationFitErr35_C15.dat
[16:21:04.009] <TB3> INFO: ----------------------------------------------------------------------
[16:21:04.009] <TB3> INFO: PixTestHighRate::xNoiseMaps() ntrig = 50, vcal = 200
[16:21:04.009] <TB3> INFO: ----------------------------------------------------------------------
[16:21:04.015] <TB3> INFO: ---> dac: vcal name: xNoiseMap ntrig: 50 dacrange: 0 .. 100 (1/-1) hits flags = 384 (plus default)
[16:21:04.028] <TB3> INFO: dacScan step from 0 .. 0
[16:21:04.028] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:21:04.028] <TB3> INFO: run 1 of 1
[16:21:17.090] <TB3> INFO: Test took 13061ms.
[16:21:18.542] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:21:18.791] <TB3> INFO: dacScan step from 1 .. 1
[16:21:18.791] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:21:18.791] <TB3> INFO: run 1 of 1
[16:21:31.797] <TB3> INFO: Test took 13006ms.
[16:21:33.246] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:21:33.491] <TB3> INFO: dacScan step from 2 .. 2
[16:21:33.491] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:21:33.491] <TB3> INFO: run 1 of 1
[16:21:46.593] <TB3> INFO: Test took 13102ms.
[16:21:48.049] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:21:48.275] <TB3> INFO: dacScan step from 3 .. 3
[16:21:48.275] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:21:48.275] <TB3> INFO: run 1 of 1
[16:22:01.594] <TB3> INFO: Test took 13319ms.
[16:22:03.038] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:03.274] <TB3> INFO: dacScan step from 4 .. 4
[16:22:03.275] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:22:03.275] <TB3> INFO: run 1 of 1
[16:22:16.737] <TB3> INFO: Test took 13462ms.
[16:22:18.057] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:18.275] <TB3> INFO: dacScan step from 5 .. 5
[16:22:18.275] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:22:18.275] <TB3> INFO: run 1 of 1
[16:22:31.376] <TB3> INFO: Test took 13101ms.
[16:22:32.729] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:32.959] <TB3> INFO: dacScan step from 6 .. 6
[16:22:32.959] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:22:32.959] <TB3> INFO: run 1 of 1
[16:22:45.977] <TB3> INFO: Test took 13018ms.
[16:22:47.288] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:22:47.504] <TB3> INFO: dacScan step from 7 .. 7
[16:22:47.504] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:22:47.505] <TB3> INFO: run 1 of 1
[16:23:00.667] <TB3> INFO: Test took 13162ms.
[16:23:01.999] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:02.217] <TB3> INFO: dacScan step from 8 .. 8
[16:23:02.217] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:23:02.217] <TB3> INFO: run 1 of 1
[16:23:15.116] <TB3> INFO: Test took 12899ms.
[16:23:16.503] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:16.737] <TB3> INFO: dacScan step from 9 .. 9
[16:23:16.738] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:23:16.738] <TB3> INFO: run 1 of 1
[16:23:30.172] <TB3> INFO: Test took 13434ms.
[16:23:31.564] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:31.807] <TB3> INFO: dacScan step from 10 .. 10
[16:23:31.807] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:23:31.807] <TB3> INFO: run 1 of 1
[16:23:45.057] <TB3> INFO: Test took 13249ms.
[16:23:46.602] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:23:46.819] <TB3> INFO: dacScan step from 11 .. 11
[16:23:46.819] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:23:46.819] <TB3> INFO: run 1 of 1
[16:24:00.230] <TB3> INFO: Test took 13410ms.
[16:24:01.624] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:01.860] <TB3> INFO: dacScan step from 12 .. 12
[16:24:01.860] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:24:01.860] <TB3> INFO: run 1 of 1
[16:24:15.429] <TB3> INFO: Test took 13569ms.
[16:24:16.881] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:17.117] <TB3> INFO: dacScan step from 13 .. 13
[16:24:17.117] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:24:17.118] <TB3> INFO: run 1 of 1
[16:24:30.576] <TB3> INFO: Test took 13458ms.
[16:24:31.979] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:32.220] <TB3> INFO: dacScan step from 14 .. 14
[16:24:32.220] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:24:32.220] <TB3> INFO: run 1 of 1
[16:24:45.559] <TB3> INFO: Test took 13339ms.
[16:24:47.025] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:24:47.298] <TB3> INFO: dacScan step from 15 .. 15
[16:24:47.298] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:24:47.298] <TB3> INFO: run 1 of 1
[16:25:00.769] <TB3> INFO: Test took 13470ms.
[16:25:02.184] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:02.471] <TB3> INFO: dacScan step from 16 .. 16
[16:25:02.471] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:25:02.471] <TB3> INFO: run 1 of 1
[16:25:15.872] <TB3> INFO: Test took 13400ms.
[16:25:17.421] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:17.700] <TB3> INFO: dacScan step from 17 .. 17
[16:25:17.700] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:25:17.700] <TB3> INFO: run 1 of 1
[16:25:31.105] <TB3> INFO: Test took 13405ms.
[16:25:32.532] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:32.757] <TB3> INFO: dacScan step from 18 .. 18
[16:25:32.757] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:25:32.757] <TB3> INFO: run 1 of 1
[16:25:46.353] <TB3> INFO: Test took 13595ms.
[16:25:47.792] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:25:48.012] <TB3> INFO: dacScan step from 19 .. 19
[16:25:48.012] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:25:48.012] <TB3> INFO: run 1 of 1
[16:26:01.602] <TB3> INFO: Test took 13590ms.
[16:26:02.948] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:03.228] <TB3> INFO: dacScan step from 20 .. 20
[16:26:03.228] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:26:03.228] <TB3> INFO: run 1 of 1
[16:26:16.899] <TB3> INFO: Test took 13670ms.
[16:26:18.347] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:18.581] <TB3> INFO: dacScan step from 21 .. 21
[16:26:18.581] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:26:18.581] <TB3> INFO: run 1 of 1
[16:26:32.058] <TB3> INFO: Test took 13477ms.
[16:26:33.451] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:33.712] <TB3> INFO: dacScan step from 22 .. 22
[16:26:33.712] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:26:33.712] <TB3> INFO: run 1 of 1
[16:26:47.126] <TB3> INFO: Test took 13413ms.
[16:26:48.428] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:26:48.666] <TB3> INFO: dacScan step from 23 .. 23
[16:26:48.666] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:26:48.666] <TB3> INFO: run 1 of 1
[16:27:01.882] <TB3> INFO: Test took 13216ms.
[16:27:03.195] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:03.420] <TB3> INFO: dacScan step from 24 .. 24
[16:27:03.420] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:27:03.420] <TB3> INFO: run 1 of 1
[16:27:16.946] <TB3> INFO: Test took 13526ms.
[16:27:18.304] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:18.591] <TB3> INFO: dacScan step from 25 .. 25
[16:27:18.591] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:27:18.591] <TB3> INFO: run 1 of 1
[16:27:32.265] <TB3> INFO: Test took 13673ms.
[16:27:33.652] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:33.907] <TB3> INFO: dacScan step from 26 .. 26
[16:27:33.907] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:27:33.907] <TB3> INFO: run 1 of 1
[16:27:47.346] <TB3> INFO: Test took 13438ms.
[16:27:48.631] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:27:48.882] <TB3> INFO: dacScan step from 27 .. 27
[16:27:48.882] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:27:48.882] <TB3> INFO: run 1 of 1
[16:28:02.365] <TB3> INFO: Test took 13483ms.
[16:28:03.673] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:03.888] <TB3> INFO: dacScan step from 28 .. 28
[16:28:03.888] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:28:03.888] <TB3> INFO: run 1 of 1
[16:28:17.467] <TB3> INFO: Test took 13578ms.
[16:28:18.781] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:19.038] <TB3> INFO: dacScan step from 29 .. 29
[16:28:19.038] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:28:19.038] <TB3> INFO: run 1 of 1
[16:28:32.279] <TB3> INFO: Test took 13240ms.
[16:28:33.809] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:34.088] <TB3> INFO: dacScan step from 30 .. 30
[16:28:34.088] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:28:34.088] <TB3> INFO: run 1 of 1
[16:28:47.446] <TB3> INFO: Test took 13358ms.
[16:28:48.842] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:28:49.071] <TB3> INFO: dacScan step from 31 .. 31
[16:28:49.071] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:28:49.071] <TB3> INFO: run 1 of 1
[16:29:02.313] <TB3> INFO: Test took 13241ms.
[16:29:03.670] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:03.920] <TB3> INFO: dacScan step from 32 .. 32
[16:29:03.920] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:29:03.920] <TB3> INFO: run 1 of 1
[16:29:17.027] <TB3> INFO: Test took 13107ms.
[16:29:18.447] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:18.726] <TB3> INFO: dacScan step from 33 .. 33
[16:29:18.726] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:29:18.726] <TB3> INFO: run 1 of 1
[16:29:32.391] <TB3> INFO: Test took 13665ms.
[16:29:33.743] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:33.967] <TB3> INFO: dacScan step from 34 .. 34
[16:29:33.967] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:29:33.967] <TB3> INFO: run 1 of 1
[16:29:47.517] <TB3> INFO: Test took 13550ms.
[16:29:48.917] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:49.163] <TB3> INFO: dacScan step from 35 .. 35
[16:29:49.163] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:29:49.163] <TB3> INFO: run 1 of 1
[16:30:02.880] <TB3> INFO: Test took 13716ms.
[16:30:04.317] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:04.565] <TB3> INFO: dacScan step from 36 .. 36
[16:30:04.565] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:30:04.565] <TB3> INFO: run 1 of 1
[16:30:18.023] <TB3> INFO: Test took 13457ms.
[16:30:19.654] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:19.878] <TB3> INFO: dacScan step from 37 .. 37
[16:30:19.878] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:30:19.878] <TB3> INFO: run 1 of 1
[16:30:33.592] <TB3> INFO: Test took 13713ms.
[16:30:35.046] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:35.289] <TB3> INFO: dacScan step from 38 .. 38
[16:30:35.289] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:30:35.289] <TB3> INFO: run 1 of 1
[16:30:49.407] <TB3> INFO: Test took 14118ms.
[16:30:50.975] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:51.254] <TB3> INFO: dacScan step from 39 .. 39
[16:30:51.254] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:30:51.254] <TB3> INFO: run 1 of 1
[16:31:05.509] <TB3> INFO: Test took 14255ms.
[16:31:07.050] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:07.284] <TB3> INFO: dacScan step from 40 .. 40
[16:31:07.284] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:31:07.284] <TB3> INFO: run 1 of 1
[16:31:21.626] <TB3> INFO: Test took 14342ms.
[16:31:23.218] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:23.475] <TB3> INFO: dacScan step from 41 .. 41
[16:31:23.475] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:31:23.475] <TB3> INFO: run 1 of 1
[16:31:37.995] <TB3> INFO: Test took 14519ms.
[16:31:39.628] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:39.923] <TB3> INFO: dacScan step from 42 .. 42
[16:31:39.923] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:31:39.923] <TB3> INFO: run 1 of 1
[16:31:55.376] <TB3> INFO: Test took 15452ms.
[16:31:56.811] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:57.046] <TB3> INFO: dacScan step from 43 .. 43
[16:31:57.046] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:31:57.046] <TB3> INFO: run 1 of 1
[16:32:12.258] <TB3> INFO: Test took 15212ms.
[16:32:13.941] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:14.217] <TB3> INFO: dacScan step from 44 .. 44
[16:32:14.218] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:32:14.218] <TB3> INFO: run 1 of 1
[16:32:29.501] <TB3> INFO: Test took 15283ms.
[16:32:30.923] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:31.145] <TB3> INFO: dacScan step from 45 .. 45
[16:32:31.145] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:32:31.145] <TB3> INFO: run 1 of 1
[16:32:46.799] <TB3> INFO: Test took 15653ms.
[16:32:48.553] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:32:48.792] <TB3> INFO: dacScan step from 46 .. 46
[16:32:48.792] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:32:48.792] <TB3> INFO: run 1 of 1
[16:33:04.808] <TB3> INFO: Test took 16015ms.
[16:33:06.523] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:33:06.744] <TB3> INFO: dacScan step from 47 .. 47
[16:33:06.744] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:33:06.744] <TB3> INFO: run 1 of 1
[16:33:22.768] <TB3> INFO: Test took 16023ms.
[16:33:24.246] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:33:24.478] <TB3> INFO: dacScan step from 48 .. 48
[16:33:24.478] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:33:24.478] <TB3> INFO: run 1 of 1
[16:33:40.373] <TB3> INFO: Test took 15894ms.
[16:33:42.165] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:33:42.456] <TB3> INFO: dacScan step from 49 .. 49
[16:33:42.456] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:33:42.456] <TB3> INFO: run 1 of 1
[16:33:47.247] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[16:33:47.247] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[16:33:47.247] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[16:33:47.247] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[16:33:58.724] <TB3> INFO: Test took 16267ms.
[16:34:00.373] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:34:00.630] <TB3> INFO: dacScan step from 50 .. 50
[16:34:00.630] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:34:00.630] <TB3> INFO: run 1 of 1
[16:34:16.806] <TB3> INFO: Test took 16175ms.
[16:34:18.570] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:34:18.860] <TB3> INFO: dacScan step from 51 .. 51
[16:34:18.860] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:34:18.860] <TB3> INFO: run 1 of 1
[16:34:34.970] <TB3> INFO: Test took 16110ms.
[16:34:36.523] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:34:36.845] <TB3> INFO: dacScan step from 52 .. 52
[16:34:36.845] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:34:36.845] <TB3> INFO: run 1 of 1
[16:34:53.049] <TB3> INFO: Test took 16204ms.
[16:34:54.662] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:34:54.909] <TB3> INFO: dacScan step from 53 .. 53
[16:34:54.909] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:34:54.909] <TB3> INFO: run 1 of 1
[16:35:11.249] <TB3> INFO: Test took 16339ms.
[16:35:12.812] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:13.105] <TB3> INFO: dacScan step from 54 .. 54
[16:35:13.105] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:35:13.105] <TB3> INFO: run 1 of 1
[16:35:29.482] <TB3> INFO: Test took 16376ms.
[16:35:31.055] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:31.305] <TB3> INFO: dacScan step from 55 .. 55
[16:35:31.305] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:35:31.305] <TB3> INFO: run 1 of 1
[16:35:47.731] <TB3> INFO: Test took 16425ms.
[16:35:49.245] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:35:49.476] <TB3> INFO: dacScan step from 56 .. 56
[16:35:49.476] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:35:49.476] <TB3> INFO: run 1 of 1
[16:36:06.101] <TB3> INFO: Test took 16624ms.
[16:36:07.648] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:07.914] <TB3> INFO: dacScan step from 57 .. 57
[16:36:07.914] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:36:07.914] <TB3> INFO: run 1 of 1
[16:36:12.575] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 1 Number of ROCs (2) != Token Chain Length (4)

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

[16:36:24.704] <TB3> INFO: Test took 16789ms.
[16:36:26.212] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:26.497] <TB3> INFO: dacScan step from 58 .. 58
[16:36:26.497] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:36:26.497] <TB3> INFO: run 1 of 1
[16:36:42.736] <TB3> INFO: Test took 16239ms.
[16:36:44.222] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:36:44.456] <TB3> INFO: dacScan step from 59 .. 59
[16:36:44.456] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:36:44.456] <TB3> INFO: run 1 of 1
[16:37:01.317] <TB3> INFO: Test took 16861ms.
[16:37:02.796] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:03.050] <TB3> INFO: dacScan step from 60 .. 60
[16:37:03.050] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:37:03.050] <TB3> INFO: run 1 of 1
[16:37:19.517] <TB3> INFO: Test took 16466ms.
[16:37:21.332] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:21.614] <TB3> INFO: dacScan step from 61 .. 61
[16:37:21.614] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:37:21.614] <TB3> INFO: run 1 of 1
[16:37:38.004] <TB3> INFO: Test took 16389ms.
[16:37:39.954] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:40.262] <TB3> INFO: dacScan step from 62 .. 62
[16:37:40.263] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:37:40.263] <TB3> INFO: run 1 of 1
[16:37:56.740] <TB3> INFO: Test took 16476ms.
[16:37:58.573] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:37:58.843] <TB3> INFO: dacScan step from 63 .. 63
[16:37:58.843] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:37:58.843] <TB3> INFO: run 1 of 1
[16:38:15.135] <TB3> INFO: Test took 16291ms.
[16:38:16.893] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:17.139] <TB3> INFO: dacScan step from 64 .. 64
[16:38:17.139] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:38:17.139] <TB3> INFO: run 1 of 1
[16:38:33.693] <TB3> INFO: Test took 16553ms.
[16:38:35.260] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:35.510] <TB3> INFO: dacScan step from 65 .. 65
[16:38:35.510] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:38:35.510] <TB3> INFO: run 1 of 1
[16:38:51.732] <TB3> INFO: Test took 16221ms.
[16:38:53.295] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:38:53.547] <TB3> INFO: dacScan step from 66 .. 66
[16:38:53.547] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:38:53.547] <TB3> INFO: run 1 of 1
[16:39:10.125] <TB3> INFO: Test took 16577ms.
[16:39:11.678] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:11.915] <TB3> INFO: dacScan step from 67 .. 67
[16:39:11.915] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:39:11.915] <TB3> INFO: run 1 of 1
[16:39:28.350] <TB3> INFO: Test took 16435ms.
[16:39:29.924] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:30.190] <TB3> INFO: dacScan step from 68 .. 68
[16:39:30.190] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:39:30.190] <TB3> INFO: run 1 of 1
[16:39:46.397] <TB3> INFO: Test took 16206ms.
[16:39:48.043] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:39:48.340] <TB3> INFO: dacScan step from 69 .. 69
[16:39:48.340] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:39:48.340] <TB3> INFO: run 1 of 1
[16:40:05.120] <TB3> INFO: Test took 16781ms.
[16:40:06.536] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:06.760] <TB3> INFO: dacScan step from 70 .. 70
[16:40:06.760] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:40:06.760] <TB3> INFO: run 1 of 1
[16:40:23.799] <TB3> INFO: Test took 17039ms.
[16:40:25.350] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:25.582] <TB3> INFO: dacScan step from 71 .. 71
[16:40:25.582] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:40:25.582] <TB3> INFO: run 1 of 1
[16:40:42.355] <TB3> INFO: Test took 16772ms.
[16:40:43.990] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:40:44.226] <TB3> INFO: dacScan step from 72 .. 72
[16:40:44.226] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:40:44.226] <TB3> INFO: run 1 of 1
[16:41:00.577] <TB3> INFO: Test took 16350ms.
[16:41:02.116] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:02.364] <TB3> INFO: dacScan step from 73 .. 73
[16:41:02.364] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:41:02.364] <TB3> INFO: run 1 of 1
[16:41:19.035] <TB3> INFO: Test took 16670ms.
[16:41:20.741] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:21.034] <TB3> INFO: dacScan step from 74 .. 74
[16:41:21.034] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:41:21.034] <TB3> INFO: run 1 of 1
[16:41:37.792] <TB3> INFO: Test took 16757ms.
[16:41:39.573] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:39.889] <TB3> INFO: dacScan step from 75 .. 75
[16:41:39.890] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:41:39.890] <TB3> INFO: run 1 of 1
[16:41:55.929] <TB3> INFO: Test took 16038ms.
[16:41:57.693] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:41:57.971] <TB3> INFO: dacScan step from 76 .. 76
[16:41:57.971] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:41:57.971] <TB3> INFO: run 1 of 1
[16:42:14.815] <TB3> INFO: Test took 16843ms.
[16:42:16.324] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:16.558] <TB3> INFO: dacScan step from 77 .. 77
[16:42:16.558] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:42:16.558] <TB3> INFO: run 1 of 1
[16:42:32.815] <TB3> INFO: Test took 16256ms.
[16:42:34.497] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:34.746] <TB3> INFO: dacScan step from 78 .. 78
[16:42:34.746] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:42:34.746] <TB3> INFO: run 1 of 1
[16:42:51.347] <TB3> INFO: Test took 16600ms.
[16:42:52.976] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:53.274] <TB3> INFO: dacScan step from 79 .. 79
[16:42:53.274] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:42:53.274] <TB3> INFO: run 1 of 1
[16:43:09.971] <TB3> INFO: Test took 16696ms.
[16:43:11.620] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:11.864] <TB3> INFO: dacScan step from 80 .. 80
[16:43:11.864] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:43:11.864] <TB3> INFO: run 1 of 1
[16:43:28.525] <TB3> INFO: Test took 16660ms.
[16:43:30.137] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:30.369] <TB3> INFO: dacScan step from 81 .. 81
[16:43:30.369] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:43:30.369] <TB3> INFO: run 1 of 1
[16:43:46.834] <TB3> INFO: Test took 16464ms.
[16:43:48.406] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:48.653] <TB3> INFO: dacScan step from 82 .. 82
[16:43:48.653] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:43:48.653] <TB3> INFO: run 1 of 1
[16:44:05.430] <TB3> INFO: Test took 16776ms.
[16:44:06.925] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:07.169] <TB3> INFO: dacScan step from 83 .. 83
[16:44:07.169] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:44:07.169] <TB3> INFO: run 1 of 1
[16:44:23.752] <TB3> INFO: Test took 16582ms.
[16:44:25.259] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:25.511] <TB3> INFO: dacScan step from 84 .. 84
[16:44:25.511] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:44:25.511] <TB3> INFO: run 1 of 1
[16:44:41.965] <TB3> INFO: Test took 16453ms.
[16:44:43.620] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:43.859] <TB3> INFO: dacScan step from 85 .. 85
[16:44:43.859] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:44:43.859] <TB3> INFO: run 1 of 1
[16:45:00.579] <TB3> INFO: Test took 16719ms.
[16:45:02.112] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:02.347] <TB3> INFO: dacScan step from 86 .. 86
[16:45:02.347] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:45:02.347] <TB3> INFO: run 1 of 1
[16:45:19.292] <TB3> INFO: Test took 16944ms.
[16:45:21.031] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:21.322] <TB3> INFO: dacScan step from 87 .. 87
[16:45:21.323] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:45:21.323] <TB3> INFO: run 1 of 1
[16:45:37.915] <TB3> INFO: Test took 16592ms.
[16:45:39.395] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:39.621] <TB3> INFO: dacScan step from 88 .. 88
[16:45:39.621] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:45:39.621] <TB3> INFO: run 1 of 1
[16:45:56.288] <TB3> INFO: Test took 16667ms.
[16:45:57.824] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:45:58.077] <TB3> INFO: dacScan step from 89 .. 89
[16:45:58.077] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:45:58.077] <TB3> INFO: run 1 of 1
[16:46:14.809] <TB3> INFO: Test took 16731ms.
[16:46:16.255] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:46:16.478] <TB3> INFO: dacScan step from 90 .. 90
[16:46:16.478] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:46:16.478] <TB3> INFO: run 1 of 1
[16:46:33.335] <TB3> INFO: Test took 16856ms.
[16:46:35.047] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:46:35.304] <TB3> INFO: dacScan step from 91 .. 91
[16:46:35.304] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:46:35.304] <TB3> INFO: run 1 of 1
[16:46:51.632] <TB3> INFO: Test took 16327ms.
[16:46:53.199] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:46:53.496] <TB3> INFO: dacScan step from 92 .. 92
[16:46:53.496] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:46:53.496] <TB3> INFO: run 1 of 1
[16:47:10.157] <TB3> INFO: Test took 16660ms.
[16:47:12.039] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:47:12.356] <TB3> INFO: dacScan step from 93 .. 93
[16:47:12.356] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:47:12.356] <TB3> INFO: run 1 of 1
[16:47:28.881] <TB3> INFO: Test took 16523ms.
[16:47:30.711] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:47:30.965] <TB3> INFO: dacScan step from 94 .. 94
[16:47:30.965] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:47:30.965] <TB3> INFO: run 1 of 1
[16:47:47.168] <TB3> INFO: Test took 16202ms.
[16:47:48.775] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:47:49.025] <TB3> INFO: dacScan step from 95 .. 95
[16:47:49.025] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:47:49.025] <TB3> INFO: run 1 of 1
[16:48:05.287] <TB3> INFO: Test took 16261ms.
[16:48:06.995] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:48:07.267] <TB3> INFO: dacScan step from 96 .. 96
[16:48:07.267] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:48:07.268] <TB3> INFO: run 1 of 1
[16:48:23.101] <TB3> INFO: Test took 15832ms.
[16:48:24.644] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:48:24.869] <TB3> INFO: dacScan step from 97 .. 97
[16:48:24.869] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:48:24.869] <TB3> INFO: run 1 of 1
[16:48:40.395] <TB3> INFO: Test took 15525ms.
[16:48:41.827] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:48:42.068] <TB3> INFO: dacScan step from 98 .. 98
[16:48:42.068] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:48:42.068] <TB3> INFO: run 1 of 1
[16:48:57.381] <TB3> INFO: Test took 15312ms.
[16:48:58.761] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:48:58.979] <TB3> INFO: dacScan step from 99 .. 99
[16:48:58.979] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:48:58.979] <TB3> INFO: run 1 of 1
[16:49:14.679] <TB3> INFO: Test took 15699ms.
[16:49:16.071] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:49:16.289] <TB3> INFO: dacScan step from 100 .. 100
[16:49:16.289] <TB3> INFO: dacScan split into 1 runs with ntrig = 50
[16:49:16.289] <TB3> INFO: run 1 of 1
[16:49:32.013] <TB3> INFO: Test took 15723ms.
[16:49:33.392] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[16:49:33.610] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:49:34.869] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:49:36.090] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:49:37.282] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:49:38.538] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:49:39.810] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:49:41.118] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:49:42.409] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:49:43.699] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:49:45.004] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:49:46.313] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:49:47.659] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:49:48.983] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:49:50.269] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:49:51.515] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:49:52.773] <TB3> INFO: dumping ASCII scurve output file: XSCurveData
[16:49:54.031] <TB3> INFO: PixTest:: pg_setup set to default.
[16:49:54.033] <TB3> INFO: vcal mean: 38.00 38.36 37.79 40.44 40.13 41.87 41.88 41.47 41.68 41.71 42.79 41.99 41.75 38.17 38.56 38.15
[16:49:54.033] <TB3> INFO: vcal RMS: 1.43 1.40 1.13 1.37 1.27 1.47 1.59 1.54 1.41 1.49 1.50 1.64 1.48 1.29 1.21 1.40
[16:49:54.033] <TB3> INFO: number of X-ray hits detected: 172167 178470 149905 270938 268897 326997 349557 335925 339281 358623 377712 325570 316582 157773 181154 170426
[16:49:54.033] <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
[16:49:54.033] <TB3> INFO: X-ray hit rate [MHz/cm2]: 50.5 52.3 43.9 79.4 78.8 95.8 102.5 98.5 99.4 105.1 110.7 95.4 92.8 46.2 53.1 50.0
[16:49:54.033] <TB3> INFO: PixTestHighRate::doXNoiseMaps() done
[16:49:54.131] <TB3> INFO: enter test to run
[16:49:54.131] <TB3> INFO: test: no parameter change
[16:49:54.285] <TB3> QUIET: Connection to board 121 closed.
[16:49:54.365] <TB3> INFO: pXar: this is the end, my friend
commander_CalDelScanAndSaveDacs.log
[17:47:36.689] <TB3> INFO: *** Welcome to pxar ***
[17:47:36.689] <TB3> INFO: *** Today: 2015/08/24
[17:47:36.689] <TB3> INFO: readRocDacs: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[17:47:36.690] <TB3> INFO: readTbmDacs: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0a.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//tbmParameters_C0b.dat
[17:47:36.691] <TB3> INFO: readMaskFile: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//defaultMaskFile.dat
[17:47:36.691] <TB3> INFO: readTrimFile: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//trimParameters35_C15.dat
[17:47:36.771] <TB3> INFO: clk: 4
[17:47:36.771] <TB3> INFO: ctr: 4
[17:47:36.771] <TB3> INFO: sda: 19
[17:47:36.771] <TB3> INFO: tin: 9
[17:47:36.771] <TB3> INFO: level: 15
[17:47:36.771] <TB3> INFO: triggerdelay: 0
[17:47:36.771] <TB3> QUIET: Instanciating API for pxar prod-01+86~g1838649
[17:47:36.771] <TB3> INFO: Log level: INFO
[17:47:36.785] <TB3> INFO: Found DTB DTB_WWV6Z5
[17:47:36.795] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[17:47:36.798] <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:
------------------------------------------------------
[17:47:36.801] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[17:47:38.331] <TB3> INFO: DUT info:
[17:47:38.331] <TB3> INFO: The DUT currently contains the following objects:
[17:47:38.331] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[17:47:38.331] <TB3> INFO: TBM Core alpha (0): 7 registers set
[17:47:38.331] <TB3> INFO: TBM Core beta (1): 7 registers set
[17:47:38.331] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:47:38.331] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:38.331] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:38.332] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:38.332] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:38.332] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:38.332] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:38.332] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:38.332] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:38.332] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:38.332] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:38.332] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:38.332] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:38.332] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:38.332] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:38.332] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:38.332] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:47:38.733] <TB3> INFO: enter 'restricted' command line mode
[17:47:38.733] <TB3> INFO: enter test to run
[17:47:38.733] <TB3> INFO: test: HighRate no parameter change
[17:47:38.733] <TB3> INFO: running: highrate
[17:47:38.734] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//phCalibrationFitErr35_C15.dat
[17:47:38.895] <TB3> INFO: ----------------------------------------------------------------------
[17:47:38.895] <TB3> INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:47:38.895] <TB3> INFO: ----------------------------------------------------------------------
[17:47:39.980] <TB3> INFO: Test took 1079ms.
[17:47:43.723] <TB3> INFO: Test took 3738ms.
[17:47:43.864] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:47.721] <TB3> INFO: Test took 3830ms.
[17:47:47.868] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:51.711] <TB3> INFO: Test took 3815ms.
[17:47:51.853] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:55.840] <TB3> INFO: Test took 3955ms.
[17:47:55.976] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:47:59.994] <TB3> INFO: Test took 3987ms.
[17:48:00.148] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:04.181] <TB3> INFO: Test took 4001ms.
[17:48:04.338] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:08.167] <TB3> INFO: Test took 3795ms.
[17:48:08.325] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:12.265] <TB3> INFO: Test took 3908ms.
[17:48:12.408] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:16.233] <TB3> INFO: Test took 3793ms.
[17:48:16.387] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:20.416] <TB3> INFO: Test took 3996ms.
[17:48:20.572] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:24.579] <TB3> INFO: Test took 3980ms.
[17:48:24.725] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:28.684] <TB3> INFO: Test took 3933ms.
[17:48:28.827] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:32.668] <TB3> INFO: Test took 3816ms.
[17:48:32.816] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:36.669] <TB3> INFO: Test took 3825ms.
[17:48:36.813] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:40.892] <TB3> INFO: Test took 4052ms.
[17:48:41.036] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:45.061] <TB3> INFO: Test took 3998ms.
[17:48:45.221] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:48.884] <TB3> INFO: Test took 3628ms.
[17:48:49.012] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:51.161] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 0 Number of ROCs (2) != Token Chain Length (4)

[17:48:51.161] <TB3> ERROR: <datapipe.cc/CheckEventValidity:L470> Channel 1 Number of ROCs (2) != Token Chain Length (4)

[17:48:51.161] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[17:48:51.161] <TB3> WARNING: Channel 1 ROC 3: Readback start marker after 15 readouts!

[17:48:52.629] <TB3> INFO: Test took 3593ms.
[17:48:52.759] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:56.393] <TB3> INFO: Test took 3609ms.
[17:48:56.521] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:48:59.994] <TB3> INFO: Test took 3449ms.
[17:49:00.115] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:49:00.410] <TB3> INFO: enter test to run
[17:49:00.410] <TB3> INFO: test: Pretest no parameter change
[17:49:00.410] <TB3> INFO: running: pretest
[17:49:00.410] <TB3> INFO: write dac parameters into /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C0.dat
[17:49:00.410] <TB3> INFO: write dac parameters into /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C1.dat
[17:49:00.411] <TB3> INFO: write dac parameters into /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C2.dat
[17:49:00.411] <TB3> INFO: write dac parameters into /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C3.dat
[17:49:00.411] <TB3> INFO: write dac parameters into /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C4.dat
[17:49:00.411] <TB3> INFO: write dac parameters into /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C5.dat
[17:49:00.411] <TB3> INFO: write dac parameters into /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C6.dat
[17:49:00.411] <TB3> INFO: write dac parameters into /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C7.dat
[17:49:00.411] <TB3> INFO: write dac parameters into /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C8.dat
[17:49:00.411] <TB3> INFO: write dac parameters into /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C9.dat
[17:49:00.411] <TB3> INFO: write dac parameters into /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C10.dat
[17:49:00.411] <TB3> INFO: write dac parameters into /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C11.dat
[17:49:00.412] <TB3> INFO: write dac parameters into /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C12.dat
[17:49:00.412] <TB3> INFO: write dac parameters into /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C13.dat
[17:49:00.412] <TB3> INFO: write dac parameters into /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C14.dat
[17:49:00.412] <TB3> INFO: write dac parameters into /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//006_CalDelScanAndSaveDacs_4mA25kV//dacParameters35_C15.dat
[17:49:00.412] <TB3> INFO: enter test to run
[17:49:00.412] <TB3> INFO: test: no parameter change
[17:49:00.594] <TB3> QUIET: Connection to board 121 closed.
[17:49:00.673] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[17:49:36.389] <TB3> INFO: *** Welcome to pxar ***
[17:49:36.389] <TB3> INFO: *** Today: 2015/08/24
[17:49:36.389] <TB3> INFO: readRocDacs: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//dacParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//dacParameters35_C15.dat
[17:49:36.390] <TB3> INFO: readTbmDacs: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//tbmParameters_C0a.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//tbmParameters_C0b.dat
[17:49:36.390] <TB3> INFO: readMaskFile: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//defaultMaskFile.dat
[17:49:36.390] <TB3> INFO: readTrimFile: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//trimParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//trimParameters35_C15.dat
[17:49:36.481] <TB3> INFO: clk: 4
[17:49:36.481] <TB3> INFO: ctr: 4
[17:49:36.481] <TB3> INFO: sda: 19
[17:49:36.481] <TB3> INFO: tin: 9
[17:49:36.481] <TB3> INFO: level: 15
[17:49:36.481] <TB3> INFO: triggerdelay: 0
[17:49:36.481] <TB3> QUIET: Instanciating API for pxar prod-01+86~g1838649
[17:49:36.481] <TB3> INFO: Log level: INFO
[17:49:36.496] <TB3> INFO: Found DTB DTB_WWV6Z5
[17:49:36.506] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[17:49:36.509] <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:
------------------------------------------------------
[17:49:36.512] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[17:49:38.037] <TB3> INFO: DUT info:
[17:49:38.037] <TB3> INFO: The DUT currently contains the following objects:
[17:49:38.037] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[17:49:38.037] <TB3> INFO: TBM Core alpha (0): 7 registers set
[17:49:38.037] <TB3> INFO: TBM Core beta (1): 7 registers set
[17:49:38.037] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:49:38.037] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:38.037] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:38.037] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:38.037] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:38.037] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:38.037] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:38.037] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:38.037] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:38.037] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:38.037] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:38.037] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:38.037] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:38.037] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:38.037] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:38.037] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:38.037] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:49:38.439] <TB3> INFO: enter 'restricted' command line mode
[17:49:38.439] <TB3> INFO: enter test to run
[17:49:38.439] <TB3> INFO: test: HighRate no parameter change
[17:49:38.439] <TB3> INFO: running: highrate
[17:49:38.439] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//007_HREfficiency_50//phCalibrationFitErr35_C15.dat
[17:49:38.577] <TB3> INFO: ----------------------------------------------------------------------
[17:49:38.577] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:49:38.577] <TB3> INFO: ----------------------------------------------------------------------
[17:49:42.605] <TB3> ERROR: <datapipe.cc/CheckEventID:L453> Channel 0 Event ID mismatch: local ID (4) != TBM ID (5)

[17:49:42.605] <TB3> WARNING: Channel 0 ROC 0: Readback start marker after 15 readouts!

[17:49:42.605] <TB3> WARNING: Channel 0 ROC 1: Readback start marker after 15 readouts!

[17:49:42.605] <TB3> WARNING: Channel 0 ROC 2: Readback start marker after 15 readouts!

[17:49:42.605] <TB3> WARNING: Channel 0 ROC 3: Readback start marker after 15 readouts!

[17:49:51.466] <TB3> INFO: Test took 12883ms.
[17:49:52.145] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:49:52.443] <TB3> INFO: number of dead pixels (per ROC): 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2
[17:49:52.443] <TB3> INFO: number of red-efficiency pixels: 4083 4082 4079 4084 189 255 293 279 258 299 301 243 256 102 117 99
[17:49:52.443] <TB3> INFO: number of X-ray hits detected: 89292 91389 77312 140510 133392 166639 177159 169402 170920 181898 193649 164599 160036 77550 89471 85371
[17:49:52.443] <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
[17:49:52.443] <TB3> INFO: number of Vcal hits detected: 203659 203836 203814 203736 207800 207735 207691 207707 207734 207690 207690 207744 207738 207895 207882 207801
[17:49:52.443] <TB3> INFO: Vcal hit fiducial efficiency (%): 98.0 98.0 98.0 97.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
[17:49:52.443] <TB3> INFO: Vcal hit overall efficiency (%): 97.9 98.0 98.0 98.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9
[17:49:52.443] <TB3> INFO: X-ray hit rate [MHz/cm2]: 26.2 26.8 22.7 41.2 39.1 48.8 51.9 49.7 50.1 53.3 56.8 48.2 46.9 22.7 26.2 25.0
[17:49:52.443] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[17:49:52.468] <TB3> INFO: PixTest:: pg_setup set to default.
[17:49:52.499] <TB3> INFO: enter test to run
[17:49:52.499] <TB3> INFO: test: no parameter change
[17:49:52.667] <TB3> QUIET: Connection to board 121 closed.
[17:49:52.747] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[17:50:27.113] <TB3> INFO: *** Welcome to pxar ***
[17:50:27.113] <TB3> INFO: *** Today: 2015/08/24
[17:50:27.113] <TB3> INFO: readRocDacs: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//dacParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//dacParameters35_C15.dat
[17:50:27.113] <TB3> INFO: readTbmDacs: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//tbmParameters_C0a.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//tbmParameters_C0b.dat
[17:50:27.113] <TB3> INFO: readMaskFile: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//defaultMaskFile.dat
[17:50:27.113] <TB3> INFO: readTrimFile: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//trimParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//trimParameters35_C15.dat
[17:50:27.201] <TB3> INFO: clk: 4
[17:50:27.201] <TB3> INFO: ctr: 4
[17:50:27.201] <TB3> INFO: sda: 19
[17:50:27.201] <TB3> INFO: tin: 9
[17:50:27.201] <TB3> INFO: level: 15
[17:50:27.201] <TB3> INFO: triggerdelay: 0
[17:50:27.201] <TB3> QUIET: Instanciating API for pxar prod-01+86~g1838649
[17:50:27.201] <TB3> INFO: Log level: INFO
[17:50:27.215] <TB3> INFO: Found DTB DTB_WWV6Z5
[17:50:27.224] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[17:50:27.227] <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:
------------------------------------------------------
[17:50:27.230] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[17:50:28.759] <TB3> INFO: DUT info:
[17:50:28.759] <TB3> INFO: The DUT currently contains the following objects:
[17:50:28.759] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[17:50:28.759] <TB3> INFO: TBM Core alpha (0): 7 registers set
[17:50:28.759] <TB3> INFO: TBM Core beta (1): 7 registers set
[17:50:28.759] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:50:28.759] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:28.759] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:28.759] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:28.760] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:28.760] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:28.760] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:28.760] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:28.760] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:28.760] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:28.760] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:28.760] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:28.760] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:28.760] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:28.760] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:28.760] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:28.760] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:50:29.161] <TB3> INFO: enter 'restricted' command line mode
[17:50:29.161] <TB3> INFO: enter test to run
[17:50:29.161] <TB3> INFO: test: HighRate no parameter change
[17:50:29.161] <TB3> INFO: running: highrate
[17:50:29.162] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//008_HREfficiency_100//phCalibrationFitErr35_C15.dat
[17:50:29.350] <TB3> INFO: ----------------------------------------------------------------------
[17:50:29.350] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:50:29.350] <TB3> INFO: ----------------------------------------------------------------------
[17:50:43.995] <TB3> INFO: Test took 14640ms.
[17:50:45.783] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:50:46.154] <TB3> INFO: number of dead pixels (per ROC): 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2
[17:50:46.154] <TB3> INFO: number of red-efficiency pixels: 275 316 318 612 557 802 1041 923 964 1045 1111 941 873 282 329 290
[17:50:46.154] <TB3> INFO: number of X-ray hits detected: 172137 178760 150498 274104 269616 330191 351262 337968 344008 361235 381568 326512 319382 159544 182059 171280
[17:50:46.154] <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
[17:50:46.154] <TB3> INFO: number of Vcal hits detected: 207566 207664 207629 207305 207382 207039 206646 206921 206837 206661 206588 206806 206987 207683 207653 207593
[17:50:46.154] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.7 99.7 99.6 99.4 99.5 99.5 99.4 99.4 99.5 99.6 99.9 99.8 99.9
[17:50:46.155] <TB3> INFO: Vcal hit overall efficiency (%): 99.8 99.8 99.8 99.7 99.7 99.5 99.3 99.5 99.4 99.4 99.3 99.4 99.5 99.8 99.8 99.8
[17:50:46.155] <TB3> INFO: X-ray hit rate [MHz/cm2]: 50.5 52.4 44.1 80.3 79.0 96.8 103.0 99.1 100.8 105.9 111.8 95.7 93.6 46.8 53.4 50.2
[17:50:46.155] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[17:50:46.181] <TB3> INFO: PixTest:: pg_setup set to default.
[17:50:46.214] <TB3> INFO: enter test to run
[17:50:46.214] <TB3> INFO: test: no parameter change
[17:50:46.381] <TB3> QUIET: Connection to board 121 closed.
[17:50:46.461] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[17:51:21.009] <TB3> INFO: *** Welcome to pxar ***
[17:51:21.009] <TB3> INFO: *** Today: 2015/08/24
[17:51:21.009] <TB3> INFO: readRocDacs: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//dacParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//dacParameters35_C15.dat
[17:51:21.010] <TB3> INFO: readTbmDacs: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//tbmParameters_C0a.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//tbmParameters_C0b.dat
[17:51:21.010] <TB3> INFO: readMaskFile: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//defaultMaskFile.dat
[17:51:21.010] <TB3> INFO: readTrimFile: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//trimParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//trimParameters35_C15.dat
[17:51:21.084] <TB3> INFO: clk: 4
[17:51:21.084] <TB3> INFO: ctr: 4
[17:51:21.084] <TB3> INFO: sda: 19
[17:51:21.084] <TB3> INFO: tin: 9
[17:51:21.084] <TB3> INFO: level: 15
[17:51:21.084] <TB3> INFO: triggerdelay: 0
[17:51:21.084] <TB3> QUIET: Instanciating API for pxar prod-01+86~g1838649
[17:51:21.084] <TB3> INFO: Log level: INFO
[17:51:21.097] <TB3> INFO: Found DTB DTB_WWV6Z5
[17:51:21.107] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[17:51:21.111] <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:
------------------------------------------------------
[17:51:21.113] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[17:51:22.641] <TB3> INFO: DUT info:
[17:51:22.642] <TB3> INFO: The DUT currently contains the following objects:
[17:51:22.642] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[17:51:22.642] <TB3> INFO: TBM Core alpha (0): 7 registers set
[17:51:22.642] <TB3> INFO: TBM Core beta (1): 7 registers set
[17:51:22.642] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:51:22.642] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:22.642] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:22.642] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:22.642] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:22.642] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:22.642] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:22.642] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:22.642] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:22.642] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:22.642] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:22.642] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:22.642] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:22.642] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:22.642] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:22.642] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:22.642] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:51:23.043] <TB3> INFO: enter 'restricted' command line mode
[17:51:23.043] <TB3> INFO: enter test to run
[17:51:23.043] <TB3> INFO: test: HighRate no parameter change
[17:51:23.043] <TB3> INFO: running: highrate
[17:51:23.043] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//009_HREfficiency_150//phCalibrationFitErr35_C15.dat
[17:51:23.178] <TB3> INFO: ----------------------------------------------------------------------
[17:51:23.178] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:51:23.178] <TB3> INFO: ----------------------------------------------------------------------
[17:51:40.689] <TB3> INFO: Test took 17506ms.
[17:51:43.869] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:51:44.302] <TB3> INFO: number of dead pixels (per ROC): 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2
[17:51:44.302] <TB3> INFO: number of red-efficiency pixels: 524 598 564 1314 1197 1757 2038 1981 1962 2058 2191 1826 1647 583 760 493
[17:51:44.302] <TB3> INFO: number of X-ray hits detected: 250687 261386 220031 393377 387916 471874 503751 486120 494269 518812 543153 471406 459031 231523 265007 248728
[17:51:44.302] <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
[17:51:44.302] <TB3> INFO: number of Vcal hits detected: 207279 207342 207204 206236 206394 205375 204409 205121 205068 204415 204263 204983 205608 207232 207132 207364
[17:51:44.302] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.8 99.7 99.7 99.3 99.3 98.9 98.5 98.7 98.7 98.5 98.4 98.7 99.0 99.7 99.6 99.8
[17:51:44.302] <TB3> INFO: Vcal hit overall efficiency (%): 99.7 99.7 99.6 99.2 99.2 98.7 98.3 98.6 98.6 98.3 98.2 98.5 98.9 99.6 99.6 99.7
[17:51:44.302] <TB3> INFO: X-ray hit rate [MHz/cm2]: 73.5 76.6 64.5 115.3 113.7 138.3 147.7 142.5 144.9 152.1 159.2 138.2 134.5 67.9 77.7 72.9
[17:51:44.302] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[17:51:44.327] <TB3> INFO: PixTest:: pg_setup set to default.
[17:51:44.365] <TB3> INFO: enter test to run
[17:51:44.365] <TB3> INFO: test: no parameter change
[17:51:44.516] <TB3> QUIET: Connection to board 121 closed.
[17:51:44.599] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[17:52:18.900] <TB3> INFO: *** Welcome to pxar ***
[17:52:18.900] <TB3> INFO: *** Today: 2015/08/24
[17:52:18.900] <TB3> INFO: readRocDacs: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//dacParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//dacParameters35_C15.dat
[17:52:18.902] <TB3> INFO: readTbmDacs: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//tbmParameters_C0a.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//tbmParameters_C0b.dat
[17:52:18.902] <TB3> INFO: readMaskFile: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//defaultMaskFile.dat
[17:52:18.902] <TB3> INFO: readTrimFile: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//trimParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//trimParameters35_C15.dat
[17:52:18.984] <TB3> INFO: clk: 4
[17:52:18.984] <TB3> INFO: ctr: 4
[17:52:18.984] <TB3> INFO: sda: 19
[17:52:18.984] <TB3> INFO: tin: 9
[17:52:18.984] <TB3> INFO: level: 15
[17:52:18.984] <TB3> INFO: triggerdelay: 0
[17:52:18.984] <TB3> QUIET: Instanciating API for pxar prod-01+86~g1838649
[17:52:18.984] <TB3> INFO: Log level: INFO
[17:52:18.998] <TB3> INFO: Found DTB DTB_WWV6Z5
[17:52:19.008] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[17:52:19.011] <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:
------------------------------------------------------
[17:52:19.014] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[17:52:20.552] <TB3> INFO: DUT info:
[17:52:20.552] <TB3> INFO: The DUT currently contains the following objects:
[17:52:20.552] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[17:52:20.552] <TB3> INFO: TBM Core alpha (0): 7 registers set
[17:52:20.552] <TB3> INFO: TBM Core beta (1): 7 registers set
[17:52:20.552] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:52:20.552] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:20.552] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:20.553] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:20.553] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:20.553] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:20.553] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:20.553] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:20.553] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:20.553] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:20.553] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:20.553] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:20.553] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:20.553] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:20.553] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:20.553] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:20.553] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:52:20.954] <TB3> INFO: enter 'restricted' command line mode
[17:52:20.954] <TB3> INFO: enter test to run
[17:52:20.955] <TB3> INFO: test: HighRate no parameter change
[17:52:20.955] <TB3> INFO: running: highrate
[17:52:20.955] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//010_HREfficiency_200//phCalibrationFitErr35_C15.dat
[17:52:21.113] <TB3> INFO: ----------------------------------------------------------------------
[17:52:21.114] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:52:21.114] <TB3> INFO: ----------------------------------------------------------------------
[17:52:41.482] <TB3> INFO: Test took 20363ms.
[17:52:46.720] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:52:47.226] <TB3> INFO: number of dead pixels (per ROC): 3 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2
[17:52:47.226] <TB3> INFO: number of red-efficiency pixels: 1001 1098 884 2195 2051 2768 3157 3190 3122 3293 3332 2983 2797 964 1353 967
[17:52:47.226] <TB3> INFO: number of X-ray hits detected: 341521 356054 301659 528764 521165 632112 675285 651651 665563 695105 728690 634702 618173 315294 360114 339377
[17:52:47.226] <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
[17:52:47.226] <TB3> INFO: number of Vcal hits detected: 206634 206685 206285 204182 204342 202051 199518 201109 200959 199145 198933 200621 202211 206308 206281 206740
[17:52:47.226] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.5 99.4 99.4 98.4 98.5 97.6 96.5 97.0 96.9 96.3 96.2 97.0 97.5 99.4 99.2 99.5
[17:52:47.226] <TB3> INFO: Vcal hit overall efficiency (%): 99.3 99.4 99.2 98.2 98.2 97.1 95.9 96.7 96.6 95.7 95.6 96.5 97.2 99.2 99.2 99.4
[17:52:47.226] <TB3> INFO: X-ray hit rate [MHz/cm2]: 100.1 104.4 88.4 155.0 152.8 185.3 197.9 191.0 195.1 203.7 213.6 186.0 181.2 92.4 105.6 99.5
[17:52:47.226] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[17:52:47.251] <TB3> INFO: PixTest:: pg_setup set to default.
[17:52:47.288] <TB3> INFO: enter test to run
[17:52:47.288] <TB3> INFO: test: no parameter change
[17:52:47.444] <TB3> QUIET: Connection to board 121 closed.
[17:52:47.526] <TB3> INFO: pXar: this is the end, my friend
commander_HREfficiency.log
[17:53:22.753] <TB3> INFO: *** Welcome to pxar ***
[17:53:22.753] <TB3> INFO: *** Today: 2015/08/24
[17:53:22.753] <TB3> INFO: readRocDacs: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//dacParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//dacParameters35_C15.dat
[17:53:22.754] <TB3> INFO: readTbmDacs: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//tbmParameters_C0a.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//tbmParameters_C0b.dat
[17:53:22.754] <TB3> INFO: readMaskFile: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//defaultMaskFile.dat
[17:53:22.755] <TB3> INFO: readTrimFile: /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//trimParameters35_C0.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//trimParameters35_C15.dat
[17:53:22.828] <TB3> INFO: clk: 4
[17:53:22.828] <TB3> INFO: ctr: 4
[17:53:22.828] <TB3> INFO: sda: 19
[17:53:22.828] <TB3> INFO: tin: 9
[17:53:22.828] <TB3> INFO: level: 15
[17:53:22.828] <TB3> INFO: triggerdelay: 0
[17:53:22.828] <TB3> QUIET: Instanciating API for pxar prod-01+86~g1838649
[17:53:22.828] <TB3> INFO: Log level: INFO
[17:53:22.841] <TB3> INFO: Found DTB DTB_WWV6Z5
[17:53:22.851] <TB3> QUIET: Connection to board DTB_WWV6Z5 opened.
[17:53:22.854] <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:
------------------------------------------------------
[17:53:22.857] <TB3> INFO: RPC call hashes of host and DTB match: 447413373
[17:53:24.418] <TB3> INFO: DUT info:
[17:53:24.418] <TB3> INFO: The DUT currently contains the following objects:
[17:53:24.418] <TB3> INFO: 2 TBM Cores tbm09c (2 ON)
[17:53:24.418] <TB3> INFO: TBM Core alpha (0): 7 registers set
[17:53:24.418] <TB3> INFO: TBM Core beta (1): 7 registers set
[17:53:24.418] <TB3> INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:53:24.418] <TB3> INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:24.418] <TB3> INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:24.418] <TB3> INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:24.418] <TB3> INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:24.418] <TB3> INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:24.418] <TB3> INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:24.418] <TB3> INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:24.418] <TB3> INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:24.418] <TB3> INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:24.418] <TB3> INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:24.418] <TB3> INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:24.418] <TB3> INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:24.418] <TB3> INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:24.418] <TB3> INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:24.418] <TB3> INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:24.418] <TB3> INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:53:24.820] <TB3> INFO: enter 'restricted' command line mode
[17:53:24.820] <TB3> INFO: enter test to run
[17:53:24.820] <TB3> INFO: test: HighRate no parameter change
[17:53:24.820] <TB3> INFO: running: highrate
[17:53:24.821] <TB3> INFO: readGainPedestalParameters /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//phCalibrationFitErr35_C0.dat .. /usr/local/data/M2083_XrayQualification2_2015-08-24_19h24m_1440437058//011_HREfficiency_250//phCalibrationFitErr35_C15.dat
[17:53:24.997] <TB3> INFO: ----------------------------------------------------------------------
[17:53:24.997] <TB3> INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:53:24.997] <TB3> INFO: ----------------------------------------------------------------------
[17:53:45.384] <TB3> INFO: Test took 20381ms.
[17:53:51.081] <TB3> INFO: Fetched DAQ statistics. Counters are being reset now.
[17:53:51.594] <TB3> INFO: number of dead pixels (per ROC): 2 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2
[17:53:51.594] <TB3> INFO: number of red-efficiency pixels: 1015 1173 977 2267 2208 2936 3262 3225 3259 3361 3475 3025 2933 1056 1489 1016
[17:53:51.594] <TB3> INFO: number of X-ray hits detected: 352253 369964 313947 546238 539103 652217 696549 674824 687300 719312 745071 653646 633206 330184 373946 348322
[17:53:51.594] <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
[17:53:51.594] <TB3> INFO: number of Vcal hits detected: 206638 206510 206137 203977 203967 201390 199055 200606 200255 198431 198232 200288 201658 206146 206054 206647
[17:53:51.594] <TB3> INFO: Vcal hit fiducial efficiency (%): 99.4 99.4 99.3 98.3 98.3 97.2 96.3 96.8 96.6 96.0 95.9 96.8 97.3 99.3 99.1 99.5
[17:53:51.594] <TB3> INFO: Vcal hit overall efficiency (%): 99.3 99.3 99.1 98.1 98.1 96.8 95.7 96.4 96.3 95.4 95.3 96.3 97.0 99.1 99.1 99.3
[17:53:51.594] <TB3> INFO: X-ray hit rate [MHz/cm2]: 103.2 108.4 92.0 160.1 158.0 191.2 204.2 197.8 201.5 210.8 218.4 191.6 185.6 96.8 109.6 102.1
[17:53:51.594] <TB3> INFO: PixTestHighRate::doXPixelAlive() done
[17:53:51.620] <TB3> INFO: PixTest:: pg_setup set to default.
[17:53:51.655] <TB3> INFO: enter test to run
[17:53:51.655] <TB3> INFO: test: no parameter change
[17:53:51.829] <TB3> QUIET: Connection to board 121 closed.
[17:53:51.908] <TB3> INFO: pXar: this is the end, my friend
MoReWeb-v1.0.0-5-g82d9ff6 on branch psi46master