[18:03:23.578] INFO: *** Welcome to pxar ***
[18:03:23.578] INFO: *** Today: 2016/10/06
[18:03:24.389] INFO: *** Version: v1.9.0-818-g96727
[18:03:24.390] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//dacParameters35_C15.dat
[18:03:24.440] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//tbmParameters_C0b.dat
[18:03:24.440] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//defaultMaskFile.dat
[18:03:24.448] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C15.dat
[18:03:24.579] INFO: clk: 4
[18:03:24.579] INFO: ctr: 4
[18:03:24.579] INFO: sda: 19
[18:03:24.579] INFO: tin: 9
[18:03:24.579] INFO: level: 15
[18:03:24.579] INFO: triggerdelay: 0
[18:03:24.596] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[18:03:24.596] INFO: Log level: INFO
[18:03:24.620] QUIET: Connection to board DTB_WREKRL opened.
[18:03:24.624] 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:
------------------------------------------------------
[18:03:24.626] INFO: RPC call hashes of host and DTB match: 398089610
[18:03:26.164] INFO: DUT info:
[18:03:26.164] INFO: The DUT currently contains the following objects:
[18:03:26.164] INFO: 2 TBM Cores tbm08c (2 ON)
[18:03:26.176] INFO: TBM Core alpha (0): 7 registers set
[18:03:26.176] INFO: TBM Core beta (1): 7 registers set
[18:03:26.176] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[18:03:26.176] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[18:03:26.176] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[18:03:26.176] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[18:03:26.177] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[18:03:26.177] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[18:03:26.177] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[18:03:26.177] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[18:03:26.177] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[18:03:26.177] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[18:03:26.177] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[18:03:26.177] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[18:03:26.177] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[18:03:26.177] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[18:03:26.177] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[18:03:26.177] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[18:03:26.177] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[18:03:26.604] INFO: enter 'restricted' command line mode
[18:03:26.604] INFO: enter test to run
[18:03:51.924] INFO: test: PixelAlive no parameter change
[18:03:51.924] INFO: running: pixelalive
[18:03:51.951] INFO: ----------------------------------------------------------------------
[18:03:51.951] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[18:03:51.951] INFO: ----------------------------------------------------------------------
[18:03:52.291] INFO: Expecting 41600 events.
[18:03:56.604] INFO: 41600 events read in total (3594ms).
[18:03:56.767] INFO: Test took 4814ms.
[18:03:56.778] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:03:57.077] INFO: PixTestAlive::aliveTest() done
[18:03:57.077] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[18:03:57.119] INFO: enter test to run
[18:04:33.636] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[18:04:33.636] INFO: running: highrate
[18:04:33.644] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[18:04:33.874] INFO: ----------------------------------------------------------------------
[18:04:33.874] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[18:04:33.874] INFO: ----------------------------------------------------------------------
[18:04:33.874] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[18:04:33.874] INFO: edge/corner pixel THR is adjusted
[18:04:33.874] INFO: PixTestHighRate::trimHotPixels: step 0...
[18:04:34.833] INFO: Collecting data for 5 seconds...
[18:04:39.852] INFO: Done with hot pixel readout
[18:04:51.145] INFO: PixTest:: pg_setup set to default.
[18:04:51.146] INFO: 0 hot pixels found in step 0
[18:04:51.159] INFO: 0 hot pixels could not be trimmed and have been masked.
[18:04:51.205] INFO: PixTest::trimHotPixels() done
[18:04:51.241] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C0.dat
[18:04:51.247] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C1.dat
[18:04:51.253] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C2.dat
[18:04:51.258] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C3.dat
[18:04:51.264] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C4.dat
[18:04:51.270] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C5.dat
[18:04:51.275] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C6.dat
[18:04:51.281] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C7.dat
[18:04:51.286] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C8.dat
[18:04:51.292] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C9.dat
[18:04:51.298] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C10.dat
[18:04:51.303] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C11.dat
[18:04:51.309] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C12.dat
[18:04:51.315] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C13.dat
[18:04:51.320] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C14.dat
[18:04:51.326] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C15.dat
[18:04:51.332] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//defaultMaskFile.dat
[18:04:51.341] INFO: enter test to run
[18:05:15.211] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[18:05:15.211] INFO: running: highrate
[18:05:15.216] INFO: ----------------------------------------------------------------------
[18:05:15.216] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[18:05:15.216] INFO: ----------------------------------------------------------------------
[18:05:15.216] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[18:05:15.216] INFO: edge/corner pixel THR is adjusted
[18:05:15.216] INFO: PixTestHighRate::trimHotPixels: step 0...
[18:05:16.172] INFO: Collecting data for 1 seconds...
[18:05:17.176] INFO: Done with hot pixel readout
[18:05:21.047] INFO: PixTest:: pg_setup set to default.
[18:05:21.048] INFO: 0 hot pixels found in step 0
[18:05:21.053] INFO: 0 hot pixels could not be trimmed and have been masked.
[18:05:21.149] INFO: PixTest::trimHotPixels() done
[18:05:21.150] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C0.dat
[18:05:21.164] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C1.dat
[18:05:21.174] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C2.dat
[18:05:21.180] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C3.dat
[18:05:21.187] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C4.dat
[18:05:21.194] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C5.dat
[18:05:21.200] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C6.dat
[18:05:21.206] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C7.dat
[18:05:21.212] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C8.dat
[18:05:21.218] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C9.dat
[18:05:21.224] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C10.dat
[18:05:21.229] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C11.dat
[18:05:21.235] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C12.dat
[18:05:21.241] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C13.dat
[18:05:21.246] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C14.dat
[18:05:21.252] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C15.dat
[18:05:21.258] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-4-16_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//defaultMaskFile.dat
[18:05:21.267] INFO: enter test to run
[18:05:47.332] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[18:05:47.332] INFO: running: xray
[18:05:47.344] INFO: ----------------------------------------------------------------------
[18:05:47.344] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[18:05:47.344] INFO: ----------------------------------------------------------------------
[18:05:48.307] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[18:06:00.178] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[18:06:29.769] INFO: Resuming triggers.
[18:06:41.643] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[18:07:11.700] INFO: Resuming triggers.
[18:07:23.574] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[18:07:53.882] INFO: Resuming triggers.
[18:08:05.754] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[18:08:35.343] INFO: Resuming triggers.
[18:08:47.214] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[18:09:16.578] INFO: Resuming triggers.
[18:09:28.450] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[18:09:57.778] INFO: Resuming triggers.
[18:10:09.652] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[18:10:39.066] INFO: Resuming triggers.
[18:10:50.938] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[18:11:20.421] INFO: Resuming triggers.
[18:11:25.791] INFO: data taking finished, elapsed time: 100 seconds.
[18:11:39.420] INFO: PixTest:: pg_setup set to default.
[18:11:39.424] INFO: PixTestXray::doPhRun() done
[18:11:39.584] INFO: enter test to run
[18:12:08.049] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[18:12:08.049] INFO: running: xray
[18:12:08.050] INFO: ----------------------------------------------------------------------
[18:12:08.050] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[18:12:08.050] INFO: ----------------------------------------------------------------------
[18:12:09.014] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[18:12:15.983] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[18:12:46.326] INFO: Resuming triggers.
[18:12:53.296] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[18:13:23.668] INFO: Resuming triggers.
[18:13:30.642] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[18:14:00.997] INFO: Resuming triggers.
[18:14:07.973] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[18:14:38.326] INFO: Resuming triggers.
[18:14:45.299] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[18:15:15.705] INFO: Resuming triggers.
[18:15:22.682] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[18:15:53.030] INFO: Resuming triggers.
[18:15:59.002] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[18:16:31.428] INFO: Resuming triggers.
[18:16:38.405] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[18:17:09.508] INFO: Resuming triggers.
[18:17:16.474] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[18:17:47.602] INFO: Resuming triggers.
[18:17:54.575] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[18:18:25.601] INFO: Resuming triggers.
[18:18:32.573] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[18:19:03.296] INFO: Resuming triggers.
[18:19:10.261] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[18:19:41.767] INFO: Resuming triggers.
[18:19:48.739] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[18:20:20.659] INFO: Resuming triggers.
[18:20:27.624] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[18:20:58.411] INFO: Resuming triggers.
[18:21:01.167] INFO: data taking finished, elapsed time: 100 seconds.
[18:21:13.509] INFO: PixTest:: pg_setup set to default.
[18:21:13.512] INFO: PixTestXray::doPhRun() done
[18:21:13.661] INFO: enter test to run
[18:21:30.109] INFO: test: HighRate no parameter change
[18:21:30.109] INFO: running: highrate
[18:21:30.129] INFO: ----------------------------------------------------------------------
[18:21:30.129] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[18:21:30.129] INFO: ----------------------------------------------------------------------
[18:21:30.284] INFO: Expecting 768 events.
[18:21:31.418] INFO: 768 events read in total (419ms).
[18:21:31.418] INFO: Test took 1269ms.
[18:21:32.221] INFO: Expecting 41600 events.
[18:21:35.288] INFO: 41600 events read in total (2540ms).
[18:21:35.289] INFO: Test took 3846ms.
[18:21:35.319] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:21:36.054] INFO: Expecting 41600 events.
[18:21:39.214] INFO: 41600 events read in total (2633ms).
[18:21:39.215] INFO: Test took 3880ms.
[18:21:39.246] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:21:39.977] INFO: Expecting 41600 events.
[18:21:43.183] INFO: 41600 events read in total (2679ms).
[18:21:43.184] INFO: Test took 3921ms.
[18:21:43.216] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:21:43.942] INFO: Expecting 41600 events.
[18:21:47.157] INFO: 41600 events read in total (2688ms).
[18:21:47.158] INFO: Test took 3925ms.
[18:21:47.190] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:21:47.919] INFO: Expecting 41600 events.
[18:21:51.129] INFO: 41600 events read in total (2684ms).
[18:21:51.130] INFO: Test took 3922ms.
[18:21:51.161] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:21:51.888] INFO: Expecting 41600 events.
[18:21:55.093] INFO: 41600 events read in total (2678ms).
[18:21:55.094] INFO: Test took 3915ms.
[18:21:55.125] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:21:55.857] INFO: Expecting 41600 events.
[18:21:59.076] INFO: 41600 events read in total (2692ms).
[18:21:59.076] INFO: Test took 3934ms.
[18:21:59.108] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:21:59.838] INFO: Expecting 41600 events.
[18:22:03.056] INFO: 41600 events read in total (2691ms).
[18:22:03.057] INFO: Test took 3931ms.
[18:22:03.089] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:22:03.814] INFO: Expecting 41600 events.
[18:22:07.030] INFO: 41600 events read in total (2689ms).
[18:22:07.031] INFO: Test took 3925ms.
[18:22:07.063] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:22:07.792] INFO: Expecting 41600 events.
[18:22:10.964] INFO: 41600 events read in total (2645ms).
[18:22:10.965] INFO: Test took 3885ms.
[18:22:10.997] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:22:11.723] INFO: Expecting 41600 events.
[18:22:14.938] INFO: 41600 events read in total (2688ms).
[18:22:14.939] INFO: Test took 3924ms.
[18:22:14.970] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:22:15.700] INFO: Expecting 41600 events.
[18:22:18.920] INFO: 41600 events read in total (2693ms).
[18:22:18.921] INFO: Test took 3934ms.
[18:22:18.953] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:22:19.680] INFO: Expecting 41600 events.
[18:22:22.884] INFO: 41600 events read in total (2677ms).
[18:22:22.886] INFO: Test took 3916ms.
[18:22:22.923] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:22:23.647] INFO: Expecting 41600 events.
[18:22:26.864] INFO: 41600 events read in total (2690ms).
[18:22:26.865] INFO: Test took 3924ms.
[18:22:26.895] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:22:27.623] INFO: Expecting 41600 events.
[18:22:30.848] INFO: 41600 events read in total (2698ms).
[18:22:30.849] INFO: Test took 3936ms.
[18:22:30.880] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:22:31.608] INFO: Expecting 41600 events.
[18:22:34.837] INFO: 41600 events read in total (2702ms).
[18:22:34.838] INFO: Test took 3941ms.
[18:22:34.870] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:22:35.601] INFO: Expecting 41600 events.
[18:22:38.818] INFO: 41600 events read in total (2691ms).
[18:22:38.819] INFO: Test took 3933ms.
[18:22:38.850] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:22:39.579] INFO: Expecting 41600 events.
[18:22:42.799] INFO: 41600 events read in total (2693ms).
[18:22:42.800] INFO: Test took 3932ms.
[18:22:42.831] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:22:43.557] INFO: Expecting 41600 events.
[18:22:46.738] INFO: 41600 events read in total (2654ms).
[18:22:46.739] INFO: Test took 3890ms.
[18:22:46.770] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:22:47.501] INFO: Expecting 41600 events.
[18:22:50.564] INFO: 41600 events read in total (2537ms).
[18:22:50.565] INFO: Test took 3777ms.
[18:22:50.596] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:22:50.965] INFO: enter test to run
[18:22:55.877] INFO: test: HighRate no parameter change
[18:22:55.877] INFO: running: highrate
[18:22:55.878] INFO: ----------------------------------------------------------------------
[18:22:55.878] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[18:22:55.878] INFO: ----------------------------------------------------------------------
[18:22:56.492] INFO: Expecting 208000 events.
[18:23:08.155] INFO: 208000 events read in total (11136ms).
[18:23:08.158] INFO: Test took 12274ms.
[18:23:08.285] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:23:08.532] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[18:23:08.532] INFO: number of red-efficiency pixels: 81 47 79 131 129 132 149 84 85 102 103 80 81 45 13 26
[18:23:08.532] INFO: number of X-ray hits detected: 59645 40864 63621 95961 101183 103920 102213 70207 70219 84957 83966 72629 73242 43975 18921 25928
[18:23:08.532] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[18:23:08.532] INFO: number of Vcal hits detected: 207918 207904 207920 207867 207870 207866 207847 207914 207915 207898 207846 207920 207917 207953 207986 207974
[18:23:08.532] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[18:23:08.532] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0 100.0
[18:23:08.532] INFO: X-ray hit rate [MHz/cm2]: 17.5 12.0 18.6 28.1 29.7 30.5 30.0 20.6 20.6 24.9 24.6 21.3 21.5 12.9 5.5 7.6
[18:23:08.532] INFO: PixTestHighRate::doXPixelAlive() done
[18:23:08.585] INFO: PixTest:: pg_setup set to default.
[18:23:08.597] INFO: enter test to run
[18:23:23.852] INFO: test: HighRate no parameter change
[18:23:23.852] INFO: running: highrate
[18:23:23.853] INFO: ----------------------------------------------------------------------
[18:23:23.853] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[18:23:23.853] INFO: ----------------------------------------------------------------------
[18:23:24.466] INFO: Expecting 208000 events.
[18:23:37.817] INFO: 208000 events read in total (12824ms).
[18:23:37.822] INFO: Test took 13959ms.
[18:23:38.103] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:23:38.397] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[18:23:38.397] INFO: number of red-efficiency pixels: 194 124 290 448 479 464 478 229 216 387 275 254 198 176 47 63
[18:23:38.397] INFO: number of X-ray hits detected: 127208 87465 134972 203769 214518 222218 216966 150511 149976 181470 177978 153640 155879 93947 40134 55538
[18:23:38.397] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[18:23:38.397] INFO: number of Vcal hits detected: 207795 207821 207666 207510 207473 207487 207470 207747 207774 207593 207665 207732 207787 207819 207952 207936
[18:23:38.397] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.9 99.9 99.9 99.9 100.0 100.0
[18:23:38.397] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.8 99.7 99.9 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[18:23:38.397] INFO: X-ray hit rate [MHz/cm2]: 37.3 25.6 39.6 59.7 62.9 65.1 63.6 44.1 44.0 53.2 52.2 45.0 45.7 27.5 11.8 16.3
[18:23:38.397] INFO: PixTestHighRate::doXPixelAlive() done
[18:23:38.449] INFO: PixTest:: pg_setup set to default.
[18:23:38.465] INFO: enter test to run
[18:23:58.404] INFO: test: HighRate no parameter change
[18:23:58.404] INFO: running: highrate
[18:23:58.405] INFO: ----------------------------------------------------------------------
[18:23:58.405] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[18:23:58.405] INFO: ----------------------------------------------------------------------
[18:23:59.021] INFO: Expecting 208000 events.
[18:24:14.127] INFO: 208000 events read in total (14579ms).
[18:24:14.134] INFO: Test took 15720ms.
[18:24:14.552] INFO: Fetched DAQ statistics. Counters are being reset now.
[18:24:14.891] INFO: number of dead pixels (per ROC): 0 1 0 0 0 0 0 0 0 0 1 0 0 0 0 0
[18:24:14.891] INFO: number of red-efficiency pixels: 353 261 673 1054 1171 1249 1209 474 434 881 621 611 444 355 92 115
[18:24:14.891] INFO: number of X-ray hits detected: 195359 132841 207438 312829 327485 338998 332345 231454 230296 278445 275173 234410 238797 144505 62647 85518
[18:24:14.891] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[18:24:14.891] INFO: number of Vcal hits detected: 207608 207660 207103 206685 206543 206368 206466 207436 207531 206947 207243 207298 207512 207601 207903 207884
[18:24:14.891] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.6 99.4 99.4 99.3 99.3 99.7 99.8 99.5 99.7 99.7 99.8 99.8 100.0 99.9
[18:24:14.891] INFO: Vcal hit overall efficiency (%): 99.8 99.8 99.6 99.4 99.3 99.2 99.3 99.7 99.8 99.5 99.6 99.7 99.8 99.8 100.0 99.9
[18:24:14.891] INFO: X-ray hit rate [MHz/cm2]: 57.3 38.9 60.8 91.7 96.0 99.4 97.4 67.8 67.5 81.6 80.7 68.7 70.0 42.4 18.4 25.1
[18:24:14.891] INFO: PixTestHighRate::doXPixelAlive() done
[18:24:14.941] INFO: PixTest:: pg_setup set to default.
[18:24:14.958] INFO: enter test to run
[18:24:38.308] INFO: test: exit no parameter change
[18:24:38.737] QUIET: Connection to board 33 closed.
[18:24:38.749] INFO: pXar: this is the end, my friend