[17:05:55.319] INFO: *** Welcome to pxar ***
[17:05:55.319] INFO: *** Today: 2016/11/17
[17:05:56.107] INFO: *** Version: v1.9.0-818-g96727
[17:05:56.107] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//dacParameters35_C15.dat
[17:05:56.153] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//tbmParameters_C0b.dat
[17:05:56.153] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//defaultMaskFile.dat
[17:05:56.162] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C15.dat
[17:05:56.266] INFO: clk: 4
[17:05:56.266] INFO: ctr: 4
[17:05:56.266] INFO: sda: 19
[17:05:56.266] INFO: tin: 9
[17:05:56.266] INFO: level: 15
[17:05:56.266] INFO: triggerdelay: 0
[17:05:56.266] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[17:05:56.266] INFO: Log level: INFO
[17:05:56.284] QUIET: Connection to board DTB_WREHUL opened.
[17:05:56.287] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 30
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREHUL
MAC address: 40D85511801E
Hostname: pixelDTB030
Comment:
------------------------------------------------------
[17:05:56.290] INFO: RPC call hashes of host and DTB match: 398089610
[17:05:57.820] INFO: DUT info:
[17:05:57.821] INFO: The DUT currently contains the following objects:
[17:05:57.821] INFO: 2 TBM Cores tbm08c (2 ON)
[17:05:57.835] INFO: TBM Core alpha (0): 7 registers set
[17:05:57.835] INFO: TBM Core beta (1): 7 registers set
[17:05:57.835] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[17:05:57.835] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[17:05:57.835] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[17:05:57.835] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[17:05:57.835] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[17:05:57.835] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[17:05:57.835] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[17:05:57.835] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[17:05:57.835] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[17:05:57.835] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[17:05:57.835] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[17:05:57.835] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[17:05:57.835] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[17:05:57.835] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[17:05:57.835] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[17:05:57.835] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[17:05:57.835] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[17:05:58.279] INFO: enter 'restricted' command line mode
[17:05:58.279] INFO: enter test to run
[17:06:08.960] INFO: test: PixelAlive no parameter change
[17:06:08.960] INFO: running: pixelalive
[17:06:08.969] INFO: ----------------------------------------------------------------------
[17:06:08.969] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[17:06:08.969] INFO: ----------------------------------------------------------------------
[17:06:09.288] INFO: Expecting 41600 events.
[17:06:13.620] INFO: 41600 events read in total (3613ms).
[17:06:13.785] INFO: Test took 4812ms.
[17:06:13.800] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:06:14.076] INFO: PixTestAlive::aliveTest() done
[17:06:14.076] INFO: number of dead pixels (per ROC): 4 11 0 1 0 0 0 0 0 0 0 0 0 0 0 0
[17:06:14.109] INFO: enter test to run
[17:06:51.216] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[17:06:51.216] INFO: running: highrate
[17:06:51.216] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[17:06:51.434] INFO: ----------------------------------------------------------------------
[17:06:51.434] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[17:06:51.434] INFO: ----------------------------------------------------------------------
[17:06:51.434] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[17:06:51.434] INFO: edge/corner pixel THR is adjusted
[17:06:51.434] INFO: PixTestHighRate::trimHotPixels: step 0...
[17:06:52.394] INFO: Collecting data for 5 seconds...
[17:06:57.412] INFO: Done with hot pixel readout
[17:07:08.414] INFO: PixTest:: pg_setup set to default.
[17:07:08.415] INFO: 0 hot pixels found in step 0
[17:07:08.426] INFO: 0 hot pixels could not be trimmed and have been masked.
[17:07:08.456] INFO: PixTest::trimHotPixels() done
[17:07:08.492] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C0.dat
[17:07:08.497] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C1.dat
[17:07:08.502] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C2.dat
[17:07:08.508] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C3.dat
[17:07:08.513] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C4.dat
[17:07:08.518] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C5.dat
[17:07:08.524] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C6.dat
[17:07:08.529] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C7.dat
[17:07:08.534] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C8.dat
[17:07:08.539] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C9.dat
[17:07:08.544] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C10.dat
[17:07:08.550] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C11.dat
[17:07:08.555] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C12.dat
[17:07:08.560] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C13.dat
[17:07:08.565] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C14.dat
[17:07:08.571] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C15.dat
[17:07:08.576] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//defaultMaskFile.dat
[17:07:08.594] INFO: enter test to run
[17:08:01.847] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[17:08:01.848] INFO: running: highrate
[17:08:01.852] INFO: ----------------------------------------------------------------------
[17:08:01.852] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[17:08:01.852] INFO: ----------------------------------------------------------------------
[17:08:01.852] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[17:08:01.852] INFO: edge/corner pixel THR is adjusted
[17:08:01.852] INFO: PixTestHighRate::trimHotPixels: step 0...
[17:08:02.811] INFO: Collecting data for 1 seconds...
[17:08:03.815] INFO: Done with hot pixel readout
[17:08:07.514] INFO: PixTest:: pg_setup set to default.
[17:08:07.515] INFO: 0 hot pixels found in step 0
[17:08:07.521] INFO: 0 hot pixels could not be trimmed and have been masked.
[17:08:07.626] INFO: PixTest::trimHotPixels() done
[17:08:07.626] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C0.dat
[17:08:07.636] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C1.dat
[17:08:07.641] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C2.dat
[17:08:07.646] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C3.dat
[17:08:07.652] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C4.dat
[17:08:07.657] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C5.dat
[17:08:07.662] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C6.dat
[17:08:07.667] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C7.dat
[17:08:07.673] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C8.dat
[17:08:07.678] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C9.dat
[17:08:07.683] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C10.dat
[17:08:07.688] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C11.dat
[17:08:07.694] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C12.dat
[17:08:07.699] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C13.dat
[17:08:07.704] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C14.dat
[17:08:07.710] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//trimParameters35_C15.dat
[17:08:07.715] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-5-09_FPIXTest-17C-FNAL-161115-1301-150V_2016-11-15_13h01m_1479236478/000_FPIXTest_p17//defaultMaskFile.dat
[17:08:07.736] INFO: enter test to run
[17:08:32.223] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[17:08:32.223] INFO: running: xray
[17:08:32.236] INFO: ----------------------------------------------------------------------
[17:08:32.236] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[17:08:32.236] INFO: ----------------------------------------------------------------------
[17:08:33.199] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[17:08:45.444] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[17:09:14.673] INFO: Resuming triggers.
[17:09:26.919] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[17:09:56.192] INFO: Resuming triggers.
[17:10:08.441] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[17:10:37.524] INFO: Resuming triggers.
[17:10:49.773] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[17:11:19.248] INFO: Resuming triggers.
[17:11:31.498] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[17:12:00.767] INFO: Resuming triggers.
[17:12:13.018] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[17:12:42.636] INFO: Resuming triggers.
[17:12:54.885] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[17:13:24.390] INFO: Resuming triggers.
[17:13:36.640] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[17:14:06.122] INFO: Resuming triggers.
[17:14:08.482] INFO: data taking finished, elapsed time: 100 seconds.
[17:14:14.531] INFO: PixTest:: pg_setup set to default.
[17:14:14.534] INFO: PixTestXray::doPhRun() done
[17:14:14.670] INFO: enter test to run
[17:15:08.373] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[17:15:08.373] INFO: running: xray
[17:15:08.374] INFO: ----------------------------------------------------------------------
[17:15:08.374] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[17:15:08.374] INFO: ----------------------------------------------------------------------
[17:15:09.337] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[17:15:16.935] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[17:15:47.616] INFO: Resuming triggers.
[17:15:55.214] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[17:16:26.417] INFO: Resuming triggers.
[17:16:34.013] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[17:17:05.072] INFO: Resuming triggers.
[17:17:12.670] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[17:17:44.031] INFO: Resuming triggers.
[17:17:51.626] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[17:18:22.581] INFO: Resuming triggers.
[17:18:30.176] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[17:19:01.497] INFO: Resuming triggers.
[17:19:09.091] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[17:19:39.104] INFO: Resuming triggers.
[17:19:46.699] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[17:20:16.759] INFO: Resuming triggers.
[17:20:24.354] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[17:20:54.552] INFO: Resuming triggers.
[17:21:02.148] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[17:21:32.310] INFO: Resuming triggers.
[17:21:39.907] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[17:22:10.053] INFO: Resuming triggers.
[17:22:17.647] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[17:22:47.725] INFO: Resuming triggers.
[17:22:55.323] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[17:23:25.498] INFO: Resuming triggers.
[17:23:27.101] INFO: data taking finished, elapsed time: 100 seconds.
[17:23:33.818] INFO: PixTest:: pg_setup set to default.
[17:23:33.822] INFO: PixTestXray::doPhRun() done
[17:23:33.972] INFO: enter test to run
[17:23:57.913] INFO: test: HighRate no parameter change
[17:23:57.913] INFO: running: highrate
[17:23:57.930] INFO: ----------------------------------------------------------------------
[17:23:57.930] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:23:57.930] INFO: ----------------------------------------------------------------------
[17:23:58.084] INFO: Expecting 768 events.
[17:23:59.218] INFO: 768 events read in total (418ms).
[17:23:59.218] INFO: Test took 1268ms.
[17:24:00.021] INFO: Expecting 41600 events.
[17:24:03.040] INFO: 41600 events read in total (2492ms).
[17:24:03.041] INFO: Test took 3805ms.
[17:24:03.069] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:03.802] INFO: Expecting 41600 events.
[17:24:06.942] INFO: 41600 events read in total (2613ms).
[17:24:06.943] INFO: Test took 3857ms.
[17:24:06.972] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:07.703] INFO: Expecting 41600 events.
[17:24:10.869] INFO: 41600 events read in total (2639ms).
[17:24:10.870] INFO: Test took 3882ms.
[17:24:10.899] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:11.632] INFO: Expecting 41600 events.
[17:24:14.817] INFO: 41600 events read in total (2658ms).
[17:24:14.818] INFO: Test took 3902ms.
[17:24:14.847] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:15.582] INFO: Expecting 41600 events.
[17:24:18.765] INFO: 41600 events read in total (2656ms).
[17:24:18.766] INFO: Test took 3903ms.
[17:24:18.795] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:19.528] INFO: Expecting 41600 events.
[17:24:22.710] INFO: 41600 events read in total (2655ms).
[17:24:22.711] INFO: Test took 3899ms.
[17:24:22.741] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:23.470] INFO: Expecting 41600 events.
[17:24:26.661] INFO: 41600 events read in total (2664ms).
[17:24:26.661] INFO: Test took 3905ms.
[17:24:26.691] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:27.423] INFO: Expecting 41600 events.
[17:24:30.608] INFO: 41600 events read in total (2658ms).
[17:24:30.609] INFO: Test took 3902ms.
[17:24:30.639] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:31.372] INFO: Expecting 41600 events.
[17:24:34.558] INFO: 41600 events read in total (2659ms).
[17:24:34.559] INFO: Test took 3903ms.
[17:24:34.588] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:35.322] INFO: Expecting 41600 events.
[17:24:38.538] INFO: 41600 events read in total (2689ms).
[17:24:38.539] INFO: Test took 3934ms.
[17:24:38.568] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:39.298] INFO: Expecting 41600 events.
[17:24:42.488] INFO: 41600 events read in total (2663ms).
[17:24:42.489] INFO: Test took 3903ms.
[17:24:42.517] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:43.250] INFO: Expecting 41600 events.
[17:24:46.439] INFO: 41600 events read in total (2662ms).
[17:24:46.440] INFO: Test took 3907ms.
[17:24:46.478] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:47.204] INFO: Expecting 41600 events.
[17:24:50.398] INFO: 41600 events read in total (2667ms).
[17:24:50.399] INFO: Test took 3903ms.
[17:24:50.428] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:51.163] INFO: Expecting 41600 events.
[17:24:54.366] INFO: 41600 events read in total (2676ms).
[17:24:54.367] INFO: Test took 3921ms.
[17:24:54.398] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:55.127] INFO: Expecting 41600 events.
[17:24:58.338] INFO: 41600 events read in total (2684ms).
[17:24:58.339] INFO: Test took 3924ms.
[17:24:58.369] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:59.099] INFO: Expecting 41600 events.
[17:25:02.277] INFO: 41600 events read in total (2651ms).
[17:25:02.278] INFO: Test took 3891ms.
[17:25:02.306] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:25:03.041] INFO: Expecting 41600 events.
[17:25:06.222] INFO: 41600 events read in total (2654ms).
[17:25:06.223] INFO: Test took 3899ms.
[17:25:06.252] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:25:06.982] INFO: Expecting 41600 events.
[17:25:10.170] INFO: 41600 events read in total (2661ms).
[17:25:10.171] INFO: Test took 3902ms.
[17:25:10.200] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:25:10.930] INFO: Expecting 41600 events.
[17:25:14.119] INFO: 41600 events read in total (2662ms).
[17:25:14.120] INFO: Test took 3903ms.
[17:25:14.149] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:25:14.881] INFO: Expecting 41600 events.
[17:25:17.983] INFO: 41600 events read in total (2575ms).
[17:25:17.984] INFO: Test took 3819ms.
[17:25:18.012] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:25:18.385] INFO: enter test to run
[17:25:22.152] INFO: test: HighRate no parameter change
[17:25:22.152] INFO: running: highrate
[17:25:22.153] INFO: ----------------------------------------------------------------------
[17:25:22.153] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:25:22.153] INFO: ----------------------------------------------------------------------
[17:25:22.765] INFO: Expecting 208000 events.
[17:25:34.402] INFO: 208000 events read in total (11110ms).
[17:25:34.404] INFO: Test took 12244ms.
[17:25:34.526] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:25:34.770] INFO: number of dead pixels (per ROC): 4 10 0 1 0 0 0 0 0 0 0 0 0 0 0 0
[17:25:34.770] INFO: number of red-efficiency pixels: 54 42 49 85 135 143 112 84 81 137 89 78 88 51 14 20
[17:25:34.770] INFO: number of X-ray hits detected: 48557 33340 51681 78023 84586 94956 93618 65891 65986 90046 85482 68171 75578 43578 17470 20213
[17:25:34.770] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:25:34.770] INFO: number of Vcal hits detected: 207747 207421 207950 207865 207860 207855 207884 207915 207915 207860 207911 207922 207909 207949 207986 207980
[17:25:34.770] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 99.9 99.9 99.9 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0
[17:25:34.770] INFO: Vcal hit overall efficiency (%): 99.9 99.7 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0
[17:25:34.770] INFO: X-ray hit rate [MHz/cm2]: 14.2 9.8 15.1 22.9 24.8 27.8 27.4 19.3 19.3 26.4 25.1 20.0 22.2 12.8 5.1 5.9
[17:25:34.770] INFO: PixTestHighRate::doXPixelAlive() done
[17:25:34.818] INFO: PixTest:: pg_setup set to default.
[17:25:34.830] INFO: enter test to run
[17:26:01.960] INFO: test: HighRate no parameter change
[17:26:01.960] INFO: running: highrate
[17:26:01.961] INFO: ----------------------------------------------------------------------
[17:26:01.961] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:26:01.961] INFO: ----------------------------------------------------------------------
[17:26:02.584] INFO: Expecting 208000 events.
[17:26:15.894] INFO: 208000 events read in total (12783ms).
[17:26:15.898] INFO: Test took 13928ms.
[17:26:16.148] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:26:16.434] INFO: number of dead pixels (per ROC): 4 10 0 1 0 0 0 0 0 0 0 0 0 0 0 0
[17:26:16.435] INFO: number of red-efficiency pixels: 168 118 176 291 318 372 397 240 229 385 238 239 186 143 36 41
[17:26:16.435] INFO: number of X-ray hits detected: 100957 69391 105820 162144 175693 196248 193904 137124 136935 185217 176775 141903 156680 91309 36468 42366
[17:26:16.435] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:26:16.435] INFO: number of Vcal hits detected: 207625 207339 207817 207647 207664 207601 207566 207750 207761 207585 207744 207748 207809 207852 207964 207958
[17:26:16.435] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.9 99.9 99.8 99.8 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0 100.0
[17:26:16.435] INFO: Vcal hit overall efficiency (%): 99.8 99.7 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0 100.0
[17:26:16.435] INFO: X-ray hit rate [MHz/cm2]: 29.6 20.3 31.0 47.5 51.5 57.5 56.8 40.2 40.1 54.3 51.8 41.6 45.9 26.8 10.7 12.4
[17:26:16.435] INFO: PixTestHighRate::doXPixelAlive() done
[17:26:16.481] INFO: PixTest:: pg_setup set to default.
[17:26:16.494] INFO: enter test to run
[17:26:43.032] INFO: test: HighRate no parameter change
[17:26:43.032] INFO: running: highrate
[17:26:43.033] INFO: ----------------------------------------------------------------------
[17:26:43.033] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:26:43.033] INFO: ----------------------------------------------------------------------
[17:26:43.655] INFO: Expecting 208000 events.
[17:26:58.229] INFO: 208000 events read in total (14047ms).
[17:26:58.235] INFO: Test took 15194ms.
[17:26:58.608] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:26:58.933] INFO: number of dead pixels (per ROC): 4 11 0 1 0 0 0 0 0 0 0 0 0 0 0 0
[17:26:58.933] INFO: number of red-efficiency pixels: 295 178 387 553 703 916 893 514 420 886 532 510 478 313 57 89
[17:26:58.933] INFO: number of X-ray hits detected: 151884 105014 160156 245647 265240 297875 293970 206323 208059 280925 268033 214454 237537 137367 55024 64813
[17:26:58.933] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:26:58.933] INFO: number of Vcal hits detected: 207470 207271 207552 207332 207204 206893 206930 207381 207539 206946 207403 207427 207480 207637 207942 207910
[17:26:58.933] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.5 99.5 99.7 99.8 99.5 99.7 99.8 99.8 99.8 100.0 100.0
[17:26:58.933] INFO: Vcal hit overall efficiency (%): 99.7 99.6 99.8 99.7 99.6 99.5 99.5 99.7 99.8 99.5 99.7 99.7 99.8 99.8 100.0 100.0
[17:26:58.933] INFO: X-ray hit rate [MHz/cm2]: 44.5 30.8 46.9 72.0 77.7 87.3 86.2 60.5 61.0 82.3 78.6 62.9 69.6 40.3 16.1 19.0
[17:26:58.933] INFO: PixTestHighRate::doXPixelAlive() done
[17:26:58.979] INFO: PixTest:: pg_setup set to default.
[17:26:58.997] INFO: enter test to run
[17:27:16.223] INFO: test: exit no parameter change
[17:27:16.542] QUIET: Connection to board 30 closed.
[17:27:16.575] INFO: pXar: this is the end, my friend