[13:26:52.123] INFO: *** Welcome to pxar ***
[13:26:52.123] INFO: *** Today: 2016/06/20
[13:26:52.138] INFO: *** Version: v1.9.0-814-g7497
[13:26:52.138] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//dacParameters35_C15.dat
[13:26:52.140] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:26:52.140] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//defaultMaskFile.dat
[13:26:52.140] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C15.dat
[13:26:52.214] INFO: clk: 4
[13:26:52.214] INFO: ctr: 4
[13:26:52.214] INFO: sda: 19
[13:26:52.214] INFO: tin: 9
[13:26:52.214] INFO: level: 15
[13:26:52.214] INFO: triggerdelay: 0
[13:26:52.214] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[13:26:52.214] INFO: Log level: INFO
[13:26:52.232] QUIET: Connection to board DTB_WREKRL opened.
[13:26:52.235] 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:
------------------------------------------------------
[13:26:52.238] INFO: RPC call hashes of host and DTB match: 398089610
[13:26:53.780] INFO: DUT info:
[13:26:53.780] INFO: The DUT currently contains the following objects:
[13:26:53.780] INFO: 2 TBM Cores tbm08c (2 ON)
[13:26:53.780] INFO: TBM Core alpha (0): 7 registers set
[13:26:53.780] INFO: TBM Core beta (1): 7 registers set
[13:26:53.780] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:26:53.780] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:53.780] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:53.780] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:53.780] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:53.781] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:53.781] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:53.781] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:53.781] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:53.781] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:53.781] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:53.781] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:53.781] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:53.781] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:53.781] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:53.781] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:53.781] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:26:54.270] INFO: enter 'restricted' command line mode
[13:26:54.270] INFO: enter test to run
[13:27:04.591] INFO: test: PixelAlive no parameter change
[13:27:04.591] INFO: running: pixelalive
[13:27:04.665] INFO: ----------------------------------------------------------------------
[13:27:04.665] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:27:04.665] INFO: ----------------------------------------------------------------------
[13:27:04.984] INFO: Expecting 41600 events.
[13:27:09.339] INFO: 41600 events read in total (3637ms).
[13:27:09.508] INFO: Test took 4840ms.
[13:27:09.520] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:27:10.036] INFO: PixTestAlive::aliveTest() done
[13:27:10.036] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:27:10.197] INFO: enter test to run
[13:28:35.158] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:28:35.158] INFO: running: highrate
[13:28:35.175] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:28:35.443] INFO: ----------------------------------------------------------------------
[13:28:35.443] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:28:35.443] INFO: ----------------------------------------------------------------------
[13:28:35.443] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:28:35.443] INFO: edge/corner pixel THR is adjusted
[13:28:35.443] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:28:36.403] INFO: Collecting data for 5 seconds...
[13:28:41.418] INFO: Done with hot pixel readout
[13:28:52.977] INFO: PixTest:: pg_setup set to default.
[13:28:52.978] INFO: 9 hot pixels found in step 0
[13:28:53.990] INFO: Collecting data for 5 seconds...
[13:28:59.006] INFO: Done with hot pixel readout
[13:29:10.582] INFO: PixTest:: pg_setup set to default.
[13:29:10.583] INFO: 7 hot pixels found in step 1
[13:29:11.570] INFO: Collecting data for 5 seconds...
[13:29:16.585] INFO: Done with hot pixel readout
[13:29:28.141] INFO: PixTest:: pg_setup set to default.
[13:29:28.142] INFO: 4 hot pixels found in step 2
[13:29:29.128] INFO: Collecting data for 5 seconds...
[13:29:34.143] INFO: Done with hot pixel readout
[13:29:45.660] INFO: PixTest:: pg_setup set to default.
[13:29:45.661] INFO: 7 hot pixels found in step 3
[13:29:46.647] INFO: Collecting data for 5 seconds...
[13:29:51.662] INFO: Done with hot pixel readout
[13:30:03.135] INFO: PixTest:: pg_setup set to default.
[13:30:03.135] INFO: 7 hot pixels found in step 4
[13:30:04.121] INFO: Collecting data for 5 seconds...
[13:30:09.137] INFO: Done with hot pixel readout
[13:30:20.652] INFO: PixTest:: pg_setup set to default.
[13:30:20.653] INFO: 4 hot pixels found in step 5
[13:30:21.639] INFO: Collecting data for 5 seconds...
[13:30:26.654] INFO: Done with hot pixel readout
[13:30:38.125] INFO: PixTest:: pg_setup set to default.
[13:30:38.126] INFO: 1 hot pixels found in step 6
[13:30:39.112] INFO: Collecting data for 5 seconds...
[13:30:44.128] INFO: Done with hot pixel readout
[13:30:55.720] INFO: PixTest:: pg_setup set to default.
[13:30:55.721] INFO: 6 hot pixels found in step 7
[13:30:56.708] INFO: Collecting data for 5 seconds...
[13:31:01.724] INFO: Done with hot pixel readout
[13:31:13.218] INFO: PixTest:: pg_setup set to default.
[13:31:13.219] INFO: 3 hot pixels found in step 8
[13:31:14.206] INFO: Collecting data for 5 seconds...
[13:31:19.221] INFO: Done with hot pixel readout
[13:31:30.767] INFO: PixTest:: pg_setup set to default.
[13:31:30.768] INFO: 1 hot pixels found in step 9
[13:31:31.755] INFO: Collecting data for 5 seconds...
[13:31:36.770] INFO: Done with hot pixel readout
[13:31:48.304] INFO: PixTest:: pg_setup set to default.
[13:31:48.305] INFO: 1 hot pixels found in step 10
[13:31:49.291] INFO: Collecting data for 5 seconds...
[13:31:54.306] INFO: Done with hot pixel readout
[13:32:05.833] INFO: PixTest:: pg_setup set to default.
[13:32:05.834] INFO: 2 hot pixels found in step 11
[13:32:06.820] INFO: Collecting data for 5 seconds...
[13:32:11.836] INFO: Done with hot pixel readout
[13:32:23.380] INFO: PixTest:: pg_setup set to default.
[13:32:23.381] INFO: 4 hot pixels found in step 12
[13:32:24.369] INFO: Collecting data for 5 seconds...
[13:32:29.385] INFO: Done with hot pixel readout
[13:32:40.885] INFO: PixTest:: pg_setup set to default.
[13:32:40.886] INFO: 1 hot pixels found in step 13
[13:32:41.873] INFO: Collecting data for 5 seconds...
[13:32:46.889] INFO: Done with hot pixel readout
[13:32:58.452] INFO: PixTest:: pg_setup set to default.
[13:32:58.453] INFO: 3 hot pixels found in step 14
[13:32:58.483] INFO: 3 hot pixels could not be trimmed and have been masked.
[13:32:58.514] INFO: PixTest::trimHotPixels() done
[13:32:58.518] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C0.dat
[13:32:58.528] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C1.dat
[13:32:58.533] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C2.dat
[13:32:58.538] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C3.dat
[13:32:58.544] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C4.dat
[13:32:58.549] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C5.dat
[13:32:58.555] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C6.dat
[13:32:58.560] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C7.dat
[13:32:58.566] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C8.dat
[13:32:58.571] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C9.dat
[13:32:58.577] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C10.dat
[13:32:58.582] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C11.dat
[13:32:58.587] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C12.dat
[13:32:58.593] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C13.dat
[13:32:58.598] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C14.dat
[13:32:58.604] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C15.dat
[13:32:58.609] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//defaultMaskFile.dat
[13:32:58.619] INFO: enter test to run
[13:33:42.180] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:33:42.180] INFO: running: highrate
[13:33:42.185] INFO: ----------------------------------------------------------------------
[13:33:42.185] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:33:42.185] INFO: ----------------------------------------------------------------------
[13:33:42.185] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:33:42.185] INFO: edge/corner pixel THR is adjusted
[13:33:42.185] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:33:43.147] INFO: Collecting data for 1 seconds...
[13:33:44.150] INFO: Done with hot pixel readout
[13:33:48.072] INFO: PixTest:: pg_setup set to default.
[13:33:48.072] INFO: 0 hot pixels found in step 0
[13:33:48.078] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:33:48.169] INFO: PixTest::trimHotPixels() done
[13:33:48.169] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C0.dat
[13:33:48.181] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C1.dat
[13:33:48.187] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C2.dat
[13:33:48.192] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C3.dat
[13:33:48.198] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C4.dat
[13:33:48.204] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C5.dat
[13:33:48.209] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C6.dat
[13:33:48.214] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C7.dat
[13:33:48.220] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C8.dat
[13:33:48.225] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C9.dat
[13:33:48.231] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C10.dat
[13:33:48.237] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C11.dat
[13:33:48.242] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C12.dat
[13:33:48.247] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C13.dat
[13:33:48.252] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C14.dat
[13:33:48.258] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//trimParameters35_C15.dat
[13:33:48.264] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-1-21_FPIXTest-17C-FNAL-160610-1511_2016-06-10_15h11m_1465589481/000_FPIXTest_p17//defaultMaskFile.dat
[13:33:48.273] INFO: enter test to run
[13:34:04.300] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:34:04.300] INFO: running: xray
[13:34:04.301] INFO: ----------------------------------------------------------------------
[13:34:04.301] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:34:04.301] INFO: ----------------------------------------------------------------------
[13:34:05.282] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:34:16.875] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:34:44.775] INFO: Resuming triggers.
[13:34:56.369] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[13:35:26.256] INFO: Resuming triggers.
[13:35:37.854] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[13:36:06.880] INFO: Resuming triggers.
[13:36:18.475] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[13:36:48.372] INFO: Resuming triggers.
[13:36:59.965] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[13:37:29.837] INFO: Resuming triggers.
[13:37:41.432] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[13:38:10.791] INFO: Resuming triggers.
[13:38:22.384] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[13:38:52.374] INFO: Resuming triggers.
[13:39:03.964] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[13:39:33.460] INFO: Resuming triggers.
[13:39:41.040] INFO: data taking finished, elapsed time: 100 seconds.
[13:40:00.642] INFO: PixTest:: pg_setup set to default.
[13:40:00.645] INFO: PixTestXray::doPhRun() done
[13:40:00.785] INFO: enter test to run
[13:41:13.201] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:41:13.201] INFO: running: xray
[13:41:13.202] INFO: ----------------------------------------------------------------------
[13:41:13.202] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:41:13.202] INFO: ----------------------------------------------------------------------
[13:41:14.170] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:41:21.113] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[13:41:51.640] INFO: Resuming triggers.
[13:41:58.583] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[13:42:29.063] INFO: Resuming triggers.
[13:42:36.008] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[13:43:06.486] INFO: Resuming triggers.
[13:43:13.433] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[13:43:43.900] INFO: Resuming triggers.
[13:43:50.848] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[13:44:21.319] INFO: Resuming triggers.
[13:44:28.270] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[13:44:58.605] INFO: Resuming triggers.
[13:45:05.556] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[13:45:36.039] INFO: Resuming triggers.
[13:45:42.989] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[13:46:14.209] INFO: Resuming triggers.
[13:46:21.160] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[13:46:52.182] INFO: Resuming triggers.
[13:46:59.133] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[13:47:30.199] INFO: Resuming triggers.
[13:47:37.149] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[13:48:08.617] INFO: Resuming triggers.
[13:48:15.565] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[13:48:47.273] INFO: Resuming triggers.
[13:48:54.221] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[13:49:25.025] INFO: Resuming triggers.
[13:49:31.971] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[13:50:02.486] INFO: Resuming triggers.
[13:50:05.541] INFO: data taking finished, elapsed time: 100 seconds.
[13:50:19.195] INFO: PixTest:: pg_setup set to default.
[13:50:19.198] INFO: PixTestXray::doPhRun() done
[13:50:19.348] INFO: enter test to run
[13:50:51.309] INFO: test: HighRate no parameter change
[13:50:51.309] INFO: running: highrate
[13:50:51.310] INFO: ----------------------------------------------------------------------
[13:50:51.310] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:50:51.310] INFO: ----------------------------------------------------------------------
[13:50:51.452] INFO: Expecting 768 events.
[13:50:52.586] INFO: 768 events read in total (418ms).
[13:50:52.586] INFO: Test took 1268ms.
[13:50:53.390] INFO: Expecting 41600 events.
[13:50:56.478] INFO: 41600 events read in total (2562ms).
[13:50:56.479] INFO: Test took 3885ms.
[13:50:56.511] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:57.241] INFO: Expecting 41600 events.
[13:51:00.416] INFO: 41600 events read in total (2648ms).
[13:51:00.417] INFO: Test took 3888ms.
[13:51:00.450] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:01.177] INFO: Expecting 41600 events.
[13:51:04.390] INFO: 41600 events read in total (2687ms).
[13:51:04.391] INFO: Test took 3923ms.
[13:51:04.425] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:05.151] INFO: Expecting 41600 events.
[13:51:08.390] INFO: 41600 events read in total (2712ms).
[13:51:08.391] INFO: Test took 3948ms.
[13:51:08.424] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:09.151] INFO: Expecting 41600 events.
[13:51:12.349] INFO: 41600 events read in total (2671ms).
[13:51:12.350] INFO: Test took 3910ms.
[13:51:12.382] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:13.111] INFO: Expecting 41600 events.
[13:51:16.342] INFO: 41600 events read in total (2704ms).
[13:51:16.343] INFO: Test took 3943ms.
[13:51:16.376] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:17.100] INFO: Expecting 41600 events.
[13:51:20.326] INFO: 41600 events read in total (2700ms).
[13:51:20.327] INFO: Test took 3935ms.
[13:51:20.360] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:21.085] INFO: Expecting 41600 events.
[13:51:24.312] INFO: 41600 events read in total (2700ms).
[13:51:24.313] INFO: Test took 3936ms.
[13:51:24.346] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:25.073] INFO: Expecting 41600 events.
[13:51:28.292] INFO: 41600 events read in total (2692ms).
[13:51:28.293] INFO: Test took 3931ms.
[13:51:28.325] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:29.049] INFO: Expecting 41600 events.
[13:51:32.279] INFO: 41600 events read in total (2703ms).
[13:51:32.280] INFO: Test took 3937ms.
[13:51:32.313] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:33.041] INFO: Expecting 41600 events.
[13:51:36.272] INFO: 41600 events read in total (2704ms).
[13:51:36.273] INFO: Test took 3942ms.
[13:51:36.306] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:37.031] INFO: Expecting 41600 events.
[13:51:40.259] INFO: 41600 events read in total (2701ms).
[13:51:40.260] INFO: Test took 3936ms.
[13:51:40.293] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:41.020] INFO: Expecting 41600 events.
[13:51:44.252] INFO: 41600 events read in total (2706ms).
[13:51:44.253] INFO: Test took 3942ms.
[13:51:44.286] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:45.010] INFO: Expecting 41600 events.
[13:51:48.241] INFO: 41600 events read in total (2704ms).
[13:51:48.242] INFO: Test took 3939ms.
[13:51:48.275] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:49.002] INFO: Expecting 41600 events.
[13:51:52.221] INFO: 41600 events read in total (2692ms).
[13:51:52.222] INFO: Test took 3929ms.
[13:51:52.255] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:52.980] INFO: Expecting 41600 events.
[13:51:56.194] INFO: 41600 events read in total (2688ms).
[13:51:56.195] INFO: Test took 3921ms.
[13:51:56.229] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:51:56.954] INFO: Expecting 41600 events.
[13:52:00.189] INFO: 41600 events read in total (2708ms).
[13:52:00.190] INFO: Test took 3943ms.
[13:52:00.223] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:00.947] INFO: Expecting 41600 events.
[13:52:04.162] INFO: 41600 events read in total (2688ms).
[13:52:04.163] INFO: Test took 3921ms.
[13:52:04.195] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:04.921] INFO: Expecting 41600 events.
[13:52:08.088] INFO: 41600 events read in total (2641ms).
[13:52:08.089] INFO: Test took 3875ms.
[13:52:08.122] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:08.851] INFO: Expecting 41600 events.
[13:52:11.913] INFO: 41600 events read in total (2535ms).
[13:52:11.914] INFO: Test took 3773ms.
[13:52:11.946] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:52:12.313] INFO: enter test to run
[13:54:43.884] INFO: test: HighRate no parameter change
[13:54:43.884] INFO: running: highrate
[13:54:43.885] INFO: ----------------------------------------------------------------------
[13:54:43.885] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:54:43.885] INFO: ----------------------------------------------------------------------
[13:54:44.501] INFO: Expecting 208000 events.
[13:54:56.297] INFO: 208000 events read in total (11269ms).
[13:54:56.300] INFO: Test took 12406ms.
[13:54:56.439] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:54:56.690] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:54:56.690] INFO: number of red-efficiency pixels: 59 58 60 113 130 108 142 76 89 139 121 90 92 50 21 99
[13:54:56.690] INFO: number of X-ray hits detected: 61317 38412 63138 96684 102558 106300 108027 76895 78068 98696 96251 82627 88475 52571 18664 22285
[13:54:56.690] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:54:56.690] INFO: number of Vcal hits detected: 207940 207940 207939 207884 207868 207890 207854 207924 207911 207860 207875 207908 207907 207949 207979 207876
[13:54:56.690] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 99.9
[13:54:56.690] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 99.9
[13:54:56.690] INFO: X-ray hit rate [MHz/cm2]: 18.0 11.3 18.5 28.3 30.1 31.2 31.7 22.5 22.9 28.9 28.2 24.2 25.9 15.4 5.5 6.5
[13:54:56.690] INFO: PixTestHighRate::doXPixelAlive() done
[13:54:56.737] INFO: PixTest:: pg_setup set to default.
[13:54:56.752] INFO: enter test to run
[13:55:18.219] INFO: test: HighRate no parameter change
[13:55:18.219] INFO: running: highrate
[13:55:18.220] INFO: ----------------------------------------------------------------------
[13:55:18.221] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:55:18.221] INFO: ----------------------------------------------------------------------
[13:55:18.835] INFO: Expecting 208000 events.
[13:55:32.230] INFO: 208000 events read in total (12868ms).
[13:55:32.235] INFO: Test took 14005ms.
[13:55:32.518] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:55:32.813] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:55:32.813] INFO: number of red-efficiency pixels: 185 134 229 279 340 352 484 218 213 408 275 232 241 201 35 191
[13:55:32.813] INFO: number of X-ray hits detected: 125869 79590 128954 197311 208779 215379 220039 157979 160079 200783 196611 168876 180493 107556 38702 45991
[13:55:32.813] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:55:32.813] INFO: number of Vcal hits detected: 207803 207860 207760 207704 207633 207624 207472 207768 207775 207560 207709 207756 207745 207778 207965 207584
[13:55:32.813] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.9 99.8 99.8 99.8 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0 99.8
[13:55:32.813] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.9 99.8 99.8 99.7 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0 99.8
[13:55:32.813] INFO: X-ray hit rate [MHz/cm2]: 36.9 23.3 37.8 57.8 61.2 63.1 64.5 46.3 46.9 58.9 57.6 49.5 52.9 31.5 11.3 13.5
[13:55:32.813] INFO: PixTestHighRate::doXPixelAlive() done
[13:55:32.858] INFO: PixTest:: pg_setup set to default.
[13:55:32.871] INFO: enter test to run
[13:56:01.267] INFO: test: HighRate no parameter change
[13:56:01.267] INFO: running: highrate
[13:56:01.268] INFO: ----------------------------------------------------------------------
[13:56:01.268] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:56:01.268] INFO: ----------------------------------------------------------------------
[13:56:01.888] INFO: Expecting 208000 events.
[13:56:17.065] INFO: 208000 events read in total (14650ms).
[13:56:17.072] INFO: Test took 15793ms.
[13:56:17.499] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:56:17.841] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[13:56:17.841] INFO: number of red-efficiency pixels: 419 264 589 688 733 768 1158 471 456 954 675 547 551 431 73 227
[13:56:17.841] INFO: number of X-ray hits detected: 190270 119430 194573 299537 315595 326219 331229 238709 242195 304406 297115 255342 270935 162085 58580 70158
[13:56:17.841] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:56:17.841] INFO: number of Vcal hits detected: 207501 207700 207241 207219 207148 207087 206497 207461 207485 206842 207201 207371 207379 207488 207923 207129
[13:56:17.841] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.7 99.6 99.6 99.4 99.8 99.8 99.5 99.7 99.7 99.7 99.8 100.0 99.6
[13:56:17.841] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.6 99.6 99.6 99.6 99.3 99.7 99.8 99.4 99.6 99.7 99.7 99.8 100.0 99.6
[13:56:17.841] INFO: X-ray hit rate [MHz/cm2]: 55.8 35.0 57.0 87.8 92.5 95.6 97.1 70.0 71.0 89.2 87.1 74.8 79.4 47.5 17.2 20.6
[13:56:17.841] INFO: PixTestHighRate::doXPixelAlive() done
[13:56:17.890] INFO: PixTest:: pg_setup set to default.
[13:56:17.909] INFO: enter test to run
[13:56:32.843] INFO: test: exit no parameter change
[13:56:33.177] QUIET: Connection to board 33 closed.
[13:56:33.178] INFO: pXar: this is the end, my friend