[21:56:05.864] INFO: *** Welcome to pxar ***
[21:56:05.864] INFO: *** Today: 2016/10/06
[21:56:06.127] INFO: *** Version: v1.9.0-818-g96727
[21:56:06.127] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//dacParameters35_C15.dat
[21:56:06.132] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//tbmParameters_C0b.dat
[21:56:06.133] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//defaultMaskFile.dat
[21:56:06.133] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C15.dat
[21:56:06.224] INFO: clk: 4
[21:56:06.224] INFO: ctr: 4
[21:56:06.224] INFO: sda: 19
[21:56:06.224] INFO: tin: 9
[21:56:06.224] INFO: level: 15
[21:56:06.224] INFO: triggerdelay: 0
[21:56:06.224] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[21:56:06.224] INFO: Log level: INFO
[21:56:06.242] QUIET: Connection to board DTB_WREKRL opened.
[21:56:06.245] 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:
------------------------------------------------------
[21:56:06.248] INFO: RPC call hashes of host and DTB match: 398089610
[21:56:07.776] INFO: DUT info:
[21:56:07.776] INFO: The DUT currently contains the following objects:
[21:56:07.776] INFO: 2 TBM Cores tbm08c (2 ON)
[21:56:07.776] INFO: TBM Core alpha (0): 7 registers set
[21:56:07.776] INFO: TBM Core beta (1): 7 registers set
[21:56:07.776] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[21:56:07.776] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[21:56:07.776] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[21:56:07.776] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[21:56:07.776] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[21:56:07.776] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[21:56:07.776] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[21:56:07.776] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[21:56:07.776] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[21:56:07.776] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[21:56:07.776] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[21:56:07.777] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[21:56:07.777] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[21:56:07.777] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[21:56:07.777] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[21:56:07.777] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[21:56:07.777] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[21:56:08.180] INFO: enter 'restricted' command line mode
[21:56:08.180] INFO: enter test to run
[21:56:18.166] INFO: test: PixelAlive no parameter change
[21:56:18.167] INFO: running: pixelalive
[21:56:18.204] INFO: ----------------------------------------------------------------------
[21:56:18.204] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[21:56:18.204] INFO: ----------------------------------------------------------------------
[21:56:18.534] INFO: Expecting 41600 events.
[21:56:22.851] INFO: 41600 events read in total (3599ms).
[21:56:23.019] INFO: Test took 4810ms.
[21:56:23.032] INFO: Fetched DAQ statistics. Counters are being reset now.
[21:56:23.345] INFO: PixTestAlive::aliveTest() done
[21:56:23.345] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[21:56:23.375] INFO: enter test to run
[21:57:35.886] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[21:57:35.886] INFO: running: highrate
[21:57:35.886] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[21:57:36.053] INFO: ----------------------------------------------------------------------
[21:57:36.053] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[21:57:36.054] INFO: ----------------------------------------------------------------------
[21:57:36.054] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[21:57:36.054] INFO: edge/corner pixel THR is adjusted
[21:57:36.054] INFO: PixTestHighRate::trimHotPixels: step 0...
[21:57:37.013] INFO: Collecting data for 5 seconds...
[21:57:42.029] INFO: Done with hot pixel readout
[21:57:53.139] INFO: PixTest:: pg_setup set to default.
[21:57:53.140] INFO: 0 hot pixels found in step 0
[21:57:53.153] INFO: 0 hot pixels could not be trimmed and have been masked.
[21:57:53.171] INFO: PixTest::trimHotPixels() done
[21:57:53.210] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C0.dat
[21:57:53.216] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C1.dat
[21:57:53.222] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C2.dat
[21:57:53.227] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C3.dat
[21:57:53.233] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C4.dat
[21:57:53.239] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C5.dat
[21:57:53.244] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C6.dat
[21:57:53.250] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C7.dat
[21:57:53.256] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C8.dat
[21:57:53.261] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C9.dat
[21:57:53.267] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C10.dat
[21:57:53.273] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C11.dat
[21:57:53.278] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C12.dat
[21:57:53.284] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C13.dat
[21:57:53.290] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C14.dat
[21:57:53.296] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C15.dat
[21:57:53.301] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//defaultMaskFile.dat
[21:57:53.312] INFO: enter test to run
[21:58:34.070] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[21:58:34.070] INFO: running: highrate
[21:58:34.074] INFO: ----------------------------------------------------------------------
[21:58:34.074] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[21:58:34.074] INFO: ----------------------------------------------------------------------
[21:58:34.074] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[21:58:34.074] INFO: edge/corner pixel THR is adjusted
[21:58:34.074] INFO: PixTestHighRate::trimHotPixels: step 0...
[21:58:35.031] INFO: Collecting data for 1 seconds...
[21:58:36.035] INFO: Done with hot pixel readout
[21:58:39.736] INFO: PixTest:: pg_setup set to default.
[21:58:39.737] INFO: 0 hot pixels found in step 0
[21:58:39.744] INFO: 0 hot pixels could not be trimmed and have been masked.
[21:58:39.841] INFO: PixTest::trimHotPixels() done
[21:58:39.841] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C0.dat
[21:58:39.854] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C1.dat
[21:58:39.866] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C2.dat
[21:58:39.872] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C3.dat
[21:58:39.877] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C4.dat
[21:58:39.883] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C5.dat
[21:58:39.888] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C6.dat
[21:58:39.894] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C7.dat
[21:58:39.900] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C8.dat
[21:58:39.905] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C9.dat
[21:58:39.911] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C10.dat
[21:58:39.917] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C11.dat
[21:58:39.922] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C12.dat
[21:58:39.928] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C13.dat
[21:58:39.934] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C14.dat
[21:58:39.940] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//trimParameters35_C15.dat
[21:58:39.945] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-5-30_FPIXTest-17C-FNAL-161003-1228-150V_2016-10-03_12h28m_1475515713/000_FPIXTest_p17//defaultMaskFile.dat
[21:58:39.955] INFO: enter test to run
[21:59:25.885] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[21:59:25.885] INFO: running: xray
[21:59:25.886] INFO: ----------------------------------------------------------------------
[21:59:25.886] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[21:59:25.887] INFO: ----------------------------------------------------------------------
[21:59:26.850] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[21:59:39.016] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[22:00:08.686] INFO: Resuming triggers.
[22:00:20.853] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[22:00:49.914] INFO: Resuming triggers.
[22:01:02.080] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[22:01:31.331] INFO: Resuming triggers.
[22:01:43.499] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[22:02:13.088] INFO: Resuming triggers.
[22:02:25.259] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[22:02:55.118] INFO: Resuming triggers.
[22:03:07.288] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[22:03:37.378] INFO: Resuming triggers.
[22:03:49.552] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[22:04:19.787] INFO: Resuming triggers.
[22:04:31.955] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[22:05:02.045] INFO: Resuming triggers.
[22:05:05.070] INFO: data taking finished, elapsed time: 100 seconds.
[22:05:12.937] INFO: PixTest:: pg_setup set to default.
[22:05:12.940] INFO: PixTestXray::doPhRun() done
[22:05:13.101] INFO: enter test to run
[22:05:50.635] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[22:05:50.635] INFO: running: xray
[22:05:50.636] INFO: ----------------------------------------------------------------------
[22:05:50.636] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[22:05:50.636] INFO: ----------------------------------------------------------------------
[22:05:51.609] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[22:05:58.969] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[22:06:29.138] INFO: Resuming triggers.
[22:06:36.503] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[22:07:06.602] INFO: Resuming triggers.
[22:07:13.966] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[22:07:44.082] INFO: Resuming triggers.
[22:07:51.442] INFO: run duration 29 seconds, buffer almost full (81%), pausing triggers.
[22:08:21.570] INFO: Resuming triggers.
[22:08:28.927] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[22:08:59.009] INFO: Resuming triggers.
[22:09:06.367] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[22:09:36.457] INFO: Resuming triggers.
[22:09:43.815] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[22:10:13.600] INFO: Resuming triggers.
[22:10:20.958] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[22:10:51.036] INFO: Resuming triggers.
[22:10:58.401] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[22:11:28.564] INFO: Resuming triggers.
[22:11:35.927] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[22:12:06.103] INFO: Resuming triggers.
[22:12:13.465] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[22:12:44.578] INFO: Resuming triggers.
[22:12:51.937] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[22:13:22.763] INFO: Resuming triggers.
[22:13:30.123] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[22:14:01.041] INFO: Resuming triggers.
[22:14:05.691] INFO: data taking finished, elapsed time: 100 seconds.
[22:14:25.506] INFO: PixTest:: pg_setup set to default.
[22:14:25.509] INFO: PixTestXray::doPhRun() done
[22:14:25.660] INFO: enter test to run
[22:14:43.183] INFO: test: HighRate no parameter change
[22:14:43.183] INFO: running: highrate
[22:14:43.210] INFO: ----------------------------------------------------------------------
[22:14:43.210] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[22:14:43.210] INFO: ----------------------------------------------------------------------
[22:14:43.356] INFO: Expecting 768 events.
[22:14:44.512] INFO: 768 events read in total (434ms).
[22:14:44.512] INFO: Test took 1290ms.
[22:14:45.315] INFO: Expecting 41600 events.
[22:14:48.426] INFO: 41600 events read in total (2584ms).
[22:14:48.427] INFO: Test took 3907ms.
[22:14:48.455] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:14:49.191] INFO: Expecting 41600 events.
[22:14:52.331] INFO: 41600 events read in total (2613ms).
[22:14:52.332] INFO: Test took 3861ms.
[22:14:52.362] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:14:53.095] INFO: Expecting 41600 events.
[22:14:56.300] INFO: 41600 events read in total (2678ms).
[22:14:56.301] INFO: Test took 3923ms.
[22:14:56.339] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:14:57.053] INFO: Expecting 41600 events.
[22:15:00.213] INFO: 41600 events read in total (2634ms).
[22:15:00.214] INFO: Test took 3850ms.
[22:15:00.245] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:15:00.976] INFO: Expecting 41600 events.
[22:15:04.217] INFO: 41600 events read in total (2715ms).
[22:15:04.218] INFO: Test took 3957ms.
[22:15:04.249] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:15:04.982] INFO: Expecting 41600 events.
[22:15:08.169] INFO: 41600 events read in total (2660ms).
[22:15:08.170] INFO: Test took 3905ms.
[22:15:08.201] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:15:08.921] INFO: Expecting 41600 events.
[22:15:12.168] INFO: 41600 events read in total (2720ms).
[22:15:12.169] INFO: Test took 3952ms.
[22:15:12.200] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:15:12.931] INFO: Expecting 41600 events.
[22:15:16.140] INFO: 41600 events read in total (2682ms).
[22:15:16.141] INFO: Test took 3925ms.
[22:15:16.171] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:15:16.902] INFO: Expecting 41600 events.
[22:15:20.255] INFO: 41600 events read in total (2826ms).
[22:15:20.256] INFO: Test took 4069ms.
[22:15:20.286] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:15:21.020] INFO: Expecting 41600 events.
[22:15:24.244] INFO: 41600 events read in total (2698ms).
[22:15:24.244] INFO: Test took 3942ms.
[22:15:24.274] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:15:24.004] INFO: Expecting 41600 events.
[22:15:28.246] INFO: 41600 events read in total (2715ms).
[22:15:28.247] INFO: Test took 3957ms.
[22:15:28.279] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:15:29.009] INFO: Expecting 41600 events.
[22:15:32.167] INFO: 41600 events read in total (2632ms).
[22:15:32.168] INFO: Test took 3872ms.
[22:15:32.199] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:15:32.928] INFO: Expecting 41600 events.
[22:15:36.100] INFO: 41600 events read in total (2645ms).
[22:15:36.101] INFO: Test took 3886ms.
[22:15:36.131] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:15:36.856] INFO: Expecting 41600 events.
[22:15:39.948] INFO: 41600 events read in total (2565ms).
[22:15:39.949] INFO: Test took 3801ms.
[22:15:39.980] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:15:40.713] INFO: Expecting 41600 events.
[22:15:43.956] INFO: 41600 events read in total (2716ms).
[22:15:43.956] INFO: Test took 3960ms.
[22:15:43.986] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:15:44.719] INFO: Expecting 41600 events.
[22:15:47.936] INFO: 41600 events read in total (2690ms).
[22:15:47.937] INFO: Test took 3934ms.
[22:15:47.967] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:15:48.697] INFO: Expecting 41600 events.
[22:15:51.980] INFO: 41600 events read in total (2756ms).
[22:15:51.981] INFO: Test took 3998ms.
[22:15:52.011] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:15:52.736] INFO: Expecting 41600 events.
[22:15:56.095] INFO: 41600 events read in total (2832ms).
[22:15:56.095] INFO: Test took 4067ms.
[22:15:56.126] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:15:56.855] INFO: Expecting 41600 events.
[22:16:00.081] INFO: 41600 events read in total (2699ms).
[22:16:00.082] INFO: Test took 3940ms.
[22:16:00.113] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:16:00.844] INFO: Expecting 41600 events.
[22:16:03.896] INFO: 41600 events read in total (2525ms).
[22:16:03.897] INFO: Test took 3767ms.
[22:16:03.927] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:16:04.297] INFO: enter test to run
[22:16:23.334] INFO: test: HighRate no parameter change
[22:16:23.334] INFO: running: highrate
[22:16:23.336] INFO: ----------------------------------------------------------------------
[22:16:23.336] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[22:16:23.336] INFO: ----------------------------------------------------------------------
[22:16:23.955] INFO: Expecting 208000 events.
[22:16:35.961] INFO: 208000 events read in total (11480ms).
[22:16:35.964] INFO: Test took 12620ms.
[22:16:36.089] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:16:36.337] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[22:16:36.337] INFO: number of red-efficiency pixels: 74 43 76 92 145 90 122 76 67 92 1290 94 84 46 18 23
[22:16:36.337] INFO: number of X-ray hits detected: 56100 35215 57317 88799 97535 95814 97877 68987 61902 83023 79463 67312 66844 41033 17902 20879
[22:16:36.337] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[22:16:36.337] INFO: number of Vcal hits detected: 207923 207956 207923 207907 207849 207907 207877 207923 207932 207908 206388 207905 207916 207953 207982 207977
[22:16:36.337] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 99.9 100.0 99.9 100.0 100.0 100.0 99.2 100.0 100.0 100.0 100.0 100.0
[22:16:36.337] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 100.0 99.9 100.0 99.9 100.0 100.0 100.0 99.2 100.0 100.0 100.0 100.0 100.0
[22:16:36.337] INFO: X-ray hit rate [MHz/cm2]: 16.4 10.3 16.8 26.0 28.6 28.1 28.7 20.2 18.1 24.3 23.3 19.7 19.6 12.0 5.2 6.1
[22:16:36.337] INFO: PixTestHighRate::doXPixelAlive() done
[22:16:36.384] INFO: PixTest:: pg_setup set to default.
[22:16:36.400] INFO: enter test to run
[22:16:54.526] INFO: test: HighRate no parameter change
[22:16:54.526] INFO: running: highrate
[22:16:54.527] INFO: ----------------------------------------------------------------------
[22:16:54.527] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[22:16:54.527] INFO: ----------------------------------------------------------------------
[22:16:55.151] INFO: Expecting 208000 events.
[22:17:08.552] INFO: 208000 events read in total (12874ms).
[22:17:08.557] INFO: Test took 14021ms.
[22:17:08.822] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:17:09.111] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[22:17:09.111] INFO: number of red-efficiency pixels: 176 93 228 295 414 335 327 203 201 263 1409 188 246 137 50 54
[22:17:09.111] INFO: number of X-ray hits detected: 115826 72861 118313 182732 201840 197664 202453 143446 127870 170713 162419 139313 139506 84414 37237 43929
[22:17:09.112] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[22:17:09.112] INFO: number of Vcal hits detected: 207815 207902 207760 207688 207553 207644 207653 207786 207793 207721 206193 207800 207739 207854 207949 207946
[22:17:09.112] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.8 99.8 99.8 99.9 99.9 99.9 99.1 99.9 99.9 99.9 100.0 100.0
[22:17:09.112] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.8 99.8 99.8 99.9 99.9 99.9 99.1 99.9 99.9 99.9 100.0 100.0
[22:17:09.112] INFO: X-ray hit rate [MHz/cm2]: 33.9 21.4 34.7 53.6 59.2 57.9 59.3 42.0 37.5 50.0 47.6 40.8 40.9 24.7 10.9 12.9
[22:17:09.112] INFO: PixTestHighRate::doXPixelAlive() done
[22:17:09.160] INFO: PixTest:: pg_setup set to default.
[22:17:09.179] INFO: enter test to run
[22:17:26.150] INFO: test: HighRate no parameter change
[22:17:26.150] INFO: running: highrate
[22:17:26.151] INFO: ----------------------------------------------------------------------
[22:17:26.151] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[22:17:26.151] INFO: ----------------------------------------------------------------------
[22:17:26.773] INFO: Expecting 208000 events.
[22:17:41.978] INFO: 208000 events read in total (14678ms).
[22:17:41.985] INFO: Test took 15824ms.
[22:17:42.376] INFO: Fetched DAQ statistics. Counters are being reset now.
[22:17:42.708] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[22:17:42.708] INFO: number of red-efficiency pixels: 405 212 553 712 1073 726 802 463 342 642 1661 500 500 300 70 87
[22:17:42.708] INFO: number of X-ray hits detected: 178764 113013 183686 283673 312322 305868 313274 221956 199493 264858 251957 216022 214811 132149 58026 68284
[22:17:42.708] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[22:17:42.708] INFO: number of Vcal hits detected: 207539 207776 207300 207160 206733 207150 207058 207458 207633 207283 205748 207445 207464 207672 207927 207911
[22:17:42.708] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.6 99.5 99.6 99.6 99.8 99.8 99.7 98.9 99.8 99.8 99.9 100.0 100.0
[22:17:42.708] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.6 99.4 99.6 99.5 99.7 99.8 99.7 98.9 99.7 99.7 99.8 100.0 100.0
[22:17:42.708] INFO: X-ray hit rate [MHz/cm2]: 52.4 33.1 53.8 83.1 91.5 89.7 91.8 65.1 58.5 77.6 73.9 63.3 63.0 38.7 17.0 20.0
[22:17:42.708] INFO: PixTestHighRate::doXPixelAlive() done
[22:17:42.758] INFO: PixTest:: pg_setup set to default.
[22:17:42.774] INFO: enter test to run
[22:17:45.286] INFO: test: exit no parameter change
[22:17:45.590] QUIET: Connection to board 33 closed.
[22:17:45.606] INFO: pXar: this is the end, my friend