[16:52:55.959] INFO: *** Welcome to pxar ***
[16:52:55.959] INFO: *** Today: 2016/08/31
[16:52:56.190] INFO: *** Version: v1.9.0-818-g96727
[16:52:56.190] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//dacParameters35_C15.dat
[16:52:56.237] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//tbmParameters_C0b.dat
[16:52:56.237] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//defaultMaskFile.dat
[16:52:56.237] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C15.dat
[16:52:56.336] INFO: clk: 4
[16:52:56.336] INFO: ctr: 4
[16:52:56.336] INFO: sda: 19
[16:52:56.336] INFO: tin: 9
[16:52:56.336] INFO: level: 15
[16:52:56.336] INFO: triggerdelay: 0
[16:52:56.336] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[16:52:56.336] INFO: Log level: INFO
[16:52:56.352] QUIET: Connection to board DTB_WREK4U opened.
[16:52:56.355] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 32
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREK4U
MAC address: 40D855118020
Hostname: pixelDTB032
Comment:
------------------------------------------------------
[16:52:56.358] INFO: RPC call hashes of host and DTB match: 398089610
[16:52:57.892] INFO: DUT info:
[16:52:57.892] INFO: The DUT currently contains the following objects:
[16:52:57.892] INFO: 2 TBM Cores tbm08c (2 ON)
[16:52:57.892] INFO: TBM Core alpha (0): 7 registers set
[16:52:57.892] INFO: TBM Core beta (1): 7 registers set
[16:52:57.892] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:52:57.892] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:57.892] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:57.892] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:57.892] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:57.892] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:57.892] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:57.892] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:57.892] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:57.892] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:57.892] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:57.893] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:57.893] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:57.893] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:57.893] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:57.893] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:57.893] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:52:58.296] INFO: enter 'restricted' command line mode
[16:52:58.296] INFO: enter test to run
[16:53:13.578] INFO: test: PixelAlive no parameter change
[16:53:13.578] INFO: running: pixelalive
[16:53:13.588] INFO: ----------------------------------------------------------------------
[16:53:13.588] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:53:13.588] INFO: ----------------------------------------------------------------------
[16:53:13.909] INFO: Expecting 41600 events.
[16:53:18.292] INFO: 41600 events read in total (3665ms).
[16:53:18.461] INFO: Test took 4871ms.
[16:53:18.473] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:53:18.738] INFO: PixTestAlive::aliveTest() done
[16:53:18.738] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0
[16:53:18.774] INFO: enter test to run
[16:53:43.282] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[16:53:43.282] INFO: running: highrate
[16:53:43.282] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[16:53:43.493] INFO: ----------------------------------------------------------------------
[16:53:43.493] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[16:53:43.493] INFO: ----------------------------------------------------------------------
[16:53:43.493] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[16:53:43.494] INFO: edge/corner pixel THR is adjusted
[16:53:43.494] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:53:44.452] INFO: Collecting data for 5 seconds...
[16:53:49.472] INFO: Done with hot pixel readout
[16:54:01.282] INFO: PixTest:: pg_setup set to default.
[16:54:01.283] INFO: 11 hot pixels found in step 0
[16:54:02.298] INFO: Collecting data for 5 seconds...
[16:54:07.319] INFO: Done with hot pixel readout
[16:54:18.985] INFO: PixTest:: pg_setup set to default.
[16:54:18.986] INFO: 11 hot pixels found in step 1
[16:54:19.972] INFO: Collecting data for 5 seconds...
[16:54:24.992] INFO: Done with hot pixel readout
[16:54:36.565] INFO: PixTest:: pg_setup set to default.
[16:54:36.566] INFO: 8 hot pixels found in step 2
[16:54:37.551] INFO: Collecting data for 5 seconds...
[16:54:42.568] INFO: Done with hot pixel readout
[16:54:54.046] INFO: PixTest:: pg_setup set to default.
[16:54:54.047] INFO: 6 hot pixels found in step 3
[16:54:55.035] INFO: Collecting data for 5 seconds...
[16:55:00.051] INFO: Done with hot pixel readout
[16:55:11.571] INFO: PixTest:: pg_setup set to default.
[16:55:11.572] INFO: 6 hot pixels found in step 4
[16:55:12.559] INFO: Collecting data for 5 seconds...
[16:55:17.576] INFO: Done with hot pixel readout
[16:55:29.069] INFO: PixTest:: pg_setup set to default.
[16:55:29.069] INFO: 10 hot pixels found in step 5
[16:55:30.056] INFO: Collecting data for 5 seconds...
[16:55:35.073] INFO: Done with hot pixel readout
[16:55:46.550] INFO: PixTest:: pg_setup set to default.
[16:55:46.550] INFO: 3 hot pixels found in step 6
[16:55:47.538] INFO: Collecting data for 5 seconds...
[16:55:52.553] INFO: Done with hot pixel readout
[16:56:04.081] INFO: PixTest:: pg_setup set to default.
[16:56:04.082] INFO: 6 hot pixels found in step 7
[16:56:05.068] INFO: Collecting data for 5 seconds...
[16:56:10.084] INFO: Done with hot pixel readout
[16:56:21.624] INFO: PixTest:: pg_setup set to default.
[16:56:21.625] INFO: 2 hot pixels found in step 8
[16:56:22.612] INFO: Collecting data for 5 seconds...
[16:56:27.629] INFO: Done with hot pixel readout
[16:56:39.151] INFO: PixTest:: pg_setup set to default.
[16:56:39.151] INFO: 2 hot pixels found in step 9
[16:56:40.138] INFO: Collecting data for 5 seconds...
[16:56:45.154] INFO: Done with hot pixel readout
[16:56:56.713] INFO: PixTest:: pg_setup set to default.
[16:56:56.714] INFO: 5 hot pixels found in step 10
[16:56:57.701] INFO: Collecting data for 5 seconds...
[16:57:02.718] INFO: Done with hot pixel readout
[16:57:14.254] INFO: PixTest:: pg_setup set to default.
[16:57:14.254] INFO: 2 hot pixels found in step 11
[16:57:15.241] INFO: Collecting data for 5 seconds...
[16:57:20.258] INFO: Done with hot pixel readout
[16:57:31.728] INFO: PixTest:: pg_setup set to default.
[16:57:31.729] INFO: 2 hot pixels found in step 12
[16:57:32.715] INFO: Collecting data for 5 seconds...
[16:57:37.732] INFO: Done with hot pixel readout
[16:57:49.234] INFO: PixTest:: pg_setup set to default.
[16:57:49.235] INFO: 1 hot pixels found in step 13
[16:57:50.222] INFO: Collecting data for 5 seconds...
[16:57:55.238] INFO: Done with hot pixel readout
[16:58:06.757] INFO: PixTest:: pg_setup set to default.
[16:58:06.757] INFO: 1 hot pixels found in step 14
[16:58:06.788] INFO: 1 hot pixels could not be trimmed and have been masked.
[16:58:06.792] INFO: PixTest::trimHotPixels() done
[16:58:06.796] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C0.dat
[16:58:06.802] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C1.dat
[16:58:06.808] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C2.dat
[16:58:06.813] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C3.dat
[16:58:06.818] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C4.dat
[16:58:06.823] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C5.dat
[16:58:06.828] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C6.dat
[16:58:06.834] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C7.dat
[16:58:06.839] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C8.dat
[16:58:06.844] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C9.dat
[16:58:06.849] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C10.dat
[16:58:06.855] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C11.dat
[16:58:06.860] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C12.dat
[16:58:06.865] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C13.dat
[16:58:06.870] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C14.dat
[16:58:06.875] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C15.dat
[16:58:06.881] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//defaultMaskFile.dat
[16:58:06.891] INFO: enter test to run
[16:58:34.840] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[16:58:34.840] INFO: running: highrate
[16:58:34.845] INFO: ----------------------------------------------------------------------
[16:58:34.845] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[16:58:34.845] INFO: ----------------------------------------------------------------------
[16:58:34.845] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[16:58:34.845] INFO: edge/corner pixel THR is adjusted
[16:58:34.845] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:58:35.803] INFO: Collecting data for 1 seconds...
[16:58:36.807] INFO: Done with hot pixel readout
[16:58:40.804] INFO: PixTest:: pg_setup set to default.
[16:58:40.804] INFO: 0 hot pixels found in step 0
[16:58:40.810] INFO: 0 hot pixels could not be trimmed and have been masked.
[16:58:40.904] INFO: PixTest::trimHotPixels() done
[16:58:40.904] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C0.dat
[16:58:40.913] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C1.dat
[16:58:40.918] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C2.dat
[16:58:40.923] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C3.dat
[16:58:40.928] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C4.dat
[16:58:40.933] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C5.dat
[16:58:40.939] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C6.dat
[16:58:40.944] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C7.dat
[16:58:40.949] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C8.dat
[16:58:40.954] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C9.dat
[16:58:40.959] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C10.dat
[16:58:40.964] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C11.dat
[16:58:40.969] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C12.dat
[16:58:40.974] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C13.dat
[16:58:40.979] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C14.dat
[16:58:40.985] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//trimParameters35_C15.dat
[16:58:40.990] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-A-48_FPIXTest-17C-FNAL-160824-1359-150V_2016-08-24_13h59m_1472065161/000_FPIXTest_p17//defaultMaskFile.dat
[16:58:40.000] INFO: enter test to run
[16:59:41.368] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[16:59:41.368] INFO: running: xray
[16:59:41.369] INFO: ----------------------------------------------------------------------
[16:59:41.369] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:59:41.369] INFO: ----------------------------------------------------------------------
[16:59:42.332] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:59:53.845] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[17:00:23.076] INFO: Resuming triggers.
[17:00:34.588] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[17:01:03.412] INFO: Resuming triggers.
[17:01:14.932] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[17:01:44.256] INFO: Resuming triggers.
[17:01:55.771] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[17:02:25.042] INFO: Resuming triggers.
[17:02:36.557] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[17:03:06.041] INFO: Resuming triggers.
[17:03:17.557] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[17:03:47.169] INFO: Resuming triggers.
[17:03:58.687] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[17:04:28.368] INFO: Resuming triggers.
[17:04:39.894] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[17:05:09.019] INFO: Resuming triggers.
[17:05:17.230] INFO: data taking finished, elapsed time: 100 seconds.
[17:05:37.761] INFO: PixTest:: pg_setup set to default.
[17:05:37.764] INFO: PixTestXray::doPhRun() done
[17:05:37.958] INFO: enter test to run
[17:06:21.093] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[17:06:21.093] INFO: running: xray
[17:06:21.094] INFO: ----------------------------------------------------------------------
[17:06:21.094] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[17:06:21.094] INFO: ----------------------------------------------------------------------
[17:06:22.058] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[17:06:28.788] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[17:07:00.220] INFO: Resuming triggers.
[17:07:06.956] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[17:07:37.510] INFO: Resuming triggers.
[17:07:44.244] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[17:08:14.347] INFO: Resuming triggers.
[17:08:21.080] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[17:08:51.281] INFO: Resuming triggers.
[17:08:58.015] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[17:09:28.253] INFO: Resuming triggers.
[17:09:34.991] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[17:10:05.293] INFO: Resuming triggers.
[17:10:12.027] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[17:10:42.230] INFO: Resuming triggers.
[17:10:48.965] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[17:11:19.125] INFO: Resuming triggers.
[17:11:25.862] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[17:11:55.962] INFO: Resuming triggers.
[17:12:02.695] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[17:12:32.940] INFO: Resuming triggers.
[17:12:39.677] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[17:13:09.887] INFO: Resuming triggers.
[17:13:16.622] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[17:13:46.838] INFO: Resuming triggers.
[17:13:53.574] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[17:14:23.736] INFO: Resuming triggers.
[17:14:30.475] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[17:15:00.538] INFO: Resuming triggers.
[17:15:06.581] INFO: data taking finished, elapsed time: 100 seconds.
[17:15:34.466] INFO: PixTest:: pg_setup set to default.
[17:15:34.470] INFO: PixTestXray::doPhRun() done
[17:15:34.622] INFO: enter test to run
[17:15:42.641] INFO: test: HighRate no parameter change
[17:15:42.641] INFO: running: highrate
[17:15:42.657] INFO: ----------------------------------------------------------------------
[17:15:42.657] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:15:42.657] INFO: ----------------------------------------------------------------------
[17:15:42.811] INFO: Expecting 768 events.
[17:15:43.949] INFO: 768 events read in total (416ms).
[17:15:43.950] INFO: Test took 1273ms.
[17:15:44.753] INFO: Expecting 41600 events.
[17:15:47.863] INFO: 41600 events read in total (2583ms).
[17:15:47.864] INFO: Test took 3893ms.
[17:15:47.897] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:48.624] INFO: Expecting 41600 events.
[17:15:51.837] INFO: 41600 events read in total (2686ms).
[17:15:51.838] INFO: Test took 3924ms.
[17:15:51.871] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:52.594] INFO: Expecting 41600 events.
[17:15:55.816] INFO: 41600 events read in total (2696ms).
[17:15:55.817] INFO: Test took 3927ms.
[17:15:55.852] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:15:56.573] INFO: Expecting 41600 events.
[17:15:59.814] INFO: 41600 events read in total (2714ms).
[17:15:59.815] INFO: Test took 3946ms.
[17:15:59.849] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:00.571] INFO: Expecting 41600 events.
[17:16:03.823] INFO: 41600 events read in total (2725ms).
[17:16:03.824] INFO: Test took 3959ms.
[17:16:03.857] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:04.581] INFO: Expecting 41600 events.
[17:16:07.786] INFO: 41600 events read in total (2678ms).
[17:16:07.787] INFO: Test took 3912ms.
[17:16:07.821] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:08.541] INFO: Expecting 41600 events.
[17:16:11.759] INFO: 41600 events read in total (2691ms).
[17:16:11.760] INFO: Test took 3922ms.
[17:16:11.794] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:12.516] INFO: Expecting 41600 events.
[17:16:15.755] INFO: 41600 events read in total (2713ms).
[17:16:15.756] INFO: Test took 3945ms.
[17:16:15.789] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:16.511] INFO: Expecting 41600 events.
[17:16:19.799] INFO: 41600 events read in total (2761ms).
[17:16:19.800] INFO: Test took 3993ms.
[17:16:19.833] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:20.555] INFO: Expecting 41600 events.
[17:16:23.785] INFO: 41600 events read in total (2703ms).
[17:16:23.786] INFO: Test took 3934ms.
[17:16:23.820] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:24.544] INFO: Expecting 41600 events.
[17:16:27.765] INFO: 41600 events read in total (2694ms).
[17:16:27.765] INFO: Test took 3928ms.
[17:16:27.800] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:28.521] INFO: Expecting 41600 events.
[17:16:31.797] INFO: 41600 events read in total (2749ms).
[17:16:31.798] INFO: Test took 3981ms.
[17:16:31.835] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:32.543] INFO: Expecting 41600 events.
[17:16:35.822] INFO: 41600 events read in total (2753ms).
[17:16:35.823] INFO: Test took 3969ms.
[17:16:35.857] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:36.577] INFO: Expecting 41600 events.
[17:16:39.791] INFO: 41600 events read in total (2687ms).
[17:16:39.792] INFO: Test took 3917ms.
[17:16:39.826] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:40.543] INFO: Expecting 41600 events.
[17:16:43.770] INFO: 41600 events read in total (2700ms).
[17:16:43.771] INFO: Test took 3928ms.
[17:16:43.804] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:44.521] INFO: Expecting 41600 events.
[17:16:47.775] INFO: 41600 events read in total (2727ms).
[17:16:47.776] INFO: Test took 3955ms.
[17:16:47.811] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:48.536] INFO: Expecting 41600 events.
[17:16:51.770] INFO: 41600 events read in total (2707ms).
[17:16:51.771] INFO: Test took 3943ms.
[17:16:51.805] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:52.529] INFO: Expecting 41600 events.
[17:16:55.764] INFO: 41600 events read in total (2708ms).
[17:16:55.765] INFO: Test took 3942ms.
[17:16:55.798] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:16:56.523] INFO: Expecting 41600 events.
[17:16:59.810] INFO: 41600 events read in total (2760ms).
[17:16:59.811] INFO: Test took 3995ms.
[17:16:59.844] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:00.570] INFO: Expecting 41600 events.
[17:17:03.624] INFO: 41600 events read in total (2527ms).
[17:17:03.625] INFO: Test took 3763ms.
[17:17:03.658] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:04.027] INFO: enter test to run
[17:17:16.249] INFO: test: HighRate no parameter change
[17:17:16.249] INFO: running: highrate
[17:17:16.250] INFO: ----------------------------------------------------------------------
[17:17:16.250] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:17:16.250] INFO: ----------------------------------------------------------------------
[17:17:16.870] INFO: Expecting 208000 events.
[17:17:28.758] INFO: 208000 events read in total (11361ms).
[17:17:28.762] INFO: Test took 12503ms.
[17:17:28.903] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:17:29.155] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0
[17:17:29.155] INFO: number of red-efficiency pixels: 92 55 96 115 113 126 125 102 82 100 118 117 110 63 37 47
[17:17:29.155] INFO: number of X-ray hits detected: 67553 42728 68603 102803 107456 109836 110725 75956 63751 92162 91077 79038 81396 51356 21584 26430
[17:17:29.155] 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:17:29.155] INFO: number of Vcal hits detected: 207904 207944 207903 207834 207885 207872 207875 207897 207918 207898 207876 207883 207887 207935 207963 207953
[17:17:29.155] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 100.0 99.9 99.9 100.0 100.0 100.0 99.9 99.9 99.9 100.0 100.0 100.0
[17:17:29.155] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 99.9 99.9 99.9 100.0 100.0 100.0
[17:17:29.155] INFO: X-ray hit rate [MHz/cm2]: 19.8 12.5 20.1 30.1 31.5 32.2 32.5 22.3 18.7 27.0 26.7 23.2 23.9 15.1 6.3 7.7
[17:17:29.155] INFO: PixTestHighRate::doXPixelAlive() done
[17:17:29.201] INFO: PixTest:: pg_setup set to default.
[17:17:29.213] INFO: enter test to run
[17:17:45.664] INFO: test: HighRate no parameter change
[17:17:45.664] INFO: running: highrate
[17:17:45.665] INFO: ----------------------------------------------------------------------
[17:17:45.666] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:17:45.666] INFO: ----------------------------------------------------------------------
[17:17:46.284] INFO: Expecting 208000 events.
[17:17:59.830] INFO: 208000 events read in total (13019ms).
[17:17:59.839] INFO: Test took 14165ms.
[17:18:00.142] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:18:00.444] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0
[17:18:00.444] INFO: number of red-efficiency pixels: 253 133 350 349 399 524 399 382 263 349 387 312 300 196 64 70
[17:18:00.444] INFO: number of X-ray hits detected: 139043 88195 143425 213409 222145 226656 229698 158141 132103 190118 189039 162950 167472 106366 44521 54943
[17:18:00.444] 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:18:00.444] INFO: number of Vcal hits detected: 207724 207861 207605 207589 207562 207416 207576 207591 207729 207631 207584 207666 207682 207790 207934 207928
[17:18:00.444] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.7 99.8 99.8 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[17:18:00.444] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.7 99.8 99.8 99.9 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[17:18:00.445] INFO: X-ray hit rate [MHz/cm2]: 40.8 25.9 42.0 62.6 65.1 66.4 67.3 46.4 38.7 55.7 55.4 47.8 49.1 31.2 13.0 16.1
[17:18:00.445] INFO: PixTestHighRate::doXPixelAlive() done
[17:18:00.491] INFO: PixTest:: pg_setup set to default.
[17:18:00.508] INFO: enter test to run
[17:18:14.000] INFO: test: HighRate no parameter change
[17:18:14.000] INFO: running: highrate
[17:18:14.001] INFO: ----------------------------------------------------------------------
[17:18:14.002] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:18:14.002] INFO: ----------------------------------------------------------------------
[17:18:15.622] INFO: Expecting 208000 events.
[17:18:31.567] INFO: 208000 events read in total (15418ms).
[17:18:31.574] INFO: Test took 16563ms.
[17:18:32.025] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:18:32.379] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0
[17:18:32.379] INFO: number of red-efficiency pixels: 610 314 824 927 1068 1384 968 809 498 827 950 768 753 494 111 128
[17:18:32.379] INFO: number of X-ray hits detected: 216243 139059 223426 331523 347316 351301 356678 245528 206774 296556 294115 253795 262277 166017 69670 86374
[17:18:32.379] 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:18:32.379] INFO: number of Vcal hits detected: 207215 207645 206851 206828 206696 206123 206811 206949 207447 207021 206851 207100 207148 207406 207884 207868
[17:18:32.379] INFO: Vcal hit fiducial efficiency (%): 99.7 99.8 99.5 99.5 99.5 99.2 99.5 99.6 99.8 99.6 99.5 99.6 99.6 99.7 99.9 99.9
[17:18:32.379] INFO: Vcal hit overall efficiency (%): 99.6 99.8 99.4 99.4 99.4 99.1 99.4 99.5 99.7 99.5 99.4 99.6 99.6 99.7 99.9 99.9
[17:18:32.379] INFO: X-ray hit rate [MHz/cm2]: 63.4 40.8 65.5 97.2 101.8 103.0 104.5 72.0 60.6 86.9 86.2 74.4 76.9 48.7 20.4 25.3
[17:18:32.379] INFO: PixTestHighRate::doXPixelAlive() done
[17:18:32.425] INFO: PixTest:: pg_setup set to default.
[17:18:32.442] INFO: enter test to run
[17:18:54.152] INFO: test: exit no parameter change
[17:18:54.654] QUIET: Connection to board 32 closed.
[17:18:54.686] INFO: pXar: this is the end, my friend