[15:11:57.239] INFO: *** Welcome to pxar ***
[15:11:57.239] INFO: *** Today: 2016/03/31
[15:11:57.265] INFO: *** Version: v1.9.0-793-ge521
[15:11:57.265] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//dacParameters35_C15.dat
[15:11:57.299] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//tbmParameters_C0b.dat
[15:11:57.299] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//defaultMaskFile.dat
[15:11:57.306] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C15.dat
[15:11:57.400] INFO: clk: 4
[15:11:57.400] INFO: ctr: 4
[15:11:57.400] INFO: sda: 19
[15:11:57.400] INFO: tin: 9
[15:11:57.400] INFO: level: 15
[15:11:57.400] INFO: triggerdelay: 0
[15:11:57.400] QUIET: Instanciating API for pxar v1.9.0+788~g8b11555
[15:11:57.400] INFO: Log level: INFO
[15:11:57.417] QUIET: Connection to board DTB_WREKRL opened.
[15:11:57.421] 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:11:57.424] INFO: RPC call hashes of host and DTB match: 398089610
[15:11:58.957] INFO: DUT info:
[15:11:58.957] INFO: The DUT currently contains the following objects:
[15:11:58.957] INFO: 2 TBM Cores tbm08c (2 ON)
[15:11:58.957] INFO: TBM Core alpha (0): 7 registers set
[15:11:58.957] INFO: TBM Core beta (1): 7 registers set
[15:11:58.957] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[15:11:58.957] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:58.957] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:58.957] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:58.957] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:58.957] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:58.958] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:58.958] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:58.958] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:58.958] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:58.958] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:58.958] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:58.958] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:58.958] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:58.958] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:58.958] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:58.958] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[15:11:59.361] INFO: enter 'restricted' command line mode
[15:11:59.361] INFO: enter test to run
[15:12:57.271] INFO: test: PixelAlive no parameter change
[15:12:57.271] INFO: running: pixelalive
[15:12:57.281] INFO: ----------------------------------------------------------------------
[15:12:57.281] 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:12:57.281] INFO: ----------------------------------------------------------------------
[15:12:57.601] INFO: Expecting 41600 events.
[15:13:01.927] INFO: 41600 events read in total (3607ms).
[15:13:02.095] INFO: Test took 4812ms.
[15:13:02.107] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:13:02.371] INFO: PixTestAlive::aliveTest() done
[15:13:02.371] INFO: number of dead pixels (per ROC): 1 3 0 0 0 4 0 0 0 0 71 0 0 0 0 0
[15:13:02.409] INFO: enter test to run
[15:13:55.079] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[15:13:55.079] INFO: running: highrate
[15:13:55.079] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[15:13:55.293] INFO: ----------------------------------------------------------------------
[15:13:55.293] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[15:13:55.293] INFO: ----------------------------------------------------------------------
[15:13:55.293] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[15:13:55.293] INFO: edge/corner pixel THR is adjusted
[15:13:55.293] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:13:56.253] INFO: Collecting data for 5 seconds...
[15:14:01.269] INFO: Done with hot pixel readout
[15:14:09.307] INFO: PixTest:: pg_setup set to default.
[15:14:09.308] INFO: 0 hot pixels found in step 0
[15:14:09.311] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:14:09.481] INFO: PixTest::trimHotPixels() done
[15:14:09.481] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C0.dat
[15:14:09.489] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C1.dat
[15:14:09.494] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C2.dat
[15:14:09.500] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C3.dat
[15:14:09.505] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C4.dat
[15:14:09.510] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C5.dat
[15:14:09.515] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C6.dat
[15:14:09.521] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C7.dat
[15:14:09.526] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C8.dat
[15:14:09.531] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C9.dat
[15:14:09.536] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C10.dat
[15:14:09.542] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C11.dat
[15:14:09.547] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C12.dat
[15:14:09.552] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C13.dat
[15:14:09.557] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C14.dat
[15:14:09.563] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C15.dat
[15:14:09.568] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//defaultMaskFile.dat
[15:14:09.573] INFO: enter test to run
[15:14:50.503] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[15:14:50.503] INFO: running: highrate
[15:14:50.507] INFO: ----------------------------------------------------------------------
[15:14:50.507] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[15:14:50.507] INFO: ----------------------------------------------------------------------
[15:14:50.507] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[15:14:50.507] INFO: edge/corner pixel THR is adjusted
[15:14:50.507] INFO: PixTestHighRate::trimHotPixels: step 0...
[15:14:51.466] INFO: Collecting data for 1 seconds...
[15:14:52.469] INFO: Done with hot pixel readout
[15:14:55.445] INFO: PixTest:: pg_setup set to default.
[15:14:55.446] INFO: 0 hot pixels found in step 0
[15:14:55.452] INFO: 0 hot pixels could not be trimmed and have been masked.
[15:14:55.581] INFO: PixTest::trimHotPixels() done
[15:14:55.581] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C0.dat
[15:14:55.591] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C1.dat
[15:14:55.596] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C2.dat
[15:14:55.602] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C3.dat
[15:14:55.607] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C4.dat
[15:14:55.612] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C5.dat
[15:14:55.617] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C6.dat
[15:14:55.622] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C7.dat
[15:14:55.628] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C8.dat
[15:14:55.633] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C9.dat
[15:14:55.638] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C10.dat
[15:14:55.643] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C11.dat
[15:14:55.648] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C12.dat
[15:14:55.654] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C13.dat
[15:14:55.659] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C14.dat
[15:14:55.664] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//trimParameters35_C15.dat
[15:14:55.669] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-K-2-32_FPIXTest-17C-FNAL-160330-1306_2016-03-30_13h06m_1459361182/000_FPIXTest_p17//defaultMaskFile.dat
[15:14:55.684] INFO: enter test to run
[15:15:49.430] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[15:15:49.430] INFO: running: xray
[15:15:49.431] INFO: ----------------------------------------------------------------------
[15:15:49.431] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:15:49.431] INFO: ----------------------------------------------------------------------
[15:15:50.395] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:16:07.523] INFO: run duration 17 seconds, buffer almost full (81%), pausing triggers.
[15:16:33.942] INFO: Resuming triggers.
[15:16:51.071] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[15:17:14.955] INFO: Resuming triggers.
[15:17:32.085] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[15:17:58.527] INFO: Resuming triggers.
[15:18:15.657] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[15:18:42.085] INFO: Resuming triggers.
[15:18:59.215] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[15:19:23.812] INFO: Resuming triggers.
[15:19:38.498] INFO: data taking finished, elapsed time: 100 seconds.
[15:20:01.373] INFO: PixTest:: pg_setup set to default.
[15:20:01.376] INFO: PixTestXray::doPhRun() done
[15:20:01.418] INFO: enter test to run
[15:21:23.812] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[15:21:23.812] INFO: running: xray
[15:21:23.813] INFO: ----------------------------------------------------------------------
[15:21:23.813] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:21:23.813] INFO: ----------------------------------------------------------------------
[15:21:24.777] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:21:34.122] INFO: run duration 9 seconds, buffer almost full (81%), pausing triggers.
[15:22:03.899] INFO: Resuming triggers.
[15:22:13.247] INFO: run duration 18 seconds, buffer almost full (81%), pausing triggers.
[15:22:43.066] INFO: Resuming triggers.
[15:22:52.410] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[15:23:22.153] INFO: Resuming triggers.
[15:23:31.500] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[15:24:01.281] INFO: Resuming triggers.
[15:24:10.626] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[15:24:40.447] INFO: Resuming triggers.
[15:24:49.792] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[15:25:19.495] INFO: Resuming triggers.
[15:25:28.840] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[15:25:58.683] INFO: Resuming triggers.
[15:26:08.029] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[15:26:37.781] INFO: Resuming triggers.
[15:26:47.128] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[15:27:17.054] INFO: Resuming triggers.
[15:27:26.403] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[15:27:56.106] INFO: Resuming triggers.
[15:28:02.972] INFO: data taking finished, elapsed time: 100 seconds.
[15:28:25.167] INFO: PixTest:: pg_setup set to default.
[15:28:25.171] INFO: PixTestXray::doPhRun() done
[15:28:25.312] INFO: enter test to run
[15:29:11.280] INFO: test: HighRate no parameter change
[15:29:11.280] INFO: running: highrate
[15:29:11.281] INFO: ----------------------------------------------------------------------
[15:29:11.281] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:29:11.281] INFO: ----------------------------------------------------------------------
[15:29:11.419] INFO: Expecting 768 events.
[15:29:12.553] INFO: 768 events read in total (418ms).
[15:29:12.554] INFO: Test took 1267ms.
[15:29:13.357] INFO: Expecting 41600 events.
[15:29:16.185] INFO: 41600 events read in total (2301ms).
[15:29:16.186] INFO: Test took 3624ms.
[15:29:16.199] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:16.963] INFO: Expecting 41600 events.
[15:29:19.944] INFO: 41600 events read in total (2454ms).
[15:29:19.944] INFO: Test took 3733ms.
[15:29:19.959] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:20.719] INFO: Expecting 41600 events.
[15:29:23.751] INFO: 41600 events read in total (2505ms).
[15:29:23.751] INFO: Test took 3782ms.
[15:29:23.766] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:24.527] INFO: Expecting 41600 events.
[15:29:27.577] INFO: 41600 events read in total (2523ms).
[15:29:27.578] INFO: Test took 3801ms.
[15:29:27.593] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:28.354] INFO: Expecting 41600 events.
[15:29:31.391] INFO: 41600 events read in total (2510ms).
[15:29:31.391] INFO: Test took 3787ms.
[15:29:31.406] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:32.164] INFO: Expecting 41600 events.
[15:29:35.209] INFO: 41600 events read in total (2518ms).
[15:29:35.210] INFO: Test took 3792ms.
[15:29:35.225] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:35.984] INFO: Expecting 41600 events.
[15:29:39.038] INFO: 41600 events read in total (2527ms).
[15:29:39.039] INFO: Test took 3802ms.
[15:29:39.053] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:39.814] INFO: Expecting 41600 events.
[15:29:42.864] INFO: 41600 events read in total (2523ms).
[15:29:42.865] INFO: Test took 3800ms.
[15:29:42.880] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:43.639] INFO: Expecting 41600 events.
[15:29:46.670] INFO: 41600 events read in total (2504ms).
[15:29:46.671] INFO: Test took 3779ms.
[15:29:46.686] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:47.447] INFO: Expecting 41600 events.
[15:29:50.550] INFO: 41600 events read in total (2576ms).
[15:29:50.551] INFO: Test took 3855ms.
[15:29:50.571] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:51.322] INFO: Expecting 41600 events.
[15:29:54.581] INFO: 41600 events read in total (2732ms).
[15:29:54.582] INFO: Test took 3997ms.
[15:29:54.619] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:55.337] INFO: Expecting 41600 events.
[15:29:58.603] INFO: 41600 events read in total (2739ms).
[15:29:58.605] INFO: Test took 3966ms.
[15:29:58.642] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:29:59.360] INFO: Expecting 41600 events.
[15:30:02.624] INFO: 41600 events read in total (2737ms).
[15:30:02.625] INFO: Test took 3963ms.
[15:30:02.663] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:03.380] INFO: Expecting 41600 events.
[15:30:06.657] INFO: 41600 events read in total (2750ms).
[15:30:06.658] INFO: Test took 3976ms.
[15:30:06.695] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:07.415] INFO: Expecting 41600 events.
[15:30:10.675] INFO: 41600 events read in total (2733ms).
[15:30:10.676] INFO: Test took 3962ms.
[15:30:10.713] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:11.431] INFO: Expecting 41600 events.
[15:30:14.719] INFO: 41600 events read in total (2761ms).
[15:30:14.720] INFO: Test took 3986ms.
[15:30:14.758] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:15.474] INFO: Expecting 41600 events.
[15:30:18.753] INFO: 41600 events read in total (2752ms).
[15:30:18.754] INFO: Test took 3977ms.
[15:30:18.793] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:19.510] INFO: Expecting 41600 events.
[15:30:22.769] INFO: 41600 events read in total (2732ms).
[15:30:22.770] INFO: Test took 3956ms.
[15:30:22.807] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:23.525] INFO: Expecting 41600 events.
[15:30:26.780] INFO: 41600 events read in total (2729ms).
[15:30:26.781] INFO: Test took 3955ms.
[15:30:26.818] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:27.539] INFO: Expecting 41600 events.
[15:30:30.699] INFO: 41600 events read in total (2633ms).
[15:30:30.700] INFO: Test took 3862ms.
[15:30:30.737] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:30:31.086] INFO: enter test to run
[15:31:51.319] INFO: test: HighRate no parameter change
[15:31:51.319] INFO: running: highrate
[15:31:51.320] INFO: ----------------------------------------------------------------------
[15:31:51.320] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:31:51.320] INFO: ----------------------------------------------------------------------
[15:31:51.934] INFO: Expecting 208000 events.
[15:32:04.008] INFO: 208000 events read in total (11547ms).
[15:32:04.012] INFO: Test took 12684ms.
[15:32:04.171] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:04.427] INFO: number of dead pixels (per ROC): 1 3 0 0 0 4 0 0 0 0 71 0 0 0 0 0
[15:32:04.427] INFO: number of red-efficiency pixels: 137 93 121 161 202 221 205 152 114 135 225 121 119 88 46 34
[15:32:04.427] INFO: number of X-ray hits detected: 77051 50065 77289 117587 124478 132689 131191 93674 83645 108843 108323 93021 95417 59954 24638 26029
[15:32:04.428] 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:32:04.428] INFO: number of Vcal hits detected: 207811 207756 207876 207834 207795 207575 207788 207843 207884 207860 204293 207874 207878 207909 207953 207965
[15:32:04.428] 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
[15:32:04.428] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.9 99.9 99.8 99.9 99.9 99.9 99.9 98.2 99.9 99.9 100.0 100.0 100.0
[15:32:04.428] INFO: X-ray hit rate [MHz/cm2]: 22.6 14.7 22.7 34.5 36.5 38.9 38.5 27.5 24.5 31.9 31.8 27.3 28.0 17.6 7.2 7.6
[15:32:04.428] INFO: PixTestHighRate::doXPixelAlive() done
[15:32:04.472] INFO: PixTest:: pg_setup set to default.
[15:32:04.485] INFO: enter test to run
[15:32:31.391] INFO: test: HighRate no parameter change
[15:32:31.391] INFO: running: highrate
[15:32:31.392] INFO: ----------------------------------------------------------------------
[15:32:31.392] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:32:31.392] INFO: ----------------------------------------------------------------------
[15:32:32.008] INFO: Expecting 208000 events.
[15:32:46.337] INFO: 208000 events read in total (13802ms).
[15:32:46.343] INFO: Test took 14943ms.
[15:32:46.689] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:32:46.005] INFO: number of dead pixels (per ROC): 1 3 0 0 0 4 0 0 0 0 71 0 0 0 0 0
[15:32:46.005] INFO: number of red-efficiency pixels: 435 209 447 674 757 872 868 530 394 616 606 446 458 296 117 65
[15:32:46.005] INFO: number of X-ray hits detected: 167978 108600 167679 257183 270495 289970 286809 205982 183309 237620 235814 203239 209274 131669 54057 56936
[15:32:46.005] 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:32:46.005] INFO: number of Vcal hits detected: 207464 207626 207476 207214 207135 206775 206985 207376 207579 207301 203861 207513 207502 207665 207875 207931
[15:32:46.005] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.7 99.6 99.6 99.5 99.7 99.8 99.7 99.7 99.8 99.8 99.9 99.9 100.0
[15:32:46.005] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.7 99.6 99.6 99.4 99.5 99.7 99.8 99.7 98.0 99.8 99.8 99.8 99.9 100.0
[15:32:46.006] INFO: X-ray hit rate [MHz/cm2]: 49.2 31.8 49.1 75.4 79.3 85.0 84.1 60.4 53.7 69.6 69.1 59.6 61.3 38.6 15.8 16.7
[15:32:46.006] INFO: PixTestHighRate::doXPixelAlive() done
[15:32:47.056] INFO: PixTest:: pg_setup set to default.
[15:32:47.073] INFO: enter test to run
[15:33:10.590] INFO: test: HighRate no parameter change
[15:33:10.590] INFO: running: highrate
[15:33:10.592] INFO: ----------------------------------------------------------------------
[15:33:10.592] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:33:10.592] INFO: ----------------------------------------------------------------------
[15:33:11.207] INFO: Expecting 208000 events.
[15:33:27.721] INFO: 208000 events read in total (15987ms).
[15:33:27.729] INFO: Test took 17129ms.
[15:33:28.243] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:33:28.612] INFO: number of dead pixels (per ROC): 1 3 0 0 0 4 0 0 0 0 71 0 0 0 0 0
[15:33:28.612] INFO: number of red-efficiency pixels: 799 440 949 1482 1688 1965 1927 1101 795 1289 1300 960 1022 630 163 113
[15:33:28.612] INFO: number of X-ray hits detected: 248299 161059 249496 380600 400665 429302 425177 305575 273712 351801 348346 300921 309870 195351 80805 85275
[15:33:28.612] 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:33:28.612] INFO: number of Vcal hits detected: 206842 207347 206560 205943 205532 204803 205042 206397 207035 206297 202845 206795 206748 207178 207818 207881
[15:33:28.612] INFO: Vcal hit fiducial efficiency (%): 99.5 99.8 99.4 99.1 98.9 98.7 98.7 99.3 99.6 99.3 99.3 99.5 99.5 99.6 99.9 99.9
[15:33:28.612] INFO: Vcal hit overall efficiency (%): 99.4 99.7 99.3 99.0 98.8 98.5 98.6 99.2 99.5 99.2 97.5 99.4 99.4 99.6 99.9 99.9
[15:33:28.612] INFO: X-ray hit rate [MHz/cm2]: 72.8 47.2 73.1 111.6 117.4 125.8 124.6 89.6 80.2 103.1 102.1 88.2 90.8 57.3 23.7 25.0
[15:33:28.612] INFO: PixTestHighRate::doXPixelAlive() done
[15:33:28.661] INFO: PixTest:: pg_setup set to default.
[15:33:28.678] INFO: enter test to run
[15:33:37.774] INFO: test: exit no parameter change
[15:33:38.062] QUIET: Connection to board 33 closed.
[15:33:38.063] INFO: pXar: this is the end, my friend