[10:26:20.761] INFO: *** Welcome to pxar ***
[10:26:20.761] INFO: *** Today: 2016/09/06
[10:26:20.778] INFO: *** Version: v1.9.0-818-g96727
[10:26:20.778] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//dacParameters35_C15.dat
[10:26:20.779] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:26:20.779] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//defaultMaskFile.dat
[10:26:20.779] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C15.dat
[10:26:20.851] INFO: clk: 4
[10:26:20.851] INFO: ctr: 4
[10:26:20.851] INFO: sda: 19
[10:26:20.851] INFO: tin: 9
[10:26:20.851] INFO: level: 15
[10:26:20.851] INFO: triggerdelay: 0
[10:26:20.851] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[10:26:20.851] INFO: Log level: INFO
[10:26:20.867] QUIET: Connection to board DTB_WREKRL opened.
[10:26:20.870] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[10:26:20.873] INFO: RPC call hashes of host and DTB match: 398089610
[10:26:22.418] INFO: DUT info:
[10:26:22.418] INFO: The DUT currently contains the following objects:
[10:26:22.418] INFO: 2 TBM Cores tbm08c (2 ON)
[10:26:22.418] INFO: TBM Core alpha (0): 7 registers set
[10:26:22.418] INFO: TBM Core beta (1): 7 registers set
[10:26:22.418] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:26:22.418] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:26:22.418] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:26:22.418] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:26:22.418] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:26:22.419] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:26:22.419] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:26:22.419] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:26:22.419] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:26:22.419] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:26:22.419] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:26:22.419] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:26:22.419] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:26:22.419] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:26:22.419] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:26:22.419] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:26:22.419] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:26:22.875] INFO: enter 'restricted' command line mode
[10:26:22.876] INFO: enter test to run
[10:26:32.093] INFO: test: PixelAlive no parameter change
[10:26:32.093] INFO: running: pixelalive
[10:26:32.145] INFO: ----------------------------------------------------------------------
[10:26:32.145] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:26:32.145] INFO: ----------------------------------------------------------------------
[10:26:32.468] INFO: Expecting 41600 events.
[10:26:36.723] INFO: 41600 events read in total (3536ms).
[10:26:36.884] INFO: Test took 4736ms.
[10:26:36.894] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:26:37.182] INFO: PixTestAlive::aliveTest() done
[10:26:37.182] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:26:37.320] INFO: enter test to run
[10:27:15.180] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:27:15.180] INFO: running: highrate
[10:27:15.191] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:27:15.420] INFO: ----------------------------------------------------------------------
[10:27:15.420] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:27:15.420] INFO: ----------------------------------------------------------------------
[10:27:15.420] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:27:15.420] INFO: edge/corner pixel THR is adjusted
[10:27:15.420] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:27:16.378] INFO: Collecting data for 5 seconds...
[10:27:21.397] INFO: Done with hot pixel readout
[10:27:30.769] INFO: PixTest:: pg_setup set to default.
[10:27:30.770] INFO: 0 hot pixels found in step 0
[10:27:30.780] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:27:30.880] INFO: PixTest::trimHotPixels() done
[10:27:30.934] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C0.dat
[10:27:30.939] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C1.dat
[10:27:30.945] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C2.dat
[10:27:30.950] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C3.dat
[10:27:30.956] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C4.dat
[10:27:30.961] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C5.dat
[10:27:30.966] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C6.dat
[10:27:30.972] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C7.dat
[10:27:30.977] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C8.dat
[10:27:30.982] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C9.dat
[10:27:30.988] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C10.dat
[10:27:30.993] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C11.dat
[10:27:30.998] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C12.dat
[10:27:30.004] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C13.dat
[10:27:31.009] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C14.dat
[10:27:31.015] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C15.dat
[10:27:31.020] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//defaultMaskFile.dat
[10:27:31.032] INFO: enter test to run
[10:28:04.516] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:28:04.516] INFO: running: highrate
[10:28:04.520] INFO: ----------------------------------------------------------------------
[10:28:04.520] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:28:04.520] INFO: ----------------------------------------------------------------------
[10:28:04.520] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:28:04.520] INFO: edge/corner pixel THR is adjusted
[10:28:04.520] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:28:05.478] INFO: Collecting data for 1 seconds...
[10:28:06.482] INFO: Done with hot pixel readout
[10:28:09.007] INFO: PixTest:: pg_setup set to default.
[10:28:09.008] INFO: 0 hot pixels found in step 0
[10:28:09.013] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:28:09.164] INFO: PixTest::trimHotPixels() done
[10:28:09.164] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C0.dat
[10:28:09.175] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C1.dat
[10:28:09.184] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C2.dat
[10:28:09.189] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C3.dat
[10:28:09.195] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C4.dat
[10:28:09.200] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C5.dat
[10:28:09.205] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C6.dat
[10:28:09.210] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C7.dat
[10:28:09.216] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C8.dat
[10:28:09.221] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C9.dat
[10:28:09.226] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C10.dat
[10:28:09.232] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C11.dat
[10:28:09.237] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C12.dat
[10:28:09.242] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C13.dat
[10:28:09.248] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C14.dat
[10:28:09.253] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//trimParameters35_C15.dat
[10:28:09.258] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-8-03_FPIXTest-17C-FNAL-160831-1531-150V_2016-08-31_15h31m_1472675500/000_FPIXTest_p17//defaultMaskFile.dat
[10:28:09.267] INFO: enter test to run
[10:28:39.452] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:28:39.452] INFO: running: xray
[10:28:39.453] INFO: ----------------------------------------------------------------------
[10:28:39.453] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:28:39.453] INFO: ----------------------------------------------------------------------
[10:28:40.433] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:28:55.603] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[10:29:23.774] INFO: Resuming triggers.
[10:29:38.941] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[10:30:06.938] INFO: Resuming triggers.
[10:30:22.105] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[10:30:50.281] INFO: Resuming triggers.
[10:31:05.451] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[10:31:33.668] INFO: Resuming triggers.
[10:31:48.837] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[10:32:16.866] INFO: Resuming triggers.
[10:32:32.036] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[10:33:00.237] INFO: Resuming triggers.
[10:33:09.567] INFO: data taking finished, elapsed time: 100 seconds.
[10:33:27.197] INFO: PixTest:: pg_setup set to default.
[10:33:27.200] INFO: PixTestXray::doPhRun() done
[10:33:27.352] INFO: enter test to run
[10:34:09.585] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:34:09.586] INFO: running: xray
[10:34:09.587] INFO: ----------------------------------------------------------------------
[10:34:09.587] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:34:09.587] INFO: ----------------------------------------------------------------------
[10:34:10.552] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:34:22.762] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[10:34:52.101] INFO: Resuming triggers.
[10:35:04.310] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[10:35:33.604] INFO: Resuming triggers.
[10:35:45.813] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[10:36:15.095] INFO: Resuming triggers.
[10:36:27.300] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[10:36:56.865] INFO: Resuming triggers.
[10:37:09.072] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[10:37:38.319] INFO: Resuming triggers.
[10:37:50.529] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[10:38:19.937] INFO: Resuming triggers.
[10:38:32.139] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[10:39:01.457] INFO: Resuming triggers.
[10:39:13.663] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[10:39:43.590] INFO: Resuming triggers.
[10:39:46.300] INFO: data taking finished, elapsed time: 100 seconds.
[10:39:53.366] INFO: PixTest:: pg_setup set to default.
[10:39:53.369] INFO: PixTestXray::doPhRun() done
[10:39:53.503] INFO: enter test to run
[10:40:17.879] INFO: test: HighRate no parameter change
[10:40:17.879] INFO: running: highrate
[10:40:17.880] INFO: ----------------------------------------------------------------------
[10:40:17.880] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:40:17.880] INFO: ----------------------------------------------------------------------
[10:40:18.026] INFO: Expecting 768 events.
[10:40:19.160] INFO: 768 events read in total (418ms).
[10:40:19.161] INFO: Test took 1269ms.
[10:40:19.963] INFO: Expecting 41600 events.
[10:40:22.828] INFO: 41600 events read in total (2338ms).
[10:40:22.829] INFO: Test took 3662ms.
[10:40:22.842] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:23.601] INFO: Expecting 41600 events.
[10:40:26.701] INFO: 41600 events read in total (2573ms).
[10:40:26.702] INFO: Test took 3849ms.
[10:40:26.715] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:27.468] INFO: Expecting 41600 events.
[10:40:30.598] INFO: 41600 events read in total (2603ms).
[10:40:30.599] INFO: Test took 3873ms.
[10:40:30.613] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:31.375] INFO: Expecting 41600 events.
[10:40:34.485] INFO: 41600 events read in total (2583ms).
[10:40:34.486] INFO: Test took 3863ms.
[10:40:34.500] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:35.262] INFO: Expecting 41600 events.
[10:40:38.337] INFO: 41600 events read in total (2548ms).
[10:40:38.338] INFO: Test took 3828ms.
[10:40:38.355] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:39.101] INFO: Expecting 41600 events.
[10:40:42.184] INFO: 41600 events read in total (2556ms).
[10:40:42.186] INFO: Test took 3821ms.
[10:40:42.210] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:42.954] INFO: Expecting 41600 events.
[10:40:46.092] INFO: 41600 events read in total (2612ms).
[10:40:46.093] INFO: Test took 3870ms.
[10:40:46.109] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:46.860] INFO: Expecting 41600 events.
[10:40:49.966] INFO: 41600 events read in total (2579ms).
[10:40:49.966] INFO: Test took 3847ms.
[10:40:49.982] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:50.738] INFO: Expecting 41600 events.
[10:40:53.814] INFO: 41600 events read in total (2549ms).
[10:40:53.815] INFO: Test took 3823ms.
[10:40:53.832] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:54.575] INFO: Expecting 41600 events.
[10:40:57.631] INFO: 41600 events read in total (2529ms).
[10:40:57.632] INFO: Test took 3785ms.
[10:40:57.646] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:40:58.404] INFO: Expecting 41600 events.
[10:41:01.436] INFO: 41600 events read in total (2505ms).
[10:41:01.436] INFO: Test took 3780ms.
[10:41:01.451] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:02.210] INFO: Expecting 41600 events.
[10:41:05.335] INFO: 41600 events read in total (2599ms).
[10:41:05.336] INFO: Test took 3875ms.
[10:41:05.350] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:06.109] INFO: Expecting 41600 events.
[10:41:09.163] INFO: 41600 events read in total (2527ms).
[10:41:09.164] INFO: Test took 3804ms.
[10:41:09.178] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:09.939] INFO: Expecting 41600 events.
[10:41:12.993] INFO: 41600 events read in total (2527ms).
[10:41:12.994] INFO: Test took 3804ms.
[10:41:13.008] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:13.770] INFO: Expecting 41600 events.
[10:41:16.810] INFO: 41600 events read in total (2513ms).
[10:41:16.811] INFO: Test took 3792ms.
[10:41:16.824] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:17.589] INFO: Expecting 41600 events.
[10:41:20.635] INFO: 41600 events read in total (2519ms).
[10:41:20.636] INFO: Test took 3801ms.
[10:41:20.649] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:21.410] INFO: Expecting 41600 events.
[10:41:24.452] INFO: 41600 events read in total (2515ms).
[10:41:24.452] INFO: Test took 3792ms.
[10:41:24.466] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:25.228] INFO: Expecting 41600 events.
[10:41:28.267] INFO: 41600 events read in total (2512ms).
[10:41:28.268] INFO: Test took 3791ms.
[10:41:28.281] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:29.042] INFO: Expecting 41600 events.
[10:41:32.023] INFO: 41600 events read in total (2454ms).
[10:41:32.024] INFO: Test took 3732ms.
[10:41:32.038] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:32.798] INFO: Expecting 41600 events.
[10:41:35.682] INFO: 41600 events read in total (2357ms).
[10:41:35.683] INFO: Test took 3634ms.
[10:41:35.696] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:41:36.096] INFO: enter test to run
[10:42:25.550] INFO: test: HighRate no parameter change
[10:42:25.550] INFO: running: highrate
[10:42:25.551] INFO: ----------------------------------------------------------------------
[10:42:25.551] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:42:25.551] INFO: ----------------------------------------------------------------------
[10:42:26.166] INFO: Expecting 208000 events.
[10:42:36.926] INFO: 208000 events read in total (10233ms).
[10:42:36.927] INFO: Test took 11367ms.
[10:42:36.968] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:42:37.204] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:42:37.204] INFO: number of red-efficiency pixels: 17 10 13 29 29 33 29 21 22 30 32 18 18 8 3 11
[10:42:37.204] INFO: number of X-ray hits detected: 16805 10381 17031 28004 29791 30160 31344 22373 20258 26857 25507 21500 21303 12747 4645 5788
[10:42:37.204] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:42:37.204] INFO: number of Vcal hits detected: 207983 207990 207987 207970 207971 207967 207971 207979 207978 207970 207968 207982 207982 207992 207997 207989
[10:42:37.204] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[10:42:37.204] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[10:42:37.204] INFO: X-ray hit rate [MHz/cm2]: 4.9 3.0 5.0 8.2 8.7 8.8 9.2 6.6 5.9 7.9 7.5 6.3 6.2 3.7 1.4 1.7
[10:42:37.204] INFO: PixTestHighRate::doXPixelAlive() done
[10:42:37.255] INFO: PixTest:: pg_setup set to default.
[10:42:37.266] INFO: enter test to run
[10:42:57.166] INFO: test: HighRate no parameter change
[10:42:57.166] INFO: running: highrate
[10:42:57.167] INFO: ----------------------------------------------------------------------
[10:42:57.167] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:42:57.167] INFO: ----------------------------------------------------------------------
[10:42:57.782] INFO: Expecting 208000 events.
[10:43:08.938] INFO: 208000 events read in total (10629ms).
[10:43:08.940] INFO: Test took 11765ms.
[10:43:09.018] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:09.248] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:43:09.248] INFO: number of red-efficiency pixels: 34 18 31 55 72 66 76 38 39 66 65 35 46 27 7 16
[10:43:09.248] INFO: number of X-ray hits detected: 34147 21310 34886 57197 61211 61738 64161 45724 41640 54485 53618 43833 43332 25793 9828 11931
[10:43:09.248] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:43:09.248] INFO: number of Vcal hits detected: 207965 207982 207969 207945 207927 207934 207924 207962 207961 207934 207934 207965 207954 207972 207993 207984
[10:43:09.249] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[10:43:09.249] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[10:43:09.249] INFO: X-ray hit rate [MHz/cm2]: 10.0 6.2 10.2 16.8 17.9 18.1 18.8 13.4 12.2 16.0 15.7 12.8 12.7 7.6 2.9 3.5
[10:43:09.249] INFO: PixTestHighRate::doXPixelAlive() done
[10:43:09.296] INFO: PixTest:: pg_setup set to default.
[10:43:09.313] INFO: enter test to run
[10:43:24.814] INFO: test: HighRate no parameter change
[10:43:24.814] INFO: running: highrate
[10:43:24.815] INFO: ----------------------------------------------------------------------
[10:43:24.815] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:43:24.815] INFO: ----------------------------------------------------------------------
[10:43:25.427] INFO: Expecting 208000 events.
[10:43:36.993] INFO: 208000 events read in total (11039ms).
[10:43:36.996] INFO: Test took 12171ms.
[10:43:37.118] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:37.362] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[10:43:37.362] INFO: number of red-efficiency pixels: 56 40 55 86 124 126 125 87 100 112 100 65 75 40 13 21
[10:43:37.362] INFO: number of X-ray hits detected: 53757 32828 54135 88547 93980 95814 98414 70612 64600 83899 81990 68313 68131 40277 15088 18589
[10:43:37.362] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:43:37.362] INFO: number of Vcal hits detected: 207943 207960 207945 207914 207875 207873 207870 207912 207894 207884 207897 207933 207924 207960 207987 207979
[10:43:37.362] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 99.9 99.9 99.9 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0
[10:43:37.362] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 100.0 99.9 99.9 99.9 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0
[10:43:37.362] INFO: X-ray hit rate [MHz/cm2]: 15.8 9.6 15.9 26.0 27.5 28.1 28.8 20.7 18.9 24.6 24.0 20.0 20.0 11.8 4.4 5.4
[10:43:37.362] INFO: PixTestHighRate::doXPixelAlive() done
[10:43:37.412] INFO: PixTest:: pg_setup set to default.
[10:43:37.423] INFO: enter test to run
[10:43:42.062] INFO: test: exit no parameter change
[10:43:42.437] QUIET: Connection to board 33 closed.
[10:43:42.450] INFO: pXar: this is the end, my friend