[15:43:14.208] INFO: *** Welcome to pxar ***
[15:43:14.208] INFO: *** Today: 2016/05/30
[15:43:14.225] INFO: *** Version: v1.9.0-796-gef167-dirty
[15:43:14.225] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//dacParameters35_C15.dat
[15:43:14.248] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//tbmParameters_C0b.dat
[15:43:14.248] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//defaultMaskFile.dat
[15:43:14.256] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C15.dat
[15:43:14.351] INFO: clk: 4
[15:43:14.351] INFO: ctr: 4
[15:43:14.351] INFO: sda: 19
[15:43:14.351] INFO: tin: 9
[15:43:14.351] INFO: level: 15
[15:43:14.351] INFO: triggerdelay: 0
[15:43:14.351] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[15:43:14.351] INFO: Log level: INFO
[15:43:14.368] QUIET: Connection to board DTB_WREKRL opened.
[15:43:14.372] 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:43:14.375] INFO: RPC call hashes of host and DTB match: 398089610
[15:43:15.906] INFO: DUT info:
[15:43:15.906] INFO: The DUT currently contains the following objects:
[15:43:15.906] INFO: 2 TBM Cores tbm08c (2 ON)
[15:43:15.906] INFO: TBM Core alpha (0): 7 registers set
[15:43:15.906] INFO: TBM Core beta (1): 7 registers set
[15:43:15.906] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:43:15.906] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:15.906] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:15.906] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:15.906] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:15.906] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:15.906] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:15.906] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:15.906] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:15.906] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:15.906] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:15.906] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:15.906] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:15.907] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:15.907] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:15.907] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:15.907] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:43:16.310] INFO: enter 'restricted' command line mode
[15:43:16.310] INFO: enter test to run
[15:43:21.906] INFO: test: PixelAlive no parameter change
[15:43:21.906] INFO: running: pixelalive
[15:43:21.915] INFO: ----------------------------------------------------------------------
[15:43:21.915] 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:43:21.915] INFO: ----------------------------------------------------------------------
[15:43:22.237] INFO: Expecting 41600 events.
[15:43:26.565] INFO: 41600 events read in total (3609ms).
[15:43:26.731] INFO: Test took 4812ms.
[15:43:26.745] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:43:27.007] INFO: PixTestAlive::aliveTest() done
[15:43:27.007] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0
[15:43:27.041] INFO: enter test to run
[15:45:06.897] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[15:45:06.897] INFO: running: highrate
[15:45:06.898] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[15:45:07.113] INFO: ----------------------------------------------------------------------
[15:45:07.113] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[15:45:07.113] INFO: ----------------------------------------------------------------------
[15:45:07.113] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[15:45:07.113] INFO: edge/corner pixel THR is adjusted
[15:45:07.113] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:45:08.072] INFO: Collecting data for 5 seconds...
[15:45:13.088] INFO: Done with hot pixel readout
[15:45:24.219] INFO: PixTest:: pg_setup set to default.
[15:45:24.220] INFO: 0 hot pixels found in step 0
[15:45:24.232] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:45:24.237] INFO: PixTest::trimHotPixels() done
[15:45:24.257] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C0.dat
[15:45:24.262] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C1.dat
[15:45:24.267] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C2.dat
[15:45:24.272] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C3.dat
[15:45:24.277] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C4.dat
[15:45:24.283] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C5.dat
[15:45:24.289] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C6.dat
[15:45:24.294] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C7.dat
[15:45:24.299] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C8.dat
[15:45:24.304] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C9.dat
[15:45:24.310] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C10.dat
[15:45:24.316] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C11.dat
[15:45:24.321] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C12.dat
[15:45:24.326] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C13.dat
[15:45:24.331] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C14.dat
[15:45:24.337] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C15.dat
[15:45:24.342] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//defaultMaskFile.dat
[15:45:24.353] INFO: enter test to run
[15:47:28.472] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[15:47:28.472] INFO: running: highrate
[15:47:28.476] INFO: ----------------------------------------------------------------------
[15:47:28.476] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[15:47:28.476] INFO: ----------------------------------------------------------------------
[15:47:28.476] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[15:47:28.476] INFO: edge/corner pixel THR is adjusted
[15:47:28.476] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:47:29.434] INFO: Collecting data for 1 seconds...
[15:47:30.438] INFO: Done with hot pixel readout
[15:47:34.042] INFO: PixTest:: pg_setup set to default.
[15:47:34.043] INFO: 0 hot pixels found in step 0
[15:47:34.048] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:47:34.155] INFO: PixTest::trimHotPixels() done
[15:47:34.156] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C0.dat
[15:47:34.164] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C1.dat
[15:47:34.169] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C2.dat
[15:47:34.174] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C3.dat
[15:47:34.180] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C4.dat
[15:47:34.185] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C5.dat
[15:47:34.190] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C6.dat
[15:47:34.195] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C7.dat
[15:47:34.201] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C8.dat
[15:47:34.206] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C9.dat
[15:47:34.211] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C10.dat
[15:47:34.216] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C11.dat
[15:47:34.221] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C12.dat
[15:47:34.227] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C13.dat
[15:47:34.232] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C14.dat
[15:47:34.237] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//trimParameters35_C15.dat
[15:47:34.242] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-3-07_FPIXTest-17C-FNAL-160525-1405_2016-05-25_14h05m_1464203134/000_FPIXTest_p17//defaultMaskFile.dat
[15:47:34.251] INFO: enter test to run
[15:48:13.392] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[15:48:13.392] INFO: running: xray
[15:48:13.393] INFO: ----------------------------------------------------------------------
[15:48:13.393] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:48:13.393] INFO: ----------------------------------------------------------------------
[15:48:14.356] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:48:26.518] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[15:48:55.919] INFO: Resuming triggers.
[15:49:08.088] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[15:49:37.428] INFO: Resuming triggers.
[15:49:49.593] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[15:50:18.891] INFO: Resuming triggers.
[15:50:31.062] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[15:51:00.343] INFO: Resuming triggers.
[15:51:12.512] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[15:51:41.924] INFO: Resuming triggers.
[15:51:54.088] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[15:52:23.360] INFO: Resuming triggers.
[15:52:35.526] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[15:53:04.783] INFO: Resuming triggers.
[15:53:16.947] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[15:53:46.271] INFO: Resuming triggers.
[15:53:49.250] INFO: data taking finished, elapsed time: 100 seconds.
[15:53:56.755] INFO: PixTest:: pg_setup set to default.
[15:53:56.758] INFO: PixTestXray::doPhRun() done
[15:53:56.892] INFO: enter test to run
[15:55:05.485] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[15:55:05.485] INFO: running: xray
[15:55:05.486] INFO: ----------------------------------------------------------------------
[15:55:05.486] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:55:05.486] INFO: ----------------------------------------------------------------------
[15:55:06.450] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:55:14.030] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[15:55:44.138] INFO: Resuming triggers.
[15:55:51.722] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[15:56:21.816] INFO: Resuming triggers.
[15:56:29.402] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[15:56:59.519] INFO: Resuming triggers.
[15:57:07.106] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[15:57:37.183] INFO: Resuming triggers.
[15:57:44.775] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[15:58:14.853] INFO: Resuming triggers.
[15:58:22.445] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[15:58:52.575] INFO: Resuming triggers.
[15:59:00.165] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[15:59:30.230] INFO: Resuming triggers.
[15:59:37.819] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[16:00:07.885] INFO: Resuming triggers.
[16:00:15.476] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[16:00:45.628] INFO: Resuming triggers.
[16:00:53.217] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[16:01:23.328] INFO: Resuming triggers.
[16:01:30.923] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[16:02:00.986] INFO: Resuming triggers.
[16:02:08.574] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[16:02:38.634] INFO: Resuming triggers.
[16:02:46.223] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[16:03:16.296] INFO: Resuming triggers.
[16:03:17.954] INFO: data taking finished, elapsed time: 100 seconds.
[16:03:24.887] INFO: PixTest:: pg_setup set to default.
[16:03:24.890] INFO: PixTestXray::doPhRun() done
[16:03:25.037] INFO: enter test to run
[16:04:32.233] INFO: test: HighRate no parameter change
[16:04:32.233] INFO: running: highrate
[16:04:32.234] INFO: ----------------------------------------------------------------------
[16:04:32.234] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:04:32.234] INFO: ----------------------------------------------------------------------
[16:04:32.374] INFO: Expecting 768 events.
[16:04:33.507] INFO: 768 events read in total (418ms).
[16:04:33.508] INFO: Test took 1268ms.
[16:04:34.311] INFO: Expecting 41600 events.
[16:04:37.295] INFO: 41600 events read in total (2457ms).
[16:04:37.296] INFO: Test took 3780ms.
[16:04:37.324] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:38.065] INFO: Expecting 41600 events.
[16:04:41.175] INFO: 41600 events read in total (2583ms).
[16:04:41.176] INFO: Test took 3836ms.
[16:04:41.204] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:41.942] INFO: Expecting 41600 events.
[16:04:45.109] INFO: 41600 events read in total (2640ms).
[16:04:45.110] INFO: Test took 3890ms.
[16:04:45.139] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:45.870] INFO: Expecting 41600 events.
[16:04:49.034] INFO: 41600 events read in total (2637ms).
[16:04:49.035] INFO: Test took 3880ms.
[16:04:49.064] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:49.797] INFO: Expecting 41600 events.
[16:04:52.994] INFO: 41600 events read in total (2670ms).
[16:04:52.995] INFO: Test took 3916ms.
[16:04:53.023] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:53.758] INFO: Expecting 41600 events.
[16:04:56.941] INFO: 41600 events read in total (2656ms).
[16:04:56.942] INFO: Test took 3902ms.
[16:04:56.971] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:04:57.700] INFO: Expecting 41600 events.
[16:05:00.878] INFO: 41600 events read in total (2651ms).
[16:05:00.879] INFO: Test took 3892ms.
[16:05:00.908] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:01.641] INFO: Expecting 41600 events.
[16:05:04.839] INFO: 41600 events read in total (2671ms).
[16:05:04.840] INFO: Test took 3917ms.
[16:05:04.869] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:05.602] INFO: Expecting 41600 events.
[16:05:08.776] INFO: 41600 events read in total (2647ms).
[16:05:08.777] INFO: Test took 3893ms.
[16:05:08.806] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:09.540] INFO: Expecting 41600 events.
[16:05:12.724] INFO: 41600 events read in total (2657ms).
[16:05:12.725] INFO: Test took 3904ms.
[16:05:12.754] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:13.489] INFO: Expecting 41600 events.
[16:05:16.684] INFO: 41600 events read in total (2668ms).
[16:05:16.685] INFO: Test took 3915ms.
[16:05:16.713] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:17.448] INFO: Expecting 41600 events.
[16:05:20.631] INFO: 41600 events read in total (2656ms).
[16:05:20.631] INFO: Test took 3901ms.
[16:05:20.660] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:21.393] INFO: Expecting 41600 events.
[16:05:24.586] INFO: 41600 events read in total (2666ms).
[16:05:24.587] INFO: Test took 3910ms.
[16:05:24.616] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:25.343] INFO: Expecting 41600 events.
[16:05:28.531] INFO: 41600 events read in total (2661ms).
[16:05:28.532] INFO: Test took 3899ms.
[16:05:28.561] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:29.295] INFO: Expecting 41600 events.
[16:05:32.496] INFO: 41600 events read in total (2674ms).
[16:05:32.497] INFO: Test took 3920ms.
[16:05:32.526] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:33.255] INFO: Expecting 41600 events.
[16:05:36.450] INFO: 41600 events read in total (2668ms).
[16:05:36.451] INFO: Test took 3908ms.
[16:05:36.480] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:37.216] INFO: Expecting 41600 events.
[16:05:40.396] INFO: 41600 events read in total (2653ms).
[16:05:40.397] INFO: Test took 3900ms.
[16:05:40.426] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:41.160] INFO: Expecting 41600 events.
[16:05:44.286] INFO: 41600 events read in total (2599ms).
[16:05:44.287] INFO: Test took 3844ms.
[16:05:44.316] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:45.051] INFO: Expecting 41600 events.
[16:05:48.097] INFO: 41600 events read in total (2519ms).
[16:05:48.098] INFO: Test took 3765ms.
[16:05:48.126] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:48.859] INFO: Expecting 41600 events.
[16:05:51.948] INFO: 41600 events read in total (2562ms).
[16:05:51.949] INFO: Test took 3806ms.
[16:05:51.978] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:05:52.349] INFO: enter test to run
[16:06:50.328] INFO: test: HighRate no parameter change
[16:06:50.328] INFO: running: highrate
[16:06:50.329] INFO: ----------------------------------------------------------------------
[16:06:50.329] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:06:50.329] INFO: ----------------------------------------------------------------------
[16:06:50.943] INFO: Expecting 208000 events.
[16:07:02.493] INFO: 208000 events read in total (11023ms).
[16:07:02.495] INFO: Test took 12158ms.
[16:07:02.614] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:07:02.856] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0
[16:07:02.856] INFO: number of red-efficiency pixels: 73 56 63 104 79 113 128 84 68 89 86 81 107 53 27 24
[16:07:02.856] INFO: number of X-ray hits detected: 56966 38647 55986 87559 90876 91165 93635 68524 60602 73308 75006 64914 68800 41643 19971 20722
[16:07:02.856] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:07:02.856] INFO: number of Vcal hits detected: 207926 207943 207936 207895 207914 207885 207871 207914 207932 207911 207913 207917 207891 207946 207924 207976
[16:07:02.856] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[16:07:02.856] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0
[16:07:02.856] INFO: X-ray hit rate [MHz/cm2]: 16.7 11.3 16.4 25.7 26.6 26.7 27.4 20.1 17.8 21.5 22.0 19.0 20.2 12.2 5.9 6.1
[16:07:02.856] INFO: PixTestHighRate::doXPixelAlive() done
[16:07:02.903] INFO: PixTest:: pg_setup set to default.
[16:07:02.916] INFO: enter test to run
[16:07:39.951] INFO: test: HighRate no parameter change
[16:07:39.951] INFO: running: highrate
[16:07:39.952] INFO: ----------------------------------------------------------------------
[16:07:39.952] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:07:39.952] INFO: ----------------------------------------------------------------------
[16:07:40.572] INFO: Expecting 208000 events.
[16:07:53.445] INFO: 208000 events read in total (12347ms).
[16:07:53.449] INFO: Test took 13488ms.
[16:07:53.690] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:07:53.970] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0
[16:07:53.970] INFO: number of red-efficiency pixels: 183 102 196 269 265 336 307 209 189 201 174 182 184 142 51 48
[16:07:53.970] INFO: number of X-ray hits detected: 116103 77773 114313 176733 183971 183780 190185 138812 123789 147866 152864 131482 139964 84134 40514 41956
[16:07:53.970] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:07:53.971] INFO: number of Vcal hits detected: 207814 207898 207794 207702 207714 207644 207673 207781 207803 207791 207817 207813 207808 207851 207899 207950
[16:07:53.971] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[16:07:53.971] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[16:07:53.971] INFO: X-ray hit rate [MHz/cm2]: 34.0 22.8 33.5 51.8 53.9 53.9 55.7 40.7 36.3 43.3 44.8 38.5 41.0 24.7 11.9 12.3
[16:07:53.971] INFO: PixTestHighRate::doXPixelAlive() done
[16:07:54.016] INFO: PixTest:: pg_setup set to default.
[16:07:54.027] INFO: enter test to run
[16:08:16.215] INFO: test: HighRate no parameter change
[16:08:16.215] INFO: running: highrate
[16:08:16.216] INFO: ----------------------------------------------------------------------
[16:08:16.216] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:08:16.216] INFO: ----------------------------------------------------------------------
[16:08:16.836] INFO: Expecting 208000 events.
[16:08:31.189] INFO: 208000 events read in total (13826ms).
[16:08:31.195] INFO: Test took 14969ms.
[16:08:31.554] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:08:31.874] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0
[16:08:31.874] INFO: number of red-efficiency pixels: 368 199 413 550 585 784 726 501 334 395 431 408 423 245 79 63
[16:08:31.874] INFO: number of X-ray hits detected: 176239 118295 173057 269399 280135 281804 288801 213153 188195 226093 233526 201759 213880 128619 61186 64551
[16:08:31.874] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:08:31.874] INFO: number of Vcal hits detected: 207587 207788 207520 207368 207333 207065 207139 207433 207635 207579 207539 207552 207560 207736 207872 207935
[16:08:31.874] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.7 99.7 99.6 99.6 99.7 99.8 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[16:08:31.874] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.7 99.7 99.6 99.6 99.7 99.8 99.8 99.8 99.8 99.8 99.9 99.9 100.0
[16:08:31.874] INFO: X-ray hit rate [MHz/cm2]: 51.7 34.7 50.7 79.0 82.1 82.6 84.6 62.5 55.2 66.3 68.4 59.1 62.7 37.7 17.9 18.9
[16:08:31.874] INFO: PixTestHighRate::doXPixelAlive() done
[16:08:31.921] INFO: PixTest:: pg_setup set to default.
[16:08:31.935] INFO: enter test to run
[16:10:06.958] INFO: test: exit no parameter change
[16:10:07.316] QUIET: Connection to board 33 closed.
[16:10:07.318] INFO: pXar: this is the end, my friend