[11:43:13.241] INFO: *** Welcome to pxar ***
[11:43:13.241] INFO: *** Today: 2016/06/09
[11:43:13.271] INFO: *** Version: v1.9.0-814-g7497
[11:43:13.271] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//dacParameters35_C15.dat
[11:43:13.296] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//tbmParameters_C0b.dat
[11:43:13.296] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//defaultMaskFile.dat
[11:43:13.300] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C15.dat
[11:43:13.380] INFO: clk: 4
[11:43:13.380] INFO: ctr: 4
[11:43:13.380] INFO: sda: 19
[11:43:13.380] INFO: tin: 9
[11:43:13.380] INFO: level: 15
[11:43:13.380] INFO: triggerdelay: 0
[11:43:13.380] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[11:43:13.380] INFO: Log level: INFO
[11:43:13.399] QUIET: Connection to board DTB_WREKRL opened.
[11:43:13.402] 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:
------------------------------------------------------
[11:43:13.405] INFO: RPC call hashes of host and DTB match: 398089610
[11:43:14.939] INFO: DUT info:
[11:43:14.939] INFO: The DUT currently contains the following objects:
[11:43:14.939] INFO: 2 TBM Cores tbm08c (2 ON)
[11:43:14.939] INFO: TBM Core alpha (0): 7 registers set
[11:43:14.939] INFO: TBM Core beta (1): 7 registers set
[11:43:14.939] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[11:43:14.939] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:14.939] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:14.939] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:14.939] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:14.940] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:14.940] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:14.940] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:14.940] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:14.940] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:14.940] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:14.940] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:14.940] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:14.940] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:14.940] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:14.940] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:14.940] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[11:43:15.343] INFO: enter 'restricted' command line mode
[11:43:15.343] INFO: enter test to run
[11:43:23.125] INFO: test: PixelAlive no parameter change
[11:43:23.125] INFO: running: pixelalive
[11:43:23.134] INFO: ----------------------------------------------------------------------
[11:43:23.134] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[11:43:23.134] INFO: ----------------------------------------------------------------------
[11:43:23.449] INFO: Expecting 41600 events.
[11:43:27.790] INFO: 41600 events read in total (3622ms).
[11:43:27.958] INFO: Test took 4822ms.
[11:43:27.970] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:43:28.238] INFO: PixTestAlive::aliveTest() done
[11:43:28.238] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[11:43:28.273] INFO: enter test to run
[11:45:36.052] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[11:45:36.052] INFO: running: highrate
[11:45:36.052] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[11:45:36.265] INFO: ----------------------------------------------------------------------
[11:45:36.265] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[11:45:36.265] INFO: ----------------------------------------------------------------------
[11:45:36.265] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[11:45:36.265] INFO: edge/corner pixel THR is adjusted
[11:45:36.265] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:45:37.224] INFO: Collecting data for 5 seconds...
[11:45:42.240] INFO: Done with hot pixel readout
[11:45:53.317] INFO: PixTest:: pg_setup set to default.
[11:45:53.318] INFO: 0 hot pixels found in step 0
[11:45:53.330] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:45:53.334] INFO: PixTest::trimHotPixels() done
[11:45:53.355] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C0.dat
[11:45:53.360] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C1.dat
[11:45:53.366] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C2.dat
[11:45:53.371] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C3.dat
[11:45:53.376] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C4.dat
[11:45:53.382] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C5.dat
[11:45:53.387] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C6.dat
[11:45:53.392] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C7.dat
[11:45:53.397] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C8.dat
[11:45:53.402] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C9.dat
[11:45:53.407] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C10.dat
[11:45:53.428] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C11.dat
[11:45:53.436] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C12.dat
[11:45:53.442] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C13.dat
[11:45:53.447] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C14.dat
[11:45:53.453] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C15.dat
[11:45:53.458] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//defaultMaskFile.dat
[11:45:53.472] INFO: enter test to run
[11:47:43.475] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[11:47:43.475] INFO: running: highrate
[11:47:43.479] INFO: ----------------------------------------------------------------------
[11:47:43.479] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[11:47:43.479] INFO: ----------------------------------------------------------------------
[11:47:43.479] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[11:47:43.479] INFO: edge/corner pixel THR is adjusted
[11:47:43.479] INFO: PixTestHighRate::trimHotPixels: step 0...
[11:47:44.437] INFO: Collecting data for 1 seconds...
[11:47:45.441] INFO: Done with hot pixel readout
[11:47:49.187] INFO: PixTest:: pg_setup set to default.
[11:47:49.188] INFO: 0 hot pixels found in step 0
[11:47:49.195] INFO: 0 hot pixels could not be trimmed and have been masked.
[11:47:49.290] INFO: PixTest::trimHotPixels() done
[11:47:49.291] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C0.dat
[11:47:49.302] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C1.dat
[11:47:49.309] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C2.dat
[11:47:49.315] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C3.dat
[11:47:49.320] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C4.dat
[11:47:49.326] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C5.dat
[11:47:49.331] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C6.dat
[11:47:49.337] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C7.dat
[11:47:49.342] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C8.dat
[11:47:49.348] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C9.dat
[11:47:49.353] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C10.dat
[11:47:49.358] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C11.dat
[11:47:49.364] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C12.dat
[11:47:49.369] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C13.dat
[11:47:49.375] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C14.dat
[11:47:49.380] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//trimParameters35_C15.dat
[11:47:49.386] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-N-1-43_FPIXTest-17C-FNAL-160603-1303_2016-06-03_13h03m_1464977020/000_FPIXTest_p17//defaultMaskFile.dat
[11:47:49.396] INFO: enter test to run
[11:48:23.939] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[11:48:23.939] INFO: running: xray
[11:48:23.940] INFO: ----------------------------------------------------------------------
[11:48:23.940] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:48:23.940] INFO: ----------------------------------------------------------------------
[11:48:24.904] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:48:37.095] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[11:49:06.648] INFO: Resuming triggers.
[11:49:18.839] INFO: run duration 24 seconds, buffer almost full (81%), pausing triggers.
[11:49:48.553] INFO: Resuming triggers.
[11:50:00.744] INFO: run duration 36 seconds, buffer almost full (81%), pausing triggers.
[11:50:29.932] INFO: Resuming triggers.
[11:50:42.125] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[11:51:11.415] INFO: Resuming triggers.
[11:51:23.606] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[11:51:52.662] INFO: Resuming triggers.
[11:52:04.852] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[11:52:34.196] INFO: Resuming triggers.
[11:52:46.383] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[11:53:15.692] INFO: Resuming triggers.
[11:53:27.878] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[11:53:57.240] INFO: Resuming triggers.
[11:54:00.054] INFO: data taking finished, elapsed time: 100 seconds.
[11:54:07.185] INFO: PixTest:: pg_setup set to default.
[11:54:07.189] INFO: PixTestXray::doPhRun() done
[11:54:07.323] INFO: enter test to run
[11:54:56.424] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[11:54:56.424] INFO: running: xray
[11:54:56.425] INFO: ----------------------------------------------------------------------
[11:54:56.425] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:54:56.425] INFO: ----------------------------------------------------------------------
[11:54:57.388] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:55:04.921] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[11:55:35.116] INFO: Resuming triggers.
[11:55:42.656] INFO: run duration 15 seconds, buffer almost full (81%), pausing triggers.
[11:56:12.911] INFO: Resuming triggers.
[11:56:20.451] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[11:56:50.603] INFO: Resuming triggers.
[11:56:58.145] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[11:57:28.333] INFO: Resuming triggers.
[11:57:35.874] INFO: run duration 37 seconds, buffer almost full (81%), pausing triggers.
[11:58:06.484] INFO: Resuming triggers.
[11:58:14.023] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[11:58:44.894] INFO: Resuming triggers.
[11:58:52.436] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[11:59:22.641] INFO: Resuming triggers.
[11:59:30.183] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[12:00:01.711] INFO: Resuming triggers.
[12:00:09.249] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[12:00:39.738] INFO: Resuming triggers.
[12:00:47.277] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[12:01:17.566] INFO: Resuming triggers.
[12:01:25.106] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[12:01:55.286] INFO: Resuming triggers.
[12:02:02.826] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[12:02:33.099] INFO: Resuming triggers.
[12:02:40.638] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[12:03:10.847] INFO: Resuming triggers.
[12:03:13.177] INFO: data taking finished, elapsed time: 100 seconds.
[12:03:22.806] INFO: PixTest:: pg_setup set to default.
[12:03:22.809] INFO: PixTestXray::doPhRun() done
[12:03:22.956] INFO: enter test to run
[12:04:43.731] INFO: test: HighRate no parameter change
[12:04:43.731] INFO: running: highrate
[12:04:43.750] INFO: ----------------------------------------------------------------------
[12:04:43.750] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:04:43.750] INFO: ----------------------------------------------------------------------
[12:04:43.906] INFO: Expecting 768 events.
[12:04:45.040] INFO: 768 events read in total (419ms).
[12:04:45.040] INFO: Test took 1268ms.
[12:04:45.843] INFO: Expecting 41600 events.
[12:04:48.850] INFO: 41600 events read in total (2480ms).
[12:04:48.851] INFO: Test took 3803ms.
[12:04:48.879] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:04:49.613] INFO: Expecting 41600 events.
[12:04:52.766] INFO: 41600 events read in total (2626ms).
[12:04:52.767] INFO: Test took 3873ms.
[12:04:52.796] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:04:53.528] INFO: Expecting 41600 events.
[12:04:56.700] INFO: 41600 events read in total (2645ms).
[12:04:56.701] INFO: Test took 3889ms.
[12:04:56.729] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:04:57.460] INFO: Expecting 41600 events.
[12:05:00.637] INFO: 41600 events read in total (2650ms).
[12:05:00.638] INFO: Test took 3893ms.
[12:05:00.667] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:01.399] INFO: Expecting 41600 events.
[12:05:04.573] INFO: 41600 events read in total (2647ms).
[12:05:04.574] INFO: Test took 3890ms.
[12:05:04.603] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:05.335] INFO: Expecting 41600 events.
[12:05:08.512] INFO: 41600 events read in total (2650ms).
[12:05:08.513] INFO: Test took 3894ms.
[12:05:08.542] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:09.278] INFO: Expecting 41600 events.
[12:05:12.472] INFO: 41600 events read in total (2667ms).
[12:05:12.473] INFO: Test took 3914ms.
[12:05:12.501] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:13.236] INFO: Expecting 41600 events.
[12:05:16.428] INFO: 41600 events read in total (2665ms).
[12:05:16.429] INFO: Test took 3911ms.
[12:05:16.458] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:17.188] INFO: Expecting 41600 events.
[12:05:20.390] INFO: 41600 events read in total (2675ms).
[12:05:20.391] INFO: Test took 3916ms.
[12:05:20.419] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:21.152] INFO: Expecting 41600 events.
[12:05:24.360] INFO: 41600 events read in total (2681ms).
[12:05:24.361] INFO: Test took 3926ms.
[12:05:24.389] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:25.120] INFO: Expecting 41600 events.
[12:05:28.322] INFO: 41600 events read in total (2675ms).
[12:05:28.323] INFO: Test took 3917ms.
[12:05:28.352] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:29.083] INFO: Expecting 41600 events.
[12:05:32.260] INFO: 41600 events read in total (2650ms).
[12:05:32.261] INFO: Test took 3893ms.
[12:05:32.290] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:33.025] INFO: Expecting 41600 events.
[12:05:36.217] INFO: 41600 events read in total (2665ms).
[12:05:36.218] INFO: Test took 3911ms.
[12:05:36.247] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:36.979] INFO: Expecting 41600 events.
[12:05:40.176] INFO: 41600 events read in total (2670ms).
[12:05:40.177] INFO: Test took 3914ms.
[12:05:40.206] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:40.937] INFO: Expecting 41600 events.
[12:05:44.127] INFO: 41600 events read in total (2663ms).
[12:05:44.128] INFO: Test took 3905ms.
[12:05:44.156] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:44.891] INFO: Expecting 41600 events.
[12:05:48.076] INFO: 41600 events read in total (2658ms).
[12:05:48.077] INFO: Test took 3905ms.
[12:05:48.105] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:48.840] INFO: Expecting 41600 events.
[12:05:52.045] INFO: 41600 events read in total (2678ms).
[12:05:52.046] INFO: Test took 3924ms.
[12:05:52.074] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:52.806] INFO: Expecting 41600 events.
[12:05:55.992] INFO: 41600 events read in total (2659ms).
[12:05:55.993] INFO: Test took 3903ms.
[12:05:56.022] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:05:56.754] INFO: Expecting 41600 events.
[12:05:59.929] INFO: 41600 events read in total (2648ms).
[12:05:59.930] INFO: Test took 3891ms.
[12:05:59.957] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:06:00.692] INFO: Expecting 41600 events.
[12:06:03.721] INFO: 41600 events read in total (2503ms).
[12:06:03.722] INFO: Test took 3747ms.
[12:06:03.751] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:06:04.123] INFO: enter test to run
[12:06:15.298] INFO: test: HighRate no parameter change
[12:06:15.299] INFO: running: highrate
[12:06:15.300] INFO: ----------------------------------------------------------------------
[12:06:15.300] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:06:15.300] INFO: ----------------------------------------------------------------------
[12:06:15.915] INFO: Expecting 208000 events.
[12:06:27.448] INFO: 208000 events read in total (11007ms).
[12:06:27.450] INFO: Test took 12141ms.
[12:06:27.568] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:06:27.811] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:06:27.811] INFO: number of red-efficiency pixels: 56 50 72 97 91 112 96 65 75 100 75 71 90 56 20 15
[12:06:27.811] INFO: number of X-ray hits detected: 49470 36768 57236 85510 88580 93897 97453 66727 62926 79546 75134 65502 69295 41471 18345 18883
[12:06:27.811] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:06:27.811] INFO: number of Vcal hits detected: 207942 207949 207927 207900 207906 207886 207902 207934 207925 207900 207923 207929 207908 207943 207980 207985
[12:06:27.811] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[12:06:27.811] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[12:06:27.811] INFO: X-ray hit rate [MHz/cm2]: 14.5 10.8 16.8 25.1 26.0 27.5 28.6 19.6 18.4 23.3 22.0 19.2 20.3 12.2 5.4 5.5
[12:06:27.811] INFO: PixTestHighRate::doXPixelAlive() done
[12:06:27.863] INFO: PixTest:: pg_setup set to default.
[12:06:27.880] INFO: enter test to run
[12:06:52.330] INFO: test: HighRate no parameter change
[12:06:52.330] INFO: running: highrate
[12:06:52.331] INFO: ----------------------------------------------------------------------
[12:06:52.331] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:06:52.331] INFO: ----------------------------------------------------------------------
[12:06:52.946] INFO: Expecting 208000 events.
[12:07:05.884] INFO: 208000 events read in total (12411ms).
[12:07:05.889] INFO: Test took 13549ms.
[12:07:06.127] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:07:06.410] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:07:06.410] INFO: number of red-efficiency pixels: 125 79 168 291 341 368 309 170 192 266 190 184 216 130 46 50
[12:07:06.410] INFO: number of X-ray hits detected: 102160 76923 120356 177973 184471 197205 203037 139791 132378 166565 157611 136309 145127 87251 38103 39377
[12:07:06.410] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:07:06.410] INFO: number of Vcal hits detected: 207870 207920 207822 207689 207641 207604 207670 207821 207803 207724 207805 207810 207780 207863 207954 207950
[12:07:06.410] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.8 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[12:07:06.410] 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.9 99.9 99.9 99.9 100.0 100.0
[12:07:06.410] INFO: X-ray hit rate [MHz/cm2]: 29.9 22.5 35.3 52.2 54.1 57.8 59.5 41.0 38.8 48.8 46.2 40.0 42.5 25.6 11.2 11.5
[12:07:06.410] INFO: PixTestHighRate::doXPixelAlive() done
[12:07:06.462] INFO: PixTest:: pg_setup set to default.
[12:07:06.479] INFO: enter test to run
[12:07:34.194] INFO: test: HighRate no parameter change
[12:07:34.194] INFO: running: highrate
[12:07:34.195] INFO: ----------------------------------------------------------------------
[12:07:34.195] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:07:34.195] INFO: ----------------------------------------------------------------------
[12:07:34.812] INFO: Expecting 208000 events.
[12:07:49.234] INFO: 208000 events read in total (13895ms).
[12:07:49.240] INFO: Test took 15036ms.
[12:07:49.610] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:07:49.933] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:07:49.933] INFO: number of red-efficiency pixels: 270 167 361 539 767 872 679 403 409 599 382 354 446 265 55 77
[12:07:49.933] INFO: number of X-ray hits detected: 154293 115260 180878 267794 276878 295600 305763 210448 198146 249089 237255 205350 218345 131911 58016 59253
[12:07:49.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
[12:07:49.933] INFO: number of Vcal hits detected: 207702 207823 207584 207388 207104 206968 207240 207554 207553 207318 207594 207626 207513 207704 207945 207923
[12:07:49.933] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.6 99.5 99.7 99.8 99.8 99.7 99.8 99.8 99.8 99.9 100.0 100.0
[12:07:49.933] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.6 99.5 99.6 99.8 99.8 99.7 99.8 99.8 99.8 99.9 100.0 100.0
[12:07:49.933] INFO: X-ray hit rate [MHz/cm2]: 45.2 33.8 53.0 78.5 81.2 86.6 89.6 61.7 58.1 73.0 69.5 60.2 64.0 38.7 17.0 17.4
[12:07:49.933] INFO: PixTestHighRate::doXPixelAlive() done
[12:07:49.985] INFO: PixTest:: pg_setup set to default.
[12:07:49.996] INFO: enter test to run
[12:07:53.586] INFO: test: exit no parameter change
[12:07:53.921] QUIET: Connection to board 33 closed.
[12:07:53.922] INFO: pXar: this is the end, my friend