[13:09:34.937] INFO: *** Welcome to pxar ***
[13:09:34.937] INFO: *** Today: 2016/06/01
[13:09:34.958] INFO: *** Version: v1.9.0-796-gef167-dirty
[13:09:34.958] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//dacParameters35_C15.dat
[13:09:34.959] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:09:34.959] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//defaultMaskFile.dat
[13:09:34.959] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C15.dat
[13:09:35.028] INFO: clk: 4
[13:09:35.028] INFO: ctr: 4
[13:09:35.028] INFO: sda: 19
[13:09:35.028] INFO: tin: 9
[13:09:35.028] INFO: level: 15
[13:09:35.028] INFO: triggerdelay: 0
[13:09:35.028] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[13:09:35.028] INFO: Log level: INFO
[13:09:35.041] QUIET: Connection to board DTB_WREKRL opened.
[13:09:35.044] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[13:09:35.047] INFO: RPC call hashes of host and DTB match: 398089610
[13:09:36.582] INFO: DUT info:
[13:09:36.582] INFO: The DUT currently contains the following objects:
[13:09:36.582] INFO: 2 TBM Cores tbm08c (2 ON)
[13:09:36.582] INFO: TBM Core alpha (0): 7 registers set
[13:09:36.582] INFO: TBM Core beta (1): 7 registers set
[13:09:36.582] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:09:36.582] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:36.582] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:36.582] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:36.582] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:36.582] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:36.582] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:36.582] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:36.582] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:36.582] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:36.582] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:36.582] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:36.583] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:36.583] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:36.583] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:36.583] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:36.583] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:09:36.986] INFO: enter 'restricted' command line mode
[13:09:36.986] INFO: enter test to run
[13:09:40.668] INFO: test: PixelAlive no parameter change
[13:09:40.668] INFO: running: pixelalive
[13:09:40.677] INFO: ----------------------------------------------------------------------
[13:09:40.677] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:09:40.677] INFO: ----------------------------------------------------------------------
[13:09:40.994] INFO: Expecting 41600 events.
[13:09:45.337] INFO: 41600 events read in total (3624ms).
[13:09:45.507] INFO: Test took 4827ms.
[13:09:45.515] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:09:45.776] INFO: PixTestAlive::aliveTest() done
[13:09:45.776] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0
[13:09:45.809] INFO: enter test to run
[13:11:14.779] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:11:14.779] INFO: running: highrate
[13:11:14.780] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:11:14.934] INFO: ----------------------------------------------------------------------
[13:11:14.934] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:11:14.934] INFO: ----------------------------------------------------------------------
[13:11:14.934] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:11:14.934] INFO: edge/corner pixel THR is adjusted
[13:11:14.934] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:11:15.893] INFO: Collecting data for 5 seconds...
[13:11:20.908] INFO: Done with hot pixel readout
[13:11:33.892] INFO: PixTest:: pg_setup set to default.








[13:11:33.922] INFO: 288 hot pixels found in step 0
[13:11:34.920] INFO: Collecting data for 5 seconds...
[13:11:39.935] INFO: Done with hot pixel readout
[13:11:52.883] INFO: PixTest:: pg_setup set to default.













[13:11:52.885] INFO: 269 hot pixels found in step 1
[13:11:53.881] INFO: Collecting data for 5 seconds...
[13:11:58.897] INFO: Done with hot pixel readout
[13:12:11.841] INFO: PixTest:: pg_setup set to default.















[13:12:11.843] INFO: 222 hot pixels found in step 2
[13:12:12.842] INFO: Collecting data for 5 seconds...
[13:12:17.857] INFO: Done with hot pixel readout
[13:12:30.861] INFO: PixTest:: pg_setup set to default.
















[13:12:30.863] INFO: 198 hot pixels found in step 3
[13:12:31.862] INFO: Collecting data for 5 seconds...
[13:12:36.877] INFO: Done with hot pixel readout
[13:12:49.809] INFO: PixTest:: pg_setup set to default.




















[13:12:49.811] INFO: 194 hot pixels found in step 4
[13:12:50.810] INFO: Collecting data for 5 seconds...
[13:12:55.825] INFO: Done with hot pixel readout
[13:13:08.723] INFO: PixTest:: pg_setup set to default.





























[13:13:08.724] INFO: 181 hot pixels found in step 5
[13:13:09.723] INFO: Collecting data for 5 seconds...
[13:13:14.739] INFO: Done with hot pixel readout
[13:13:27.755] INFO: PixTest:: pg_setup set to default.































[13:13:27.757] INFO: 179 hot pixels found in step 6
[13:13:28.757] INFO: Collecting data for 5 seconds...
[13:13:33.772] INFO: Done with hot pixel readout
[13:13:46.725] INFO: PixTest:: pg_setup set to default.









































[13:13:46.728] INFO: 168 hot pixels found in step 7
[13:13:47.727] INFO: Collecting data for 5 seconds...
[13:13:52.742] INFO: Done with hot pixel readout
[13:14:05.709] INFO: PixTest:: pg_setup set to default.









































[13:14:05.710] INFO: 140 hot pixels found in step 8
[13:14:06.711] INFO: Collecting data for 5 seconds...
[13:14:11.727] INFO: Done with hot pixel readout
[13:14:24.700] INFO: PixTest:: pg_setup set to default.



















































[13:14:24.702] INFO: 166 hot pixels found in step 9
[13:14:25.701] INFO: Collecting data for 5 seconds...
[13:14:30.717] INFO: Done with hot pixel readout
[13:14:43.623] INFO: PixTest:: pg_setup set to default.













































[13:14:43.625] INFO: 169 hot pixels found in step 10
[13:14:44.624] INFO: Collecting data for 5 seconds...
[13:14:49.639] INFO: Done with hot pixel readout
[13:15:02.590] INFO: PixTest:: pg_setup set to default.
















































[13:15:02.592] INFO: 130 hot pixels found in step 11
[13:15:03.591] INFO: Collecting data for 5 seconds...
[13:15:08.607] INFO: Done with hot pixel readout
[13:15:21.522] INFO: PixTest:: pg_setup set to default.












































[13:15:21.524] INFO: 136 hot pixels found in step 12
[13:15:22.523] INFO: Collecting data for 5 seconds...
[13:15:27.538] INFO: Done with hot pixel readout
[13:15:40.477] INFO: PixTest:: pg_setup set to default.














































[13:15:40.478] INFO: 148 hot pixels found in step 13
[13:15:41.478] INFO: Collecting data for 5 seconds...
[13:15:46.494] INFO: Done with hot pixel readout
[13:15:59.412] INFO: PixTest:: pg_setup set to default.

















































[13:15:59.413] INFO: 133 hot pixels found in step 14
[13:15:59.457] INFO: 133 hot pixels could not be trimmed and have been masked.
[13:15:59.461] INFO: PixTest::trimHotPixels() done
[13:15:59.461] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C0.dat
[13:15:59.466] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C1.dat
[13:15:59.472] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C2.dat
[13:15:59.478] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C3.dat
[13:15:59.484] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C4.dat
[13:15:59.489] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C5.dat
[13:15:59.494] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C6.dat
[13:15:59.500] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C7.dat
[13:15:59.505] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C8.dat
[13:15:59.510] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C9.dat
[13:15:59.515] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C10.dat
[13:15:59.520] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C11.dat
[13:15:59.526] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C12.dat
[13:15:59.531] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C13.dat
[13:15:59.536] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C14.dat
[13:15:59.541] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C15.dat
[13:15:59.547] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//defaultMaskFile.dat
[13:15:59.566] INFO: enter test to run
[13:16:46.289] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:16:46.289] INFO: running: highrate
[13:16:46.294] INFO: ----------------------------------------------------------------------
[13:16:46.294] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:16:46.294] INFO: ----------------------------------------------------------------------
[13:16:46.294] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:16:46.294] INFO: edge/corner pixel THR is adjusted
[13:16:46.294] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:16:47.252] INFO: Collecting data for 1 seconds...
[13:16:48.255] INFO: Done with hot pixel readout
[13:16:52.959] INFO: PixTest:: pg_setup set to default.
[13:16:52.960] INFO: 0 hot pixels found in step 0
[13:16:52.965] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:16:53.031] INFO: PixTest::trimHotPixels() done
[13:16:53.031] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C0.dat
[13:16:53.038] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C1.dat
[13:16:53.044] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C2.dat
[13:16:53.049] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C3.dat
[13:16:53.054] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C4.dat
[13:16:53.060] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C5.dat
[13:16:53.065] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C6.dat
[13:16:53.070] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C7.dat
[13:16:53.075] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C8.dat
[13:16:53.081] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C9.dat
[13:16:53.086] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C10.dat
[13:16:53.091] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C11.dat
[13:16:53.096] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C12.dat
[13:16:53.102] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C13.dat
[13:16:53.107] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C14.dat
[13:16:53.112] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C15.dat
[13:16:53.117] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-K-NS_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//defaultMaskFile.dat
[13:16:53.128] INFO: enter test to run
[13:17:23.417] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:17:23.417] INFO: running: xray
[13:17:23.418] INFO: ----------------------------------------------------------------------
[13:17:23.418] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:17:23.418] INFO: ----------------------------------------------------------------------
[13:17:24.381] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:17:34.702] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[13:18:04.367] INFO: Resuming triggers.
[13:18:14.688] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[13:18:44.509] INFO: Resuming triggers.
[13:18:54.832] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[13:19:24.700] INFO: Resuming triggers.
[13:19:35.023] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[13:20:04.709] INFO: Resuming triggers.
[13:20:15.031] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[13:20:44.834] INFO: Resuming triggers.
[13:20:55.156] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[13:21:24.931] INFO: Resuming triggers.
[13:21:35.252] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[13:22:04.998] INFO: Resuming triggers.
[13:22:15.319] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[13:22:45.096] INFO: Resuming triggers.
[13:22:55.413] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[13:23:25.320] INFO: Resuming triggers.
[13:23:32.742] INFO: data taking finished, elapsed time: 100 seconds.
[13:23:54.370] INFO: PixTest:: pg_setup set to default.
[13:23:54.373] INFO: PixTestXray::doPhRun() done
[13:23:54.513] INFO: enter test to run
[13:24:34.789] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:24:34.789] INFO: running: xray
[13:24:34.791] INFO: ----------------------------------------------------------------------
[13:24:34.791] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:24:34.791] INFO: ----------------------------------------------------------------------
[13:24:35.754] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:24:41.175] INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[13:25:11.573] INFO: Resuming triggers.
[13:25:16.993] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[13:25:47.263] INFO: Resuming triggers.
[13:25:52.684] INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[13:26:22.961] INFO: Resuming triggers.
[13:26:28.384] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[13:26:58.765] INFO: Resuming triggers.
[13:27:04.189] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[13:27:34.551] INFO: Resuming triggers.
[13:27:39.975] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[13:28:10.344] INFO: Resuming triggers.
[13:28:15.766] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[13:28:46.184] INFO: Resuming triggers.
[13:28:51.608] INFO: run duration 43 seconds, buffer almost full (81%), pausing triggers.
[13:29:22.011] INFO: Resuming triggers.
[13:29:27.436] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[13:29:57.601] INFO: Resuming triggers.
[13:30:03.024] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[13:30:33.413] INFO: Resuming triggers.
[13:30:38.837] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[13:31:09.255] INFO: Resuming triggers.
[13:31:14.680] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[13:31:45.041] INFO: Resuming triggers.
[13:31:50.461] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[13:32:20.831] INFO: Resuming triggers.
[13:32:26.250] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[13:32:56.597] INFO: Resuming triggers.
[13:33:02.018] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[13:33:32.384] INFO: Resuming triggers.
[13:33:37.805] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[13:34:08.153] INFO: Resuming triggers.
[13:34:13.572] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[13:34:43.982] INFO: Resuming triggers.
[13:34:49.402] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[13:35:19.793] INFO: Resuming triggers.
[13:35:22.510] INFO: data taking finished, elapsed time: 100 seconds.
[13:35:38.038] INFO: PixTest:: pg_setup set to default.
[13:35:38.042] INFO: PixTestXray::doPhRun() done
[13:35:38.190] INFO: enter test to run
[13:42:04.086] INFO: test: HighRate no parameter change
[13:42:04.086] INFO: running: highrate
[13:42:04.087] INFO: ----------------------------------------------------------------------
[13:42:04.087] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:42:04.087] INFO: ----------------------------------------------------------------------
[13:42:04.228] INFO: Expecting 768 events.
[13:42:05.362] INFO: 768 events read in total (418ms).
[13:42:05.362] INFO: Test took 1268ms.
[13:42:06.165] INFO: Expecting 41600 events.
[13:42:09.310] INFO: 41600 events read in total (2618ms).
[13:42:09.311] INFO: Test took 3942ms.
[13:42:09.352] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:10.068] INFO: Expecting 41600 events.
[13:42:13.335] INFO: 41600 events read in total (2741ms).
[13:42:13.336] INFO: Test took 3963ms.
[13:42:13.379] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:14.088] INFO: Expecting 41600 events.
[13:42:17.392] INFO: 41600 events read in total (2777ms).
[13:42:17.393] INFO: Test took 3992ms.
[13:42:17.436] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:18.145] INFO: Expecting 41600 events.
[13:42:21.460] INFO: 41600 events read in total (2788ms).
[13:42:21.461] INFO: Test took 4005ms.
[13:42:21.503] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:22.214] INFO: Expecting 41600 events.
[13:42:25.528] INFO: 41600 events read in total (2787ms).
[13:42:25.530] INFO: Test took 4006ms.
[13:42:25.572] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:26.282] INFO: Expecting 41600 events.
[13:42:29.610] INFO: 41600 events read in total (2801ms).
[13:42:29.612] INFO: Test took 4019ms.
[13:42:29.655] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:30.364] INFO: Expecting 41600 events.
[13:42:33.687] INFO: 41600 events read in total (2796ms).
[13:42:33.688] INFO: Test took 4011ms.
[13:42:33.730] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:34.438] INFO: Expecting 41600 events.
[13:42:37.766] INFO: 41600 events read in total (2801ms).
[13:42:37.767] INFO: Test took 4015ms.
[13:42:37.810] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:38.518] INFO: Expecting 41600 events.
[13:42:41.849] INFO: 41600 events read in total (2804ms).
[13:42:41.851] INFO: Test took 4019ms.
[13:42:41.893] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:42.603] INFO: Expecting 41600 events.
[13:42:45.936] INFO: 41600 events read in total (2806ms).
[13:42:45.937] INFO: Test took 4022ms.
[13:42:45.980] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:46.685] INFO: Expecting 41600 events.
[13:42:50.008] INFO: 41600 events read in total (2796ms).
[13:42:50.009] INFO: Test took 4007ms.
[13:42:50.051] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:50.759] INFO: Expecting 41600 events.
[13:42:54.085] INFO: 41600 events read in total (2799ms).
[13:42:54.086] INFO: Test took 4012ms.
[13:42:54.129] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:54.834] INFO: Expecting 41600 events.
[13:42:58.165] INFO: 41600 events read in total (2804ms).
[13:42:58.166] INFO: Test took 4015ms.
[13:42:58.209] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:42:58.914] INFO: Expecting 41600 events.
[13:43:02.235] INFO: 41600 events read in total (2794ms).
[13:43:02.236] INFO: Test took 4007ms.
[13:43:02.278] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:02.985] INFO: Expecting 41600 events.
[13:43:06.305] INFO: 41600 events read in total (2793ms).
[13:43:06.306] INFO: Test took 4006ms.
[13:43:06.349] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:07.058] INFO: Expecting 41600 events.
[13:43:10.381] INFO: 41600 events read in total (2796ms).
[13:43:10.382] INFO: Test took 4011ms.
[13:43:10.425] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:11.128] INFO: Expecting 41600 events.
[13:43:14.461] INFO: 41600 events read in total (2806ms).
[13:43:14.462] INFO: Test took 4015ms.
[13:43:14.504] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:15.210] INFO: Expecting 41600 events.
[13:43:18.533] INFO: 41600 events read in total (2796ms).
[13:43:18.534] INFO: Test took 4007ms.
[13:43:18.577] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:19.287] INFO: Expecting 41600 events.
[13:43:22.599] INFO: 41600 events read in total (2785ms).
[13:43:22.600] INFO: Test took 4002ms.
[13:43:22.643] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:23.350] INFO: Expecting 41600 events.
[13:43:26.553] INFO: 41600 events read in total (2676ms).
[13:43:26.554] INFO: Test took 3891ms.
[13:43:26.596] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:43:26.937] INFO: enter test to run
[13:44:00.781] INFO: test: HighRate no parameter change
[13:44:00.781] INFO: running: highrate
[13:44:00.782] INFO: ----------------------------------------------------------------------
[13:44:00.782] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:44:00.782] INFO: ----------------------------------------------------------------------
[13:44:01.396] INFO: Expecting 208000 events.
[13:44:13.755] INFO: 208000 events read in total (11833ms).
[13:44:13.759] INFO: Test took 12967ms.
[13:44:13.947] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:14.214] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0
[13:44:14.214] INFO: number of red-efficiency pixels: 129 75 118 254 188 179 204 140 155 165 190 111 169 74 23 36
[13:44:14.214] INFO: number of X-ray hits detected: 89044 59790 91828 146581 143819 149730 157678 116811 115350 125578 122211 107314 115258 67689 26688 31190
[13:44:14.214] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:44:14.214] INFO: number of Vcal hits detected: 207865 207924 207875 207733 207802 207816 207793 207855 207842 207826 207805 207886 207775 207923 207976 207962
[13:44:14.214] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[13:44:14.214] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[13:44:14.215] INFO: X-ray hit rate [MHz/cm2]: 26.1 17.5 26.9 43.0 42.2 43.9 46.2 34.2 33.8 36.8 35.8 31.5 33.8 19.8 7.8 9.1
[13:44:14.215] INFO: PixTestHighRate::doXPixelAlive() done
[13:44:14.263] INFO: PixTest:: pg_setup set to default.
[13:44:14.279] INFO: enter test to run
[13:44:29.925] INFO: test: HighRate no parameter change
[13:44:29.925] INFO: running: highrate
[13:44:29.926] INFO: ----------------------------------------------------------------------
[13:44:29.926] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:44:29.926] INFO: ----------------------------------------------------------------------
[13:44:30.545] INFO: Expecting 208000 events.
[13:44:45.514] INFO: 208000 events read in total (14442ms).
[13:44:45.521] INFO: Test took 15584ms.
[13:44:45.924] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:46.260] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0
[13:44:46.260] INFO: number of red-efficiency pixels: 456 234 446 1032 833 711 848 542 455 543 687 421 633 257 78 76
[13:44:46.260] INFO: number of X-ray hits detected: 191482 128838 194953 311984 305939 320981 337928 248544 247740 269519 261604 229507 247048 145617 57119 67021
[13:44:46.260] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:44:46.260] INFO: number of Vcal hits detected: 207480 207751 207477 206736 207031 207182 206993 207375 207484 207392 207221 207544 207241 207720 207921 207922
[13:44:46.260] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.4 99.6 99.6 99.6 99.7 99.8 99.7 99.7 99.8 99.7 99.9 100.0 100.0
[13:44:46.260] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.4 99.5 99.6 99.5 99.7 99.8 99.7 99.6 99.8 99.6 99.9 100.0 100.0
[13:44:46.260] INFO: X-ray hit rate [MHz/cm2]: 56.1 37.8 57.1 91.4 89.7 94.1 99.0 72.9 72.6 79.0 76.7 67.3 72.4 42.7 16.7 19.6
[13:44:46.260] INFO: PixTestHighRate::doXPixelAlive() done
[13:44:46.312] INFO: PixTest:: pg_setup set to default.
[13:44:46.331] INFO: enter test to run
[13:46:25.892] INFO: test: HighRate no parameter change
[13:46:25.892] INFO: running: highrate
[13:46:25.893] INFO: ----------------------------------------------------------------------
[13:46:25.893] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:46:25.893] INFO: ----------------------------------------------------------------------
[13:46:26.509] INFO: Expecting 208000 events.
[13:46:44.418] INFO: 208000 events read in total (17382ms).
[13:46:44.428] INFO: Test took 18524ms.
[13:46:45.051] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:46:45.456] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 1 0 0 0
[13:46:45.456] INFO: number of red-efficiency pixels: 965 523 968 2300 1927 1750 1928 1205 1051 1262 1603 964 1429 557 129 134
[13:46:45.456] INFO: number of X-ray hits detected: 288328 192788 296364 471167 463427 483962 508025 376456 375002 406687 393893 346171 372888 219986 86835 101232
[13:46:45.456] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:46:45.456] INFO: number of Vcal hits detected: 206541 207367 206499 204022 205017 205446 204939 206260 206644 206263 205749 206818 206072 207311 207864 207864
[13:46:45.456] INFO: Vcal hit fiducial efficiency (%): 99.4 99.7 99.4 98.3 98.7 98.9 98.7 99.2 99.4 99.3 99.0 99.5 99.2 99.7 99.9 99.9
[13:46:45.456] INFO: Vcal hit overall efficiency (%): 99.3 99.7 99.3 98.1 98.6 98.8 98.5 99.2 99.3 99.2 98.9 99.4 99.1 99.7 99.9 99.9
[13:46:45.456] INFO: X-ray hit rate [MHz/cm2]: 84.5 56.5 86.9 138.1 135.8 141.9 148.9 110.3 109.9 119.2 115.5 101.5 109.3 64.5 25.5 29.7
[13:46:45.456] INFO: PixTestHighRate::doXPixelAlive() done
[13:46:45.501] INFO: PixTest:: pg_setup set to default.
[13:46:45.511] INFO: enter test to run
[13:47:27.971] INFO: test: exit no parameter change
[13:47:28.304] QUIET: Connection to board 33 closed.
[13:47:28.305] INFO: pXar: this is the end, my friend