[08:43:19.120] INFO: *** Welcome to pxar ***
[08:43:19.120] INFO: *** Today: 2016/09/22
[08:43:23.211] INFO: *** Version: v1.9.0-818-g96727
[08:43:23.211] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//dacParameters35_C15.dat
[08:43:23.442] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//tbmParameters_C0b.dat
[08:43:23.442] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//defaultMaskFile.dat
[08:43:23.473] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C15.dat
[08:43:24.720] INFO: clk: 4
[08:43:24.720] INFO: ctr: 4
[08:43:24.720] INFO: sda: 19
[08:43:24.720] INFO: tin: 9
[08:43:24.720] INFO: level: 15
[08:43:24.720] INFO: triggerdelay: 0
[08:43:24.720] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[08:43:24.720] INFO: Log level: INFO
[08:43:24.738] QUIET: Connection to board DTB_WREKRL opened.
[08:43:24.742] 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:
------------------------------------------------------
[08:43:24.745] INFO: RPC call hashes of host and DTB match: 398089610
[08:43:26.271] INFO: DUT info:
[08:43:26.271] INFO: The DUT currently contains the following objects:
[08:43:26.271] INFO: 2 TBM Cores tbm08c (2 ON)
[08:43:26.271] INFO: TBM Core alpha (0): 7 registers set
[08:43:26.271] INFO: TBM Core beta (1): 7 registers set
[08:43:26.271] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[08:43:26.271] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:26.271] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:26.272] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:26.272] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:26.272] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:26.272] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:26.272] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:26.272] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:26.272] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:26.272] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:26.272] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:26.272] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:26.272] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:26.272] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:26.272] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:26.272] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[08:43:26.711] INFO: enter 'restricted' command line mode
[08:43:26.711] INFO: enter test to run
[08:43:34.362] INFO: test: PixelAlive no parameter change
[08:43:34.362] INFO: running: pixelalive
[08:43:34.371] INFO: ----------------------------------------------------------------------
[08:43:34.371] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[08:43:34.371] INFO: ----------------------------------------------------------------------
[08:43:34.692] INFO: Expecting 41600 events.
[08:43:39.052] INFO: 41600 events read in total (3642ms).
[08:43:39.218] INFO: Test took 4845ms.
[08:43:39.226] INFO: Fetched DAQ statistics. Counters are being reset now.
[08:43:39.488] INFO: PixTestAlive::aliveTest() done
[08:43:39.488] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0 0
[08:43:39.523] INFO: enter test to run
[08:44:07.073] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[08:44:07.074] INFO: running: highrate
[08:44:07.074] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[08:44:09.215] INFO: ----------------------------------------------------------------------
[08:44:09.215] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[08:44:09.215] INFO: ----------------------------------------------------------------------
[08:44:09.215] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[08:44:09.215] INFO: edge/corner pixel THR is adjusted
[08:44:09.215] INFO: PixTestHighRate::trimHotPixels: step 0...
[08:44:10.174] INFO: Collecting data for 5 seconds...
[08:44:15.191] INFO: Done with hot pixel readout
[08:44:26.862] INFO: PixTest:: pg_setup set to default.
[08:44:26.863] INFO: 0 hot pixels found in step 0
[08:44:26.876] INFO: 0 hot pixels could not be trimmed and have been masked.
[08:44:26.880] INFO: PixTest::trimHotPixels() done
[08:44:26.902] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C0.dat
[08:44:26.907] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C1.dat
[08:44:26.913] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C2.dat
[08:44:26.918] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C3.dat
[08:44:26.924] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C4.dat
[08:44:26.930] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C5.dat
[08:44:26.935] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C6.dat
[08:44:26.940] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C7.dat
[08:44:26.946] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C8.dat
[08:44:26.951] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C9.dat
[08:44:26.957] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C10.dat
[08:44:26.963] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C11.dat
[08:44:26.968] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C12.dat
[08:44:26.973] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C13.dat
[08:44:26.979] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C14.dat
[08:44:26.985] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C15.dat
[08:44:26.990] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//defaultMaskFile.dat
[08:44:26.000] INFO: enter test to run
[08:45:12.705] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[08:45:12.705] INFO: running: highrate
[08:45:12.709] INFO: ----------------------------------------------------------------------
[08:45:12.709] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[08:45:12.709] INFO: ----------------------------------------------------------------------
[08:45:12.710] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[08:45:12.710] INFO: edge/corner pixel THR is adjusted
[08:45:12.710] INFO: PixTestHighRate::trimHotPixels: step 0...
[08:45:13.667] INFO: Collecting data for 1 seconds...
[08:45:14.671] INFO: Done with hot pixel readout
[08:45:18.577] INFO: PixTest:: pg_setup set to default.
[08:45:18.578] INFO: 0 hot pixels found in step 0
[08:45:18.584] INFO: 0 hot pixels could not be trimmed and have been masked.
[08:45:18.680] INFO: PixTest::trimHotPixels() done
[08:45:18.681] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C0.dat
[08:45:18.688] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C1.dat
[08:45:18.694] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C2.dat
[08:45:18.699] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C3.dat
[08:45:18.704] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C4.dat
[08:45:18.710] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C5.dat
[08:45:18.715] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C6.dat
[08:45:18.720] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C7.dat
[08:45:18.726] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C8.dat
[08:45:18.733] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C9.dat
[08:45:18.740] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C10.dat
[08:45:18.749] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C11.dat
[08:45:18.756] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C12.dat
[08:45:18.762] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C13.dat
[08:45:18.767] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C14.dat
[08:45:18.773] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//trimParameters35_C15.dat
[08:45:18.778] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-7-21_FPIXTest-17C-FNAL-160916-1208-150V_2016-09-16_12h08m_1474045711/000_FPIXTest_p17//defaultMaskFile.dat
[08:45:18.788] INFO: enter test to run
[08:45:40.353] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[08:45:40.353] INFO: running: xray
[08:45:40.354] INFO: ----------------------------------------------------------------------
[08:45:40.354] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:45:40.354] INFO: ----------------------------------------------------------------------
[08:45:41.317] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:45:52.821] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[08:46:22.202] INFO: Resuming triggers.
[08:46:33.703] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[08:47:03.451] INFO: Resuming triggers.
[08:47:14.955] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[08:47:45.178] INFO: Resuming triggers.
[08:47:56.684] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[08:48:26.983] INFO: Resuming triggers.
[08:48:38.488] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[08:49:09.260] INFO: Resuming triggers.
[08:49:20.761] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[08:49:51.186] INFO: Resuming triggers.
[08:50:02.696] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[08:50:33.031] INFO: Resuming triggers.
[08:50:44.537] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[08:51:14.923] INFO: Resuming triggers.
[08:51:23.264] INFO: data taking finished, elapsed time: 100 seconds.
[08:51:44.736] INFO: PixTest:: pg_setup set to default.
[08:51:44.739] INFO: PixTestXray::doPhRun() done
[08:51:44.914] INFO: enter test to run
[08:52:14.118] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[08:52:14.118] INFO: running: xray
[08:52:14.119] INFO: ----------------------------------------------------------------------
[08:52:14.119] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[08:52:14.119] INFO: ----------------------------------------------------------------------
[08:52:15.083] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[08:52:21.710] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[08:52:51.894] INFO: Resuming triggers.
[08:52:58.525] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[08:53:28.676] INFO: Resuming triggers.
[08:53:35.310] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[08:54:05.506] INFO: Resuming triggers.
[08:54:12.138] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[08:54:42.294] INFO: Resuming triggers.
[08:54:48.927] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[08:55:19.141] INFO: Resuming triggers.
[08:55:25.770] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[08:55:56.012] INFO: Resuming triggers.
[08:56:02.645] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[08:56:32.854] INFO: Resuming triggers.
[08:56:39.486] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[08:57:09.732] INFO: Resuming triggers.
[08:57:16.366] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[08:57:46.596] INFO: Resuming triggers.
[08:57:53.230] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[08:58:24.225] INFO: Resuming triggers.
[08:58:30.850] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[08:59:01.877] INFO: Resuming triggers.
[08:59:08.508] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[08:59:39.154] INFO: Resuming triggers.
[08:59:45.778] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[09:00:16.672] INFO: Resuming triggers.
[09:00:23.299] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[09:00:53.938] INFO: Resuming triggers.
[09:01:00.566] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[09:01:31.684] INFO: Resuming triggers.
[09:01:32.574] INFO: data taking finished, elapsed time: 100 seconds.
[09:01:36.003] INFO: PixTest:: pg_setup set to default.
[09:01:36.006] INFO: PixTestXray::doPhRun() done
[09:01:37.160] INFO: enter test to run
[09:02:45.946] INFO: test: HighRate no parameter change
[09:02:45.946] INFO: running: highrate
[09:02:45.959] INFO: ----------------------------------------------------------------------
[09:02:45.959] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[09:02:45.959] INFO: ----------------------------------------------------------------------
[09:02:46.105] INFO: Expecting 768 events.
[09:02:47.239] INFO: 768 events read in total (419ms).
[09:02:47.239] INFO: Test took 1268ms.
[09:02:48.042] INFO: Expecting 41600 events.
[09:02:51.211] INFO: 41600 events read in total (2642ms).
[09:02:51.212] INFO: Test took 3947ms.
[09:02:51.245] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:02:51.970] INFO: Expecting 41600 events.
[09:02:55.146] INFO: 41600 events read in total (2649ms).
[09:02:55.147] INFO: Test took 3885ms.
[09:02:55.181] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:02:55.908] INFO: Expecting 41600 events.
[09:02:59.015] INFO: 41600 events read in total (2580ms).
[09:02:59.016] INFO: Test took 3818ms.
[09:02:59.050] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:02:59.775] INFO: Expecting 41600 events.
[09:03:03.050] INFO: 41600 events read in total (2749ms).
[09:03:03.051] INFO: Test took 3983ms.
[09:03:03.085] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:03.810] INFO: Expecting 41600 events.
[09:03:07.081] INFO: 41600 events read in total (2745ms).
[09:03:07.082] INFO: Test took 3980ms.
[09:03:07.116] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:07.841] INFO: Expecting 41600 events.
[09:03:11.123] INFO: 41600 events read in total (2755ms).
[09:03:11.124] INFO: Test took 3989ms.
[09:03:11.159] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:11.879] INFO: Expecting 41600 events.
[09:03:15.152] INFO: 41600 events read in total (2746ms).
[09:03:15.153] INFO: Test took 3976ms.
[09:03:15.193] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:15.897] INFO: Expecting 41600 events.
[09:03:19.194] INFO: 41600 events read in total (2770ms).
[09:03:19.195] INFO: Test took 3976ms.
[09:03:19.229] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:19.950] INFO: Expecting 41600 events.
[09:03:23.214] INFO: 41600 events read in total (2737ms).
[09:03:23.215] INFO: Test took 3968ms.
[09:03:23.249] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:23.971] INFO: Expecting 41600 events.
[09:03:27.279] INFO: 41600 events read in total (2781ms).
[09:03:27.280] INFO: Test took 4012ms.
[09:03:27.313] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:28.038] INFO: Expecting 41600 events.
[09:03:31.283] INFO: 41600 events read in total (2719ms).
[09:03:31.284] INFO: Test took 3952ms.
[09:03:31.320] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:32.040] INFO: Expecting 41600 events.
[09:03:35.510] INFO: 41600 events read in total (2943ms).
[09:03:35.511] INFO: Test took 4174ms.
[09:03:35.546] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:36.263] INFO: Expecting 41600 events.
[09:03:39.575] INFO: 41600 events read in total (2786ms).
[09:03:39.576] INFO: Test took 4013ms.
[09:03:39.610] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:40.332] INFO: Expecting 41600 events.
[09:03:43.654] INFO: 41600 events read in total (2795ms).
[09:03:43.655] INFO: Test took 4027ms.
[09:03:43.689] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:44.410] INFO: Expecting 41600 events.
[09:03:47.731] INFO: 41600 events read in total (2794ms).
[09:03:47.732] INFO: Test took 4026ms.
[09:03:47.765] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:48.487] INFO: Expecting 41600 events.
[09:03:51.778] INFO: 41600 events read in total (2764ms).
[09:03:51.779] INFO: Test took 3996ms.
[09:03:51.812] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:52.534] INFO: Expecting 41600 events.
[09:03:55.838] INFO: 41600 events read in total (2777ms).
[09:03:55.838] INFO: Test took 4008ms.
[09:03:55.872] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:03:56.597] INFO: Expecting 41600 events.
[09:03:59.860] INFO: 41600 events read in total (2736ms).
[09:03:59.861] INFO: Test took 3971ms.
[09:03:59.894] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:04:00.619] INFO: Expecting 41600 events.
[09:04:03.828] INFO: 41600 events read in total (2683ms).
[09:04:03.829] INFO: Test took 3918ms.
[09:04:03.862] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:04:04.590] INFO: Expecting 41600 events.
[09:04:07.665] INFO: 41600 events read in total (2549ms).
[09:04:07.666] INFO: Test took 3787ms.
[09:04:07.698] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:04:08.065] INFO: enter test to run
[09:04:24.529] INFO: test: HighRate no parameter change
[09:04:24.529] INFO: running: highrate
[09:04:24.531] INFO: ----------------------------------------------------------------------
[09:04:24.531] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:04:24.531] INFO: ----------------------------------------------------------------------
[09:04:25.151] INFO: Expecting 208000 events.
[09:04:36.000] INFO: 208000 events read in total (11322ms).
[09:04:36.003] INFO: Test took 12462ms.
[09:04:37.140] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:04:37.390] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 1 0 0
[09:04:37.390] INFO: number of red-efficiency pixels: 70 48 73 221 141 135 146 87 104 137 134 114 100 65 29 28
[09:04:37.390] INFO: number of X-ray hits detected: 60768 41018 57612 91777 98985 103436 101829 75050 82651 105799 103926 86718 89038 53942 21710 23746
[09:04:37.391] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:04:37.391] INFO: number of Vcal hits detected: 207929 207952 207922 207448 207855 207861 207849 207913 207895 207862 207863 207882 207899 207884 207970 207972
[09:04:37.391] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.7 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0
[09:04:37.391] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.7 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 100.0 99.9 100.0 100.0
[09:04:37.391] INFO: X-ray hit rate [MHz/cm2]: 17.8 12.0 16.9 26.9 29.0 30.3 29.8 22.0 24.2 31.0 30.5 25.4 26.1 15.8 6.4 7.0
[09:04:37.391] INFO: PixTestHighRate::doXPixelAlive() done
[09:04:37.445] INFO: PixTest:: pg_setup set to default.
[09:04:37.457] INFO: enter test to run
[09:04:53.929] INFO: test: HighRate no parameter change
[09:04:53.929] INFO: running: highrate
[09:04:53.930] INFO: ----------------------------------------------------------------------
[09:04:53.930] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:04:53.930] INFO: ----------------------------------------------------------------------
[09:04:54.547] INFO: Expecting 208000 events.
[09:05:08.087] INFO: 208000 events read in total (13013ms).
[09:05:08.092] INFO: Test took 14151ms.
[09:05:08.388] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:05:08.689] INFO: number of dead pixels (per ROC): 0 0 0 22 0 0 0 0 0 0 0 0 0 1 0 0
[09:05:08.689] INFO: number of red-efficiency pixels: 217 112 232 529 496 516 474 265 316 515 486 369 312 231 49 65
[09:05:08.689] INFO: number of X-ray hits detected: 130406 87906 124767 197637 211847 222547 219823 160649 177005 225698 221405 185696 190983 115758 47052 50517
[09:05:08.689] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:05:08.689] INFO: number of Vcal hits detected: 207769 207884 207747 204029 207474 207432 207498 207719 207661 207447 207464 207605 207670 207706 207951 207935
[09:05:08.689] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 98.6 99.8 99.7 99.8 99.9 99.8 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[09:05:08.689] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 98.1 99.7 99.7 99.8 99.9 99.8 99.7 99.7 99.8 99.8 99.9 100.0 100.0
[09:05:08.689] INFO: X-ray hit rate [MHz/cm2]: 38.2 25.8 36.6 57.9 62.1 65.2 64.4 47.1 51.9 66.2 64.9 54.4 56.0 33.9 13.8 14.8
[09:05:08.689] INFO: PixTestHighRate::doXPixelAlive() done
[09:05:08.738] INFO: PixTest:: pg_setup set to default.
[09:05:08.753] INFO: enter test to run
[09:05:23.713] INFO: test: HighRate no parameter change
[09:05:23.713] INFO: running: highrate
[09:05:23.714] INFO: ----------------------------------------------------------------------
[09:05:23.714] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:05:23.714] INFO: ----------------------------------------------------------------------
[09:05:24.330] INFO: Expecting 208000 events.
[09:05:39.927] INFO: 208000 events read in total (15070ms).
[09:05:39.934] INFO: Test took 16210ms.
[09:05:40.398] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:05:40.750] INFO: number of dead pixels (per ROC): 0 0 0 15 0 0 0 0 0 0 0 0 0 1 0 0
[09:05:40.750] INFO: number of red-efficiency pixels: 485 324 538 1117 1166 1215 1184 555 780 1252 1255 892 686 546 103 89
[09:05:40.750] INFO: number of X-ray hits detected: 200311 134777 192824 303732 325697 342729 338647 247448 272731 346511 340712 285419 293485 177869 72338 79282
[09:05:40.750] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:05:40.750] INFO: number of Vcal hits detected: 207428 207641 207306 203020 206551 206445 206521 207349 207063 206411 206376 206883 207222 207282 207892 207909
[09:05:40.750] INFO: Vcal hit fiducial efficiency (%): 99.8 99.8 99.7 97.9 99.4 99.3 99.4 99.7 99.6 99.3 99.3 99.5 99.7 99.7 99.9 100.0
[09:05:40.750] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.7 97.6 99.3 99.3 99.3 99.7 99.5 99.2 99.2 99.5 99.6 99.7 99.9 100.0
[09:05:40.750] INFO: X-ray hit rate [MHz/cm2]: 58.7 39.5 56.5 89.0 95.5 100.5 99.3 72.5 79.9 101.6 99.9 83.7 86.0 52.1 21.2 23.2
[09:05:40.750] INFO: PixTestHighRate::doXPixelAlive() done
[09:05:40.796] INFO: PixTest:: pg_setup set to default.
[09:05:40.816] INFO: enter test to run
[09:05:50.641] INFO: test: exit no parameter change
[09:05:50.945] QUIET: Connection to board 33 closed.
[09:05:50.980] INFO: pXar: this is the end, my friend