[15:25:06.789] INFO: *** Welcome to pxar ***
[15:25:06.789] INFO: *** Today: 2016/04/27
[15:25:06.804] INFO: *** Version: v1.9.0-796-gef167-dirty
[15:25:06.804] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//dacParameters35_C15.dat
[15:25:06.992] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//tbmParameters_C0b.dat
[15:25:06.992] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//defaultMaskFile.dat
[15:25:07.013] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C15.dat
[15:25:08.403] INFO: clk: 4
[15:25:08.403] INFO: ctr: 4
[15:25:08.403] INFO: sda: 19
[15:25:08.403] INFO: tin: 9
[15:25:08.403] INFO: level: 15
[15:25:08.403] INFO: triggerdelay: 0
[15:25:08.403] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[15:25:08.403] INFO: Log level: INFO
[15:25:08.420] QUIET: Connection to board DTB_WREKRL opened.
[15:25:08.424] 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:
------------------------------------------------------
[15:25:08.427] INFO: RPC call hashes of host and DTB match: 398089610
[15:25:09.962] INFO: DUT info:
[15:25:09.962] INFO: The DUT currently contains the following objects:
[15:25:09.962] INFO: 2 TBM Cores tbm08c (2 ON)
[15:25:09.963] INFO: TBM Core alpha (0): 7 registers set
[15:25:09.963] INFO: TBM Core beta (1): 7 registers set
[15:25:09.963] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:25:09.963] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:09.963] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:09.963] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:09.963] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:09.963] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:09.963] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:09.963] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:09.963] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:09.963] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:09.963] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:09.963] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:09.963] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:09.963] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:09.963] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:09.963] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:09.963] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:25:10.373] INFO: enter 'restricted' command line mode
[15:25:10.373] INFO: enter test to run
[15:25:23.079] INFO: test: PixelAlive no parameter change
[15:25:23.079] INFO: running: pixelalive
[15:25:23.088] INFO: ----------------------------------------------------------------------
[15:25:23.088] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[15:25:23.088] INFO: ----------------------------------------------------------------------
[15:25:23.408] INFO: Expecting 41600 events.
[15:25:27.713] INFO: 41600 events read in total (3586ms).
[15:25:27.881] INFO: Test took 4789ms.
[15:25:27.895] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:25:28.159] INFO: PixTestAlive::aliveTest() done
[15:25:28.159] INFO: number of dead pixels (per ROC): 0 0 2 1 0 0 0 0 0 0 1 0 0 0 0 0
[15:25:28.190] INFO: enter test to run
[15:26:14.311] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[15:26:14.311] INFO: running: highrate
[15:26:14.312] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[15:26:15.448] INFO: ----------------------------------------------------------------------
[15:26:15.448] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[15:26:15.448] INFO: ----------------------------------------------------------------------
[15:26:15.448] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[15:26:15.448] INFO: edge/corner pixel THR is adjusted
[15:26:15.448] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:26:16.406] INFO: Collecting data for 5 seconds...
[15:26:21.424] INFO: Done with hot pixel readout
[15:26:32.727] INFO: PixTest:: pg_setup set to default.
[15:26:32.728] INFO: 14 hot pixels found in step 0
[15:26:33.741] INFO: Collecting data for 5 seconds...
[15:26:38.758] INFO: Done with hot pixel readout
[15:26:50.525] INFO: PixTest:: pg_setup set to default.
[15:26:50.526] INFO: 11 hot pixels found in step 1
[15:26:51.521] INFO: Collecting data for 5 seconds...
[15:26:56.538] INFO: Done with hot pixel readout
[15:27:08.348] INFO: PixTest:: pg_setup set to default.
[15:27:08.349] INFO: 10 hot pixels found in step 2
[15:27:09.341] INFO: Collecting data for 5 seconds...
[15:27:14.358] INFO: Done with hot pixel readout
[15:27:26.116] INFO: PixTest:: pg_setup set to default.
[15:27:26.117] INFO: 13 hot pixels found in step 3
[15:27:27.109] INFO: Collecting data for 5 seconds...
[15:27:32.126] INFO: Done with hot pixel readout
[15:27:43.885] INFO: PixTest:: pg_setup set to default.
[15:27:43.886] INFO: 11 hot pixels found in step 4
[15:27:44.879] INFO: Collecting data for 5 seconds...
[15:27:49.896] INFO: Done with hot pixel readout
[15:28:01.695] INFO: PixTest:: pg_setup set to default.
[15:28:01.695] INFO: 10 hot pixels found in step 5
[15:28:02.688] INFO: Collecting data for 5 seconds...
[15:28:07.705] INFO: Done with hot pixel readout
[15:28:19.470] INFO: PixTest:: pg_setup set to default.
[15:28:19.470] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:28:19.470] INFO: 8 hot pixels found in step 6
[15:28:20.463] INFO: Collecting data for 5 seconds...
[15:28:25.481] INFO: Done with hot pixel readout
[15:28:37.239] INFO: PixTest:: pg_setup set to default.
[15:28:37.239] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:28:37.240] INFO: 3 hot pixels found in step 7
[15:28:38.232] INFO: Collecting data for 5 seconds...
[15:28:43.249] INFO: Done with hot pixel readout
[15:28:54.851] INFO: PixTest:: pg_setup set to default.
[15:28:54.851] INFO: 3 hot pixels found in step 8
[15:28:55.844] INFO: Collecting data for 5 seconds...
[15:29:00.861] INFO: Done with hot pixel readout
[15:29:11.032] INFO: PixTest:: pg_setup set to default.
[15:29:11.033] INFO: 1 hot pixels found in step 9
[15:29:12.025] INFO: Collecting data for 5 seconds...
[15:29:17.035] INFO: Done with hot pixel readout
[15:29:26.848] INFO: PixTest:: pg_setup set to default.
[15:29:26.849] INFO: 1 hot pixels found in step 10
[15:29:27.842] INFO: Collecting data for 5 seconds...
[15:29:32.859] INFO: Done with hot pixel readout
[15:29:44.596] INFO: PixTest:: pg_setup set to default.
[15:29:44.597] INFO: 3 hot pixels found in step 11
[15:29:45.591] INFO: Collecting data for 5 seconds...
[15:29:50.608] INFO: Done with hot pixel readout
[15:30:02.283] INFO: PixTest:: pg_setup set to default.
[15:30:02.284] INFO: 2 hot pixels found in step 12
[15:30:03.279] INFO: Collecting data for 5 seconds...
[15:30:08.296] INFO: Done with hot pixel readout
[15:30:19.901] INFO: PixTest:: pg_setup set to default.
[15:30:19.901] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:30:19.902] INFO: 4 hot pixels found in step 13
[15:30:20.895] INFO: Collecting data for 5 seconds...
[15:30:25.912] INFO: Done with hot pixel readout
[15:30:37.670] INFO: PixTest:: pg_setup set to default.
[15:30:37.670] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[15:30:37.670] INFO: 1 hot pixels found in step 14
[15:30:37.707] INFO: 1 hot pixels could not be trimmed and have been masked.
[15:30:37.711] INFO: PixTest::trimHotPixels() done
[15:30:37.711] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C0.dat
[15:30:37.717] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C1.dat
[15:30:37.723] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C2.dat
[15:30:37.728] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C3.dat
[15:30:37.733] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C4.dat
[15:30:37.738] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C5.dat
[15:30:37.743] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C6.dat
[15:30:37.749] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C7.dat
[15:30:37.754] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C8.dat
[15:30:37.759] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C9.dat
[15:30:37.764] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C10.dat
[15:30:37.770] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C11.dat
[15:30:37.775] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C12.dat
[15:30:37.780] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C13.dat
[15:30:37.785] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C14.dat
[15:30:37.791] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C15.dat
[15:30:37.796] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//defaultMaskFile.dat
[15:30:37.806] INFO: enter test to run
[15:31:54.325] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[15:31:54.325] INFO: running: highrate
[15:31:54.329] INFO: ----------------------------------------------------------------------
[15:31:54.329] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[15:31:54.329] INFO: ----------------------------------------------------------------------
[15:31:54.329] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[15:31:54.329] INFO: edge/corner pixel THR is adjusted
[15:31:54.329] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:31:55.286] INFO: Collecting data for 1 seconds...
[15:31:56.290] INFO: Done with hot pixel readout
[15:32:00.399] INFO: PixTest:: pg_setup set to default.
[15:32:00.400] INFO: 0 hot pixels found in step 0
[15:32:00.405] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:32:00.491] INFO: PixTest::trimHotPixels() done
[15:32:00.492] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C0.dat
[15:32:00.499] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C1.dat
[15:32:00.505] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C2.dat
[15:32:00.510] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C3.dat
[15:32:00.516] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C4.dat
[15:32:00.522] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C5.dat
[15:32:00.527] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C6.dat
[15:32:00.532] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C7.dat
[15:32:00.538] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C8.dat
[15:32:00.543] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C9.dat
[15:32:00.548] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C10.dat
[15:32:00.554] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C11.dat
[15:32:00.559] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C12.dat
[15:32:00.564] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C13.dat
[15:32:00.570] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C14.dat
[15:32:00.575] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//trimParameters35_C15.dat
[15:32:00.580] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-M-3-01_FPIXTest-17C-FNAL-160422-0956_2016-04-22_09h56m_1461337018/000_FPIXTest_p17//defaultMaskFile.dat
[15:32:00.590] INFO: enter test to run
[15:33:41.572] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[15:33:41.572] INFO: running: xray
[15:33:41.573] INFO: ----------------------------------------------------------------------
[15:33:41.573] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:33:41.573] INFO: ----------------------------------------------------------------------
[15:33:42.536] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:33:53.961] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[15:34:23.531] INFO: Resuming triggers.
[15:34:34.959] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[15:35:04.519] INFO: Resuming triggers.
[15:35:15.947] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[15:35:45.401] INFO: Resuming triggers.
[15:35:56.830] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[15:36:26.298] INFO: Resuming triggers.
[15:36:37.726] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[15:37:07.189] INFO: Resuming triggers.
[15:37:18.620] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[15:37:48.043] INFO: Resuming triggers.
[15:37:59.468] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[15:38:28.941] INFO: Resuming triggers.
[15:38:40.371] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[15:39:09.802] INFO: Resuming triggers.
[15:39:18.706] INFO: data taking finished, elapsed time: 100 seconds.
[15:39:38.335] INFO: PixTest:: pg_setup set to default.
[15:39:38.338] INFO: PixTestXray::doPhRun() done
[15:39:38.477] INFO: enter test to run
[15:41:21.592] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[15:41:21.592] INFO: running: xray
[15:41:21.594] INFO: ----------------------------------------------------------------------
[15:41:21.594] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:41:21.594] INFO: ----------------------------------------------------------------------
[15:41:22.562] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:41:28.997] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[15:41:59.099] INFO: Resuming triggers.
[15:42:05.532] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[15:42:35.599] INFO: Resuming triggers.
[15:42:42.035] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[15:43:12.080] INFO: Resuming triggers.
[15:43:18.511] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[15:43:48.550] INFO: Resuming triggers.
[15:43:54.987] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[15:44:25.089] INFO: Resuming triggers.
[15:44:31.522] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[15:45:01.653] INFO: Resuming triggers.
[15:45:08.087] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[15:45:38.193] INFO: Resuming triggers.
[15:45:44.632] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[15:46:14.630] INFO: Resuming triggers.
[15:46:21.063] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[15:46:51.200] INFO: Resuming triggers.
[15:46:57.633] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[15:47:27.821] INFO: Resuming triggers.
[15:47:34.255] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[15:48:04.427] INFO: Resuming triggers.
[15:48:10.861] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[15:48:40.952] INFO: Resuming triggers.
[15:48:47.384] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[15:49:17.368] INFO: Resuming triggers.
[15:49:23.804] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[15:49:50.738] INFO: Resuming triggers.
[15:49:57.173] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[15:50:27.100] INFO: Resuming triggers.
[15:50:30.917] INFO: data taking finished, elapsed time: 100 seconds.
[15:50:48.967] INFO: PixTest:: pg_setup set to default.
[15:50:48.970] INFO: PixTestXray::doPhRun() done
[15:50:49.120] INFO: enter test to run
[15:51:42.004] INFO: test: HighRate no parameter change
[15:51:42.004] INFO: running: highrate
[15:51:42.005] INFO: ----------------------------------------------------------------------
[15:51:42.005] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:51:42.005] INFO: ----------------------------------------------------------------------
[15:51:43.145] INFO: Expecting 768 events.
[15:51:44.279] INFO: 768 events read in total (418ms).
[15:51:44.280] INFO: Test took 1269ms.
[15:51:45.083] INFO: Expecting 41600 events.
[15:51:48.154] INFO: 41600 events read in total (2544ms).
[15:51:48.155] INFO: Test took 3867ms.
[15:51:48.189] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:48.913] INFO: Expecting 41600 events.
[15:51:52.121] INFO: 41600 events read in total (2681ms).
[15:51:52.122] INFO: Test took 3914ms.
[15:51:52.156] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:52.882] INFO: Expecting 41600 events.
[15:51:56.093] INFO: 41600 events read in total (2684ms).
[15:51:56.093] INFO: Test took 3919ms.
[15:51:56.128] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:51:56.852] INFO: Expecting 41600 events.
[15:52:00.079] INFO: 41600 events read in total (2700ms).
[15:52:00.080] INFO: Test took 3933ms.
[15:52:00.114] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:00.839] INFO: Expecting 41600 events.
[15:52:04.052] INFO: 41600 events read in total (2686ms).
[15:52:04.053] INFO: Test took 3922ms.
[15:52:04.087] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:04.812] INFO: Expecting 41600 events.
[15:52:08.056] INFO: 41600 events read in total (2717ms).
[15:52:08.057] INFO: Test took 3952ms.
[15:52:08.092] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:08.816] INFO: Expecting 41600 events.
[15:52:12.062] INFO: 41600 events read in total (2719ms).
[15:52:12.063] INFO: Test took 3952ms.
[15:52:12.098] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:12.817] INFO: Expecting 41600 events.
[15:52:16.034] INFO: 41600 events read in total (2690ms).
[15:52:16.035] INFO: Test took 3919ms.
[15:52:16.069] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:16.790] INFO: Expecting 41600 events.
[15:52:20.047] INFO: 41600 events read in total (2730ms).
[15:52:20.048] INFO: Test took 3960ms.
[15:52:20.082] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:20.808] INFO: Expecting 41600 events.
[15:52:24.052] INFO: 41600 events read in total (2717ms).
[15:52:24.053] INFO: Test took 3952ms.
[15:52:24.088] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:24.810] INFO: Expecting 41600 events.
[15:52:28.049] INFO: 41600 events read in total (2712ms).
[15:52:28.050] INFO: Test took 3944ms.
[15:52:28.085] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:28.807] INFO: Expecting 41600 events.
[15:52:32.057] INFO: 41600 events read in total (2723ms).
[15:52:32.058] INFO: Test took 3955ms.
[15:52:32.092] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:32.816] INFO: Expecting 41600 events.
[15:52:36.074] INFO: 41600 events read in total (2731ms).
[15:52:36.075] INFO: Test took 3965ms.
[15:52:36.110] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:36.831] INFO: Expecting 41600 events.
[15:52:40.074] INFO: 41600 events read in total (2716ms).
[15:52:40.075] INFO: Test took 3947ms.
[15:52:40.109] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:40.833] INFO: Expecting 41600 events.
[15:52:44.090] INFO: 41600 events read in total (2730ms).
[15:52:44.091] INFO: Test took 3965ms.
[15:52:44.125] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:44.845] INFO: Expecting 41600 events.
[15:52:48.075] INFO: 41600 events read in total (2703ms).
[15:52:48.076] INFO: Test took 3933ms.
[15:52:48.110] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:48.833] INFO: Expecting 41600 events.
[15:52:52.083] INFO: 41600 events read in total (2723ms).
[15:52:52.084] INFO: Test took 3955ms.
[15:52:52.118] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:52.841] INFO: Expecting 41600 events.
[15:52:56.057] INFO: 41600 events read in total (2689ms).
[15:52:56.059] INFO: Test took 3922ms.
[15:52:56.094] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:52:56.816] INFO: Expecting 41600 events.
[15:53:00.030] INFO: 41600 events read in total (2687ms).
[15:53:00.031] INFO: Test took 3918ms.
[15:53:00.065] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:00.791] INFO: Expecting 41600 events.
[15:53:03.868] INFO: 41600 events read in total (2550ms).
[15:53:03.869] INFO: Test took 3785ms.
[15:53:03.903] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:04.265] INFO: enter test to run
[15:53:32.747] INFO: test: HighRate no parameter change
[15:53:32.747] INFO: running: highrate
[15:53:32.748] INFO: ----------------------------------------------------------------------
[15:53:32.748] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:53:32.748] INFO: ----------------------------------------------------------------------
[15:53:33.365] INFO: Expecting 208000 events.
[15:53:45.267] INFO: 208000 events read in total (11375ms).
[15:53:45.270] INFO: Test took 12513ms.
[15:53:45.413] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:53:45.663] INFO: number of dead pixels (per ROC): 0 0 2 1 0 0 0 0 0 0 1 0 0 0 0 0
[15:53:45.663] INFO: number of red-efficiency pixels: 61 46 64 132 163 158 145 138 83 114 126 110 94 60 25 20
[15:53:45.663] INFO: number of X-ray hits detected: 66469 46364 67790 109009 117733 114998 118144 86747 77610 97053 96495 82821 85753 51408 20899 23531
[15:53:45.663] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:53:45.663] INFO: number of Vcal hits detected: 207939 207954 207837 207818 207833 207834 207852 207859 207917 207883 207818 207887 207904 207939 207974 207980
[15:53:45.663] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[15:53:45.663] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[15:53:45.663] INFO: X-ray hit rate [MHz/cm2]: 19.5 13.6 19.9 32.0 34.5 33.7 34.6 25.4 22.7 28.4 28.3 24.3 25.1 15.1 6.1 6.9
[15:53:45.663] INFO: PixTestHighRate::doXPixelAlive() done
[15:53:45.712] INFO: PixTest:: pg_setup set to default.
[15:53:45.727] INFO: enter test to run
[15:54:26.155] INFO: test: HighRate no parameter change
[15:54:26.155] INFO: running: highrate
[15:54:26.156] INFO: ----------------------------------------------------------------------
[15:54:26.156] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:54:26.156] INFO: ----------------------------------------------------------------------
[15:54:26.771] INFO: Expecting 208000 events.
[15:54:40.467] INFO: 208000 events read in total (13169ms).
[15:54:40.472] INFO: Test took 14306ms.
[15:54:40.775] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:54:41.078] INFO: number of dead pixels (per ROC): 0 0 2 1 0 0 0 0 0 0 1 0 0 0 0 0
[15:54:41.078] INFO: number of red-efficiency pixels: 158 108 228 385 629 553 454 392 214 278 319 318 259 159 47 46
[15:54:41.078] INFO: number of X-ray hits detected: 137347 96033 141560 228263 244562 240057 245777 179824 161730 203000 201584 172184 179273 106332 43129 49336
[15:54:41.078] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:54:41.078] INFO: number of Vcal hits detected: 207834 207886 207654 207540 207306 207394 207502 207565 207781 207701 207620 207660 207732 207834 207952 207954
[15:54:41.078] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.8 99.8 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[15:54:41.078] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.7 99.8 99.8 99.9 99.9 99.8 99.8 99.9 99.9 100.0 100.0
[15:54:41.078] INFO: X-ray hit rate [MHz/cm2]: 40.3 28.1 41.5 66.9 71.7 70.4 72.0 52.7 47.4 59.5 59.1 50.5 52.5 31.2 12.6 14.5
[15:54:41.078] INFO: PixTestHighRate::doXPixelAlive() done
[15:54:41.126] INFO: PixTest:: pg_setup set to default.
[15:54:41.143] INFO: enter test to run
[15:55:05.498] INFO: test: HighRate no parameter change
[15:55:05.498] INFO: running: highrate
[15:55:05.500] INFO: ----------------------------------------------------------------------
[15:55:05.500] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:55:05.500] INFO: ----------------------------------------------------------------------
[15:55:06.117] INFO: Expecting 208000 events.
[15:55:21.887] INFO: 208000 events read in total (15244ms).
[15:55:21.895] INFO: Test took 16387ms.
[15:55:22.370] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:55:22.727] INFO: number of dead pixels (per ROC): 0 0 2 1 0 0 0 0 0 0 1 0 0 0 0 0
[15:55:22.727] INFO: number of red-efficiency pixels: 399 222 546 936 1488 1373 1048 900 496 748 772 738 594 448 86 106
[15:55:22.727] INFO: number of X-ray hits detected: 214002 149630 219607 352903 381008 372543 380424 279874 251658 316415 313388 268610 278059 166159 67194 76860
[15:55:22.727] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:55:22.727] INFO: number of Vcal hits detected: 207534 207754 207233 206825 206001 206098 206695 206805 207442 207153 207019 207119 207348 207459 207911 207888
[15:55:22.727] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.1 99.2 99.4 99.5 99.7 99.6 99.6 99.6 99.7 99.8 100.0 99.9
[15:55:22.727] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.6 99.4 99.0 99.1 99.4 99.4 99.7 99.6 99.5 99.6 99.7 99.7 100.0 99.9
[15:55:22.727] INFO: X-ray hit rate [MHz/cm2]: 62.7 43.9 64.4 103.4 111.7 109.2 111.5 82.0 73.8 92.7 91.9 78.7 81.5 48.7 19.7 22.5
[15:55:22.727] INFO: PixTestHighRate::doXPixelAlive() done
[15:55:22.777] INFO: PixTest:: pg_setup set to default.
[15:55:22.795] INFO: enter test to run
[15:55:40.570] INFO: test: exit no parameter change
[15:55:40.954] QUIET: Connection to board 33 closed.
[15:55:40.955] INFO: pXar: this is the end, my friend