[10:07:42.796] INFO: *** Welcome to pxar ***
[10:07:42.796] INFO: *** Today: 2016/09/26
[10:07:44.020] INFO: *** Version: v1.9.0-818-g96727
[10:07:44.020] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//dacParameters35_C15.dat
[10:07:44.136] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:07:44.137] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//defaultMaskFile.dat
[10:07:44.153] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C15.dat
[10:07:44.283] INFO: clk: 4
[10:07:44.283] INFO: ctr: 4
[10:07:44.283] INFO: sda: 19
[10:07:44.283] INFO: tin: 9
[10:07:44.283] INFO: level: 15
[10:07:44.283] INFO: triggerdelay: 0
[10:07:44.283] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[10:07:44.283] INFO: Log level: INFO
[10:07:44.302] QUIET: Connection to board DTB_WREKRL opened.
[10:07:44.305] 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:07:44.308] INFO: RPC call hashes of host and DTB match: 398089610
[10:07:45.862] INFO: DUT info:
[10:07:45.863] INFO: The DUT currently contains the following objects:
[10:07:45.863] INFO: 2 TBM Cores tbm08c (2 ON)
[10:07:45.863] INFO: TBM Core alpha (0): 7 registers set
[10:07:45.863] INFO: TBM Core beta (1): 7 registers set
[10:07:45.863] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:07:45.863] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.863] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.863] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.863] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.863] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.863] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.863] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.863] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.863] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.863] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.863] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.863] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.863] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.863] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.863] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:45.863] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:07:46.295] INFO: enter 'restricted' command line mode
[10:07:46.295] INFO: enter test to run
[10:07:54.063] INFO: test: PixelAlive no parameter change
[10:07:54.063] INFO: running: pixelalive
[10:07:54.131] INFO: ----------------------------------------------------------------------
[10:07:54.131] 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:07:54.131] INFO: ----------------------------------------------------------------------
[10:07:54.451] INFO: Expecting 41600 events.
[10:07:58.805] INFO: 41600 events read in total (3635ms).
[10:07:58.975] INFO: Test took 4840ms.
[10:07:58.985] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:07:59.504] INFO: PixTestAlive::aliveTest() done
[10:07:59.504] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 0 7 0 0
[10:07:59.671] INFO: enter test to run
[10:08:26.399] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:08:26.399] INFO: running: highrate
[10:08:26.419] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:08:26.651] INFO: ----------------------------------------------------------------------
[10:08:26.651] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:08:26.651] INFO: ----------------------------------------------------------------------
[10:08:26.651] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:08:26.651] INFO: edge/corner pixel THR is adjusted
[10:08:26.652] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:08:27.611] INFO: Collecting data for 5 seconds...
[10:08:32.629] INFO: Done with hot pixel readout
[10:08:44.009] INFO: PixTest:: pg_setup set to default.
[10:08:44.010] INFO: 0 hot pixels found in step 0
[10:08:44.023] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:08:44.043] INFO: PixTest::trimHotPixels() done
[10:08:44.085] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C0.dat
[10:08:44.091] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C1.dat
[10:08:44.096] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C2.dat
[10:08:44.101] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C3.dat
[10:08:44.107] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C4.dat
[10:08:44.112] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C5.dat
[10:08:44.117] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C6.dat
[10:08:44.123] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C7.dat
[10:08:44.128] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C8.dat
[10:08:44.134] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C9.dat
[10:08:44.139] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C10.dat
[10:08:44.144] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C11.dat
[10:08:44.150] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C12.dat
[10:08:44.155] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C13.dat
[10:08:44.160] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C14.dat
[10:08:44.166] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C15.dat
[10:08:44.193] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//defaultMaskFile.dat
[10:08:44.204] INFO: enter test to run
[10:09:38.390] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:09:38.390] INFO: running: highrate
[10:09:38.395] INFO: ----------------------------------------------------------------------
[10:09:38.395] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:09:38.395] INFO: ----------------------------------------------------------------------
[10:09:38.395] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:09:38.395] INFO: edge/corner pixel THR is adjusted
[10:09:38.395] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:09:39.352] INFO: Collecting data for 1 seconds...
[10:09:40.356] INFO: Done with hot pixel readout
[10:09:44.129] INFO: PixTest:: pg_setup set to default.
[10:09:44.130] INFO: 2 hot pixels found in step 0
[10:09:45.192] INFO: Collecting data for 1 seconds...
[10:09:46.195] INFO: Done with hot pixel readout
[10:09:49.986] INFO: PixTest:: pg_setup set to default.
[10:09:49.987] INFO: 3 hot pixels found in step 1
[10:09:51.049] INFO: Collecting data for 1 seconds...
[10:09:52.053] INFO: Done with hot pixel readout
[10:09:55.857] INFO: PixTest:: pg_setup set to default.
[10:09:55.858] INFO: 3 hot pixels found in step 2
[10:09:56.911] INFO: Collecting data for 1 seconds...
[10:09:57.915] INFO: Done with hot pixel readout
[10:10:01.731] INFO: PixTest:: pg_setup set to default.
[10:10:01.732] INFO: 1 hot pixels found in step 3
[10:10:02.788] INFO: Collecting data for 1 seconds...
[10:10:03.792] INFO: Done with hot pixel readout
[10:10:07.508] INFO: PixTest:: pg_setup set to default.
[10:10:07.508] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:10:07.508] INFO: 2 hot pixels found in step 4
[10:10:08.566] INFO: Collecting data for 1 seconds...
[10:10:09.571] INFO: Done with hot pixel readout
[10:10:13.380] INFO: PixTest:: pg_setup set to default.
[10:10:13.381] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:10:13.381] INFO: 3 hot pixels found in step 5
[10:10:14.431] INFO: Collecting data for 1 seconds...
[10:10:15.435] INFO: Done with hot pixel readout
[10:10:19.118] INFO: PixTest:: pg_setup set to default.
[10:10:19.119] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:10:19.119] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:10:19.119] INFO: 3 hot pixels found in step 6
[10:10:20.175] INFO: Collecting data for 1 seconds...
[10:10:21.179] INFO: Done with hot pixel readout
[10:10:24.856] INFO: PixTest:: pg_setup set to default.
[10:10:24.857] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:10:24.857] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:10:24.857] INFO: 2 hot pixels found in step 7
[10:10:24.863] INFO: 2 hot pixels could not be trimmed and have been masked.
[10:10:24.964] INFO: PixTest::trimHotPixels() done
[10:10:24.964] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C0.dat
[10:10:24.973] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C1.dat
[10:10:24.983] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C2.dat
[10:10:24.990] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C3.dat
[10:10:24.996] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C4.dat
[10:10:24.001] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C5.dat
[10:10:25.007] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C6.dat
[10:10:25.013] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C7.dat
[10:10:25.018] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C8.dat
[10:10:25.023] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C9.dat
[10:10:25.029] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C10.dat
[10:10:25.035] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C11.dat
[10:10:25.040] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C12.dat
[10:10:25.046] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C13.dat
[10:10:25.051] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C14.dat
[10:10:25.056] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//trimParameters35_C15.dat
[10:10:25.062] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-4-45_FPIXTest-17C-FNAL-160914-1251-150V_2016-09-14_12h51m_1473875478/000_FPIXTest_p17//defaultMaskFile.dat
[10:10:25.073] INFO: enter test to run
[10:10:58.677] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:10:58.678] INFO: running: xray
[10:10:58.679] INFO: ----------------------------------------------------------------------
[10:10:58.679] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:10:58.679] INFO: ----------------------------------------------------------------------
[10:10:59.661] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:11:11.611] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:11:41.108] INFO: Resuming triggers.
[10:11:53.055] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[10:12:22.160] INFO: Resuming triggers.
[10:12:34.104] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[10:13:04.477] INFO: Resuming triggers.
[10:13:16.424] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[10:13:46.364] INFO: Resuming triggers.
[10:13:58.315] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[10:14:28.442] INFO: Resuming triggers.
[10:14:40.397] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[10:15:09.747] INFO: Resuming triggers.
[10:15:21.695] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[10:15:51.098] INFO: Resuming triggers.
[10:16:03.044] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[10:16:32.397] INFO: Resuming triggers.
[10:16:37.172] INFO: data taking finished, elapsed time: 100 seconds.
[10:16:49.156] INFO: PixTest:: pg_setup set to default.
[10:16:49.159] INFO: PixTestXray::doPhRun() done
[10:16:49.337] INFO: enter test to run
[10:17:50.323] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:17:50.323] INFO: running: xray
[10:17:50.324] INFO: ----------------------------------------------------------------------
[10:17:50.324] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:17:50.324] INFO: ----------------------------------------------------------------------
[10:17:51.287] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:17:58.393] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[10:18:28.910] INFO: Resuming triggers.
[10:18:36.016] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[10:19:06.381] INFO: Resuming triggers.
[10:19:13.493] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[10:19:43.969] INFO: Resuming triggers.
[10:19:51.080] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[10:20:21.378] INFO: Resuming triggers.
[10:20:28.486] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[10:20:58.862] INFO: Resuming triggers.
[10:21:05.968] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[10:21:36.258] INFO: Resuming triggers.
[10:21:43.369] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[10:22:13.715] INFO: Resuming triggers.
[10:22:20.819] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[10:22:51.749] INFO: Resuming triggers.
[10:22:58.853] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[10:23:29.725] INFO: Resuming triggers.
[10:23:36.832] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[10:24:07.946] INFO: Resuming triggers.
[10:24:15.050] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[10:24:45.405] INFO: Resuming triggers.
[10:24:52.512] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[10:25:24.044] INFO: Resuming triggers.
[10:25:31.151] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[10:26:02.390] INFO: Resuming triggers.
[10:26:09.493] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[10:26:41.429] INFO: Resuming triggers.
[10:26:42.281] INFO: data taking finished, elapsed time: 100 seconds.
[10:26:46.348] INFO: PixTest:: pg_setup set to default.
[10:26:46.351] INFO: PixTestXray::doPhRun() done
[10:26:46.505] INFO: enter test to run
[10:27:05.591] INFO: test: HighRate no parameter change
[10:27:05.591] INFO: running: highrate
[10:27:05.610] INFO: ----------------------------------------------------------------------
[10:27:05.611] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:27:05.611] INFO: ----------------------------------------------------------------------
[10:27:05.765] INFO: Expecting 768 events.
[10:27:06.899] INFO: 768 events read in total (419ms).
[10:27:06.899] INFO: Test took 1268ms.
[10:27:07.703] INFO: Expecting 41600 events.
[10:27:10.782] INFO: 41600 events read in total (2552ms).
[10:27:10.783] INFO: Test took 3840ms.
[10:27:10.811] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:11.548] INFO: Expecting 41600 events.
[10:27:14.684] INFO: 41600 events read in total (2609ms).
[10:27:14.685] INFO: Test took 3858ms.
[10:27:14.714] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:15.448] INFO: Expecting 41600 events.
[10:27:18.644] INFO: 41600 events read in total (2669ms).
[10:27:18.645] INFO: Test took 3915ms.
[10:27:18.675] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:19.406] INFO: Expecting 41600 events.
[10:27:22.592] INFO: 41600 events read in total (2659ms).
[10:27:22.593] INFO: Test took 3901ms.
[10:27:22.623] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:23.355] INFO: Expecting 41600 events.
[10:27:26.553] INFO: 41600 events read in total (2671ms).
[10:27:26.554] INFO: Test took 3915ms.
[10:27:26.584] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:27.317] INFO: Expecting 41600 events.
[10:27:30.512] INFO: 41600 events read in total (2668ms).
[10:27:30.513] INFO: Test took 3913ms.
[10:27:30.542] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:31.274] INFO: Expecting 41600 events.
[10:27:34.448] INFO: 41600 events read in total (2647ms).
[10:27:34.449] INFO: Test took 3890ms.
[10:27:34.479] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:35.209] INFO: Expecting 41600 events.
[10:27:38.418] INFO: 41600 events read in total (2682ms).
[10:27:38.419] INFO: Test took 3923ms.
[10:27:38.449] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:39.178] INFO: Expecting 41600 events.
[10:27:42.391] INFO: 41600 events read in total (2686ms).
[10:27:42.392] INFO: Test took 3926ms.
[10:27:42.421] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:43.152] INFO: Expecting 41600 events.
[10:27:46.351] INFO: 41600 events read in total (2673ms).
[10:27:46.352] INFO: Test took 3913ms.
[10:27:46.382] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:47.111] INFO: Expecting 41600 events.
[10:27:50.315] INFO: 41600 events read in total (2677ms).
[10:27:50.316] INFO: Test took 3917ms.
[10:27:50.346] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:51.080] INFO: Expecting 41600 events.
[10:27:54.295] INFO: 41600 events read in total (2689ms).
[10:27:54.296] INFO: Test took 3934ms.
[10:27:54.325] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:55.056] INFO: Expecting 41600 events.
[10:27:58.247] INFO: 41600 events read in total (2664ms).
[10:27:58.248] INFO: Test took 3906ms.
[10:27:58.278] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:27:59.012] INFO: Expecting 41600 events.
[10:28:02.205] INFO: 41600 events read in total (2666ms).
[10:28:02.206] INFO: Test took 3910ms.
[10:28:02.236] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:02.968] INFO: Expecting 41600 events.
[10:28:06.178] INFO: 41600 events read in total (2684ms).
[10:28:06.179] INFO: Test took 3925ms.
[10:28:06.209] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:06.941] INFO: Expecting 41600 events.
[10:28:10.146] INFO: 41600 events read in total (2678ms).
[10:28:10.147] INFO: Test took 3921ms.
[10:28:10.178] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:10.905] INFO: Expecting 41600 events.
[10:28:14.100] INFO: 41600 events read in total (2668ms).
[10:28:14.101] INFO: Test took 3907ms.
[10:28:14.131] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:14.864] INFO: Expecting 41600 events.
[10:28:18.058] INFO: 41600 events read in total (2667ms).
[10:28:18.059] INFO: Test took 3911ms.
[10:28:18.090] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:18.822] INFO: Expecting 41600 events.
[10:28:22.007] INFO: 41600 events read in total (2658ms).
[10:28:22.008] INFO: Test took 3901ms.
[10:28:22.038] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:22.772] INFO: Expecting 41600 events.
[10:28:25.830] INFO: 41600 events read in total (2531ms).
[10:28:25.831] INFO: Test took 3776ms.
[10:28:25.861] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:26.233] INFO: enter test to run
[10:28:31.782] INFO: test: HighRate no parameter change
[10:28:31.783] INFO: running: highrate
[10:28:31.784] INFO: ----------------------------------------------------------------------
[10:28:31.784] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:28:31.784] INFO: ----------------------------------------------------------------------
[10:28:32.396] INFO: Expecting 208000 events.
[10:28:44.098] INFO: 208000 events read in total (11175ms).
[10:28:44.101] INFO: Test took 12309ms.
[10:28:44.225] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:28:44.471] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 1 0 0 7 0 0
[10:28:44.471] INFO: number of red-efficiency pixels: 63 45 71 81 121 111 112 79 87 122 160 179 91 61 15 22
[10:28:44.471] INFO: number of X-ray hits detected: 57111 35095 53939 81139 91083 91748 93937 68277 69936 92050 95294 76532 83004 46700 18792 21321
[10:28:44.471] 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:28:44.471] INFO: number of Vcal hits detected: 207936 207955 207928 207918 207876 207885 207886 207921 207912 207876 207790 207576 207908 207596 207985 207978
[10:28:44.472] 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 99.9 99.8 100.0 100.0 100.0 100.0
[10:28:44.472] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 100.0 99.9 99.9 99.9 100.0 100.0 99.9 99.9 99.8 100.0 99.8 100.0 100.0
[10:28:44.472] INFO: X-ray hit rate [MHz/cm2]: 16.7 10.3 15.8 23.8 26.7 26.9 27.5 20.0 20.5 27.0 27.9 22.4 24.3 13.7 5.5 6.2
[10:28:44.472] INFO: PixTestHighRate::doXPixelAlive() done
[10:28:44.520] INFO: PixTest:: pg_setup set to default.
[10:28:44.535] INFO: enter test to run
[10:29:00.558] INFO: test: HighRate no parameter change
[10:29:00.558] INFO: running: highrate
[10:29:00.559] INFO: ----------------------------------------------------------------------
[10:29:00.559] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:29:00.559] INFO: ----------------------------------------------------------------------
[10:29:01.173] INFO: Expecting 208000 events.
[10:29:14.375] INFO: 208000 events read in total (12675ms).
[10:29:14.379] INFO: Test took 13810ms.
[10:29:14.645] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:14.935] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 1 26 0 7 0 0
[10:29:14.935] INFO: number of red-efficiency pixels: 222 85 203 240 372 344 319 233 239 405 423 425 264 126 33 41
[10:29:14.935] INFO: number of X-ray hits detected: 120474 74967 115151 171763 193040 194892 199451 145880 148874 194670 201192 161163 175446 98008 40360 45437
[10:29:14.935] 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:29:14.935] INFO: number of Vcal hits detected: 207769 207913 207783 207749 207603 207628 207659 207761 207747 207568 207485 203431 207719 207523 207967 207958
[10:29:14.935] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.8 99.8 99.8 99.9 99.9 99.8 99.8 98.4 99.9 99.9 100.0 100.0
[10:29:14.935] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.8 99.8 99.8 99.9 99.9 99.8 99.8 97.8 99.9 99.8 100.0 100.0
[10:29:14.935] INFO: X-ray hit rate [MHz/cm2]: 35.3 22.0 33.8 50.3 56.6 57.1 58.5 42.8 43.6 57.1 59.0 47.2 51.4 28.7 11.8 13.3
[10:29:14.935] INFO: PixTestHighRate::doXPixelAlive() done
[10:29:14.987] INFO: PixTest:: pg_setup set to default.
[10:29:14.004] INFO: enter test to run
[10:29:31.758] INFO: test: HighRate no parameter change
[10:29:31.758] INFO: running: highrate
[10:29:31.759] INFO: ----------------------------------------------------------------------
[10:29:31.759] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:29:31.759] INFO: ----------------------------------------------------------------------
[10:29:32.373] INFO: Expecting 208000 events.
[10:29:47.272] INFO: 208000 events read in total (14372ms).
[10:29:47.278] INFO: Test took 15508ms.
[10:29:47.680] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:29:48.015] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 1 127 0 7 0 0
[10:29:48.015] INFO: number of red-efficiency pixels: 433 200 488 581 803 935 752 537 527 1030 1065 697 549 312 76 73
[10:29:48.015] INFO: number of X-ray hits detected: 185212 115795 176417 264341 295618 296945 306750 224415 229105 299734 309094 246528 268196 150323 62313 70049
[10:29:48.015] 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:29:48.015] INFO: number of Vcal hits detected: 207517 207787 207409 207357 207054 206829 207125 207367 207414 206757 206619 200630 207395 207317 207921 207926
[10:29:48.015] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.7 99.6 99.5 99.6 99.7 99.7 99.5 99.4 99.5 99.7 99.9 100.0 100.0
[10:29:48.015] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.7 99.5 99.4 99.6 99.7 99.7 99.4 99.3 96.5 99.7 99.7 100.0 100.0
[10:29:48.015] INFO: X-ray hit rate [MHz/cm2]: 54.3 33.9 51.7 77.5 86.6 87.0 89.9 65.8 67.2 87.9 90.6 72.3 78.6 44.1 18.3 20.5
[10:29:48.015] INFO: PixTestHighRate::doXPixelAlive() done
[10:29:48.068] INFO: PixTest:: pg_setup set to default.
[10:29:48.081] INFO: enter test to run
[10:29:50.950] INFO: test: exit no parameter change
[10:29:51.338] QUIET: Connection to board 33 closed.
[10:29:51.348] INFO: pXar: this is the end, my friend