[16:48:12.208] INFO: *** Welcome to pxar ***
[16:48:12.208] INFO: *** Today: 2016/10/06
[16:48:13.042] INFO: *** Version: v1.9.0-818-g96727
[16:48:13.042] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//dacParameters35_C15.dat
[16:48:13.059] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//tbmParameters_C0b.dat
[16:48:13.077] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//defaultMaskFile.dat
[16:48:13.091] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C15.dat
[16:48:13.213] INFO: clk: 4
[16:48:13.213] INFO: ctr: 4
[16:48:13.213] INFO: sda: 19
[16:48:13.213] INFO: tin: 9
[16:48:13.213] INFO: level: 15
[16:48:13.213] INFO: triggerdelay: 0
[16:48:13.213] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[16:48:13.213] INFO: Log level: INFO
[16:48:13.246] QUIET: Connection to board DTB_WREKRL opened.
[16:48:13.250] 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:
------------------------------------------------------
[16:48:13.253] INFO: RPC call hashes of host and DTB match: 398089610
[16:48:14.789] INFO: DUT info:
[16:48:14.789] INFO: The DUT currently contains the following objects:
[16:48:14.789] INFO: 2 TBM Cores tbm08c (2 ON)
[16:48:14.789] INFO: TBM Core alpha (0): 7 registers set
[16:48:14.789] INFO: TBM Core beta (1): 7 registers set
[16:48:14.789] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:48:14.789] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:14.789] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:14.790] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:14.790] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:14.790] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:14.790] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:14.790] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:14.790] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:14.790] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:14.790] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:14.790] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:14.790] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:14.790] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:14.790] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:14.790] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:14.790] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:48:15.208] INFO: enter 'restricted' command line mode
[16:48:15.208] INFO: enter test to run
[16:48:55.114] INFO: test: PixelAlive no parameter change
[16:48:55.114] INFO: running: pixelalive
[16:48:55.135] INFO: ----------------------------------------------------------------------
[16:48:55.135] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:48:55.135] INFO: ----------------------------------------------------------------------
[16:48:55.455] INFO: Expecting 41600 events.
[16:48:59.810] INFO: 41600 events read in total (3637ms).
[16:48:59.977] INFO: Test took 4840ms.
[16:48:59.994] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:49:00.336] INFO: PixTestAlive::aliveTest() done
[16:49:00.336] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 4 0 0 0 0
[16:49:00.370] INFO: enter test to run
[16:49:34.234] INFO: test: timing no parameter change
[16:49:34.234] INFO: running: timing
[16:49:34.238] INFO: ######################################################################
[16:49:34.238] INFO: PixTestTiming::doTest()
[16:49:34.238] INFO: ######################################################################
[16:49:34.238] INFO: ----------------------------------------------------------------------
[16:49:34.238] INFO: PixTestTiming::TBMPhaseScan()
[16:49:34.238] INFO: ----------------------------------------------------------------------
[16:54:42.374] INFO: TBM Phase Settings: 216
[16:54:42.374] INFO: 400MHz Phase: 6
[16:54:42.375] INFO: 160MHz Phase: 6
[16:54:42.375] INFO: Functional Phase Area: 4
[16:54:42.378] INFO: Test took 308140 ms.
[16:54:42.378] INFO: PixTestTiming::TBMPhaseScan() done.
[16:54:42.378] INFO: ----------------------------------------------------------------------
[16:54:42.378] INFO: PixTestTiming::ROCDelayScan()
[16:54:42.378] INFO: ----------------------------------------------------------------------
[16:56:36.083] INFO: ROC Delay Settings: 219
[16:56:36.083] INFO: ROC Header-Trailer/Token Delay: 11
[16:56:36.083] INFO: ROC Port 0 Delay: 3
[16:56:36.083] INFO: ROC Port 1 Delay: 3
[16:56:36.083] INFO: Functional ROC Area: 3
[16:56:36.086] INFO: Test took 113708 ms.
[16:56:36.086] INFO: PixTestTiming::ROCDelayScan() done.
[16:56:36.086] INFO: ----------------------------------------------------------------------
[16:56:36.086] INFO: PixTestTiming::TimingTest()
[16:56:36.086] INFO: ----------------------------------------------------------------------
[16:56:52.189] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:07.136] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:22.220] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:37.167] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:52.366] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:58:07.387] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:58:22.342] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:58:37.409] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:58:52.497] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:59:07.617] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:59:07.999] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:59:08.018] INFO: ----------------------------------------------------------------------
[16:59:08.018] INFO: The fraction of properly decoded events is 100.00%: 10000000/10000000
[16:59:08.018] INFO: ----------------------------------------------------------------------
[16:59:08.018] INFO: ----------------------------------------------------------------------
[16:59:08.018] INFO: Read back bit status: 1
[16:59:08.018] INFO: ----------------------------------------------------------------------
[16:59:08.018] INFO: ----------------------------------------------------------------------
[16:59:08.018] INFO: Timings are good!
[16:59:08.018] INFO: ----------------------------------------------------------------------
[16:59:08.018] INFO: Test took 151932 ms.
[16:59:08.018] INFO: PixTestTiming::TimingTest() done.
[16:59:08.018] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//tbmParameters_C0a.dat
[16:59:08.041] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//tbmParameters_C0b.dat
[16:59:08.042] INFO: PixTestTiming::doTest took 573808 ms.
[16:59:08.042] INFO: PixTestTiming::doTest() done
[16:59:08.042] INFO: Write out TBMPhaseScan_0_V0
[16:59:08.042] INFO: Write out TBMPhaseScan_1_V0
[16:59:08.042] INFO: Write out CombinedTBMPhaseScan_V0
[16:59:08.044] INFO: Write out ROCDelayScan3_V0
[16:59:08.045] INFO: enter test to run
[17:00:16.406] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[17:00:16.406] INFO: running: highrate
[17:00:16.406] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[17:00:16.677] INFO: ----------------------------------------------------------------------
[17:00:16.677] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[17:00:16.677] INFO: ----------------------------------------------------------------------
[17:00:16.677] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[17:00:16.677] INFO: edge/corner pixel THR is adjusted
[17:00:16.677] INFO: PixTestHighRate::trimHotPixels: step 0...
[17:00:17.636] INFO: Collecting data for 5 seconds...
[17:00:22.655] INFO: Done with hot pixel readout
[17:00:34.869] INFO: PixTest:: pg_setup set to default.
[17:00:34.869] INFO: 14 hot pixels found in step 0
[17:00:35.865] INFO: Collecting data for 5 seconds...
[17:00:40.883] INFO: Done with hot pixel readout
[17:00:52.990] INFO: PixTest:: pg_setup set to default.
[17:00:52.991] INFO: 8 hot pixels found in step 1
[17:00:53.985] INFO: Collecting data for 5 seconds...
[17:00:58.003] INFO: Done with hot pixel readout
[17:01:11.178] INFO: PixTest:: pg_setup set to default.
[17:01:11.179] INFO: 9 hot pixels found in step 2
[17:01:12.174] INFO: Collecting data for 5 seconds...
[17:01:17.192] INFO: Done with hot pixel readout
[17:01:29.265] INFO: PixTest:: pg_setup set to default.
[17:01:29.266] INFO: 9 hot pixels found in step 3
[17:01:30.260] INFO: Collecting data for 5 seconds...
[17:01:35.282] INFO: Done with hot pixel readout
[17:01:47.504] INFO: PixTest:: pg_setup set to default.
[17:01:47.505] INFO: 9 hot pixels found in step 4
[17:01:48.500] INFO: Collecting data for 5 seconds...
[17:01:53.519] INFO: Done with hot pixel readout
[17:02:05.447] INFO: PixTest:: pg_setup set to default.


[17:02:05.448] INFO: 12 hot pixels found in step 5
[17:02:06.444] INFO: Collecting data for 5 seconds...
[17:02:11.463] INFO: Done with hot pixel readout
[17:02:23.751] INFO: PixTest:: pg_setup set to default.
[17:02:23.752] INFO: 7 hot pixels found in step 6
[17:02:24.746] INFO: Collecting data for 5 seconds...
[17:02:29.763] INFO: Done with hot pixel readout
[17:02:41.706] INFO: PixTest:: pg_setup set to default.
[17:02:41.707] INFO: 3 hot pixels found in step 7
[17:02:42.701] INFO: Collecting data for 5 seconds...
[17:02:47.718] INFO: Done with hot pixel readout
[17:02:59.607] INFO: PixTest:: pg_setup set to default.
[17:02:59.608] INFO: 6 hot pixels found in step 8
[17:03:00.602] INFO: Collecting data for 5 seconds...
[17:03:05.618] INFO: Done with hot pixel readout
[17:03:17.622] INFO: PixTest:: pg_setup set to default.
[17:03:17.623] INFO: 6 hot pixels found in step 9
[17:03:18.617] INFO: Collecting data for 5 seconds...
[17:03:23.634] INFO: Done with hot pixel readout
[17:03:35.536] INFO: PixTest:: pg_setup set to default.
[17:03:35.537] INFO: 7 hot pixels found in step 10
[17:03:36.533] INFO: Collecting data for 5 seconds...
[17:03:41.549] INFO: Done with hot pixel readout
[17:03:53.478] INFO: PixTest:: pg_setup set to default.

[17:03:53.479] INFO: 9 hot pixels found in step 11
[17:03:54.473] INFO: Collecting data for 5 seconds...
[17:03:59.489] INFO: Done with hot pixel readout
[17:04:11.349] INFO: PixTest:: pg_setup set to default.
[17:04:11.350] INFO: 5 hot pixels found in step 12
[17:04:12.344] INFO: Collecting data for 5 seconds...
[17:04:17.361] INFO: Done with hot pixel readout
[17:04:29.234] INFO: PixTest:: pg_setup set to default.
[17:04:29.235] INFO: 5 hot pixels found in step 13
[17:04:30.231] INFO: Collecting data for 5 seconds...
[17:04:35.248] INFO: Done with hot pixel readout
[17:04:47.072] INFO: PixTest:: pg_setup set to default.
[17:04:47.073] INFO: 6 hot pixels found in step 14
[17:04:47.111] INFO: 6 hot pixels could not be trimmed and have been masked.
[17:04:47.114] INFO: PixTest::trimHotPixels() done
[17:04:47.115] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C0.dat
[17:04:47.120] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C1.dat
[17:04:47.126] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C2.dat
[17:04:47.131] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C3.dat
[17:04:47.137] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C4.dat
[17:04:47.142] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C5.dat
[17:04:47.147] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C6.dat
[17:04:47.152] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C7.dat
[17:04:47.157] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C8.dat
[17:04:47.163] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C9.dat
[17:04:47.168] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C10.dat
[17:04:47.173] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C11.dat
[17:04:47.178] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C12.dat
[17:04:47.184] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C13.dat
[17:04:47.189] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C14.dat
[17:04:47.194] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//trimParameters35_C15.dat
[17:04:47.199] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-2-35_FPIXTest-17C-FNAL-161003-1214-150V_2016-10-03_12h14m_1475514868/000_FPIXTest_p17//defaultMaskFile.dat
[17:04:47.210] INFO: enter test to run
[17:05:13.564] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[17:05:13.564] INFO: running: xray
[17:05:13.565] INFO: ----------------------------------------------------------------------
[17:05:13.565] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[17:05:13.565] INFO: ----------------------------------------------------------------------
[17:05:14.528] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[17:05:25.733] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[17:05:54.794] INFO: Resuming triggers.
[17:06:05.002] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[17:06:35.345] INFO: Resuming triggers.
[17:06:46.551] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[17:07:16.015] INFO: Resuming triggers.
[17:07:27.222] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[17:07:56.628] INFO: Resuming triggers.
[17:08:07.832] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[17:08:37.219] INFO: Resuming triggers.
[17:08:48.422] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[17:09:17.847] INFO: Resuming triggers.
[17:09:29.049] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[17:09:58.505] INFO: Resuming triggers.
[17:10:09.706] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[17:10:39.597] INFO: Resuming triggers.
[17:10:50.295] INFO: data taking finished, elapsed time: 100 seconds.
[17:11:18.120] INFO: PixTest:: pg_setup set to default.
[17:11:18.123] INFO: PixTestXray::doPhRun() done
[17:11:18.325] INFO: enter test to run
[17:12:22.265] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[17:12:22.265] INFO: running: xray
[17:12:22.266] INFO: ----------------------------------------------------------------------
[17:12:22.266] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[17:12:22.266] INFO: ----------------------------------------------------------------------
[17:12:23.239] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[17:12:29.655] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[17:13:00.349] INFO: Resuming triggers.
[17:13:06.771] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[17:13:38.244] INFO: Resuming triggers.
[17:13:44.666] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[17:14:15.853] INFO: Resuming triggers.
[17:14:22.275] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[17:14:53.001] INFO: Resuming triggers.
[17:15:00.423] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[17:15:32.031] INFO: Resuming triggers.
[17:15:38.454] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[17:16:09.491] INFO: Resuming triggers.
[17:16:15.916] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[17:16:46.226] INFO: Resuming triggers.
[17:16:52.649] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[17:17:22.663] INFO: Resuming triggers.
[17:17:29.085] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[17:17:59.284] INFO: Resuming triggers.
[17:18:05.711] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[17:18:35.925] INFO: Resuming triggers.
[17:18:42.347] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[17:19:12.391] INFO: Resuming triggers.
[17:19:18.810] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[17:19:48.838] INFO: Resuming triggers.
[17:19:55.257] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[17:20:25.280] INFO: Resuming triggers.
[17:20:31.699] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[17:21:01.733] INFO: Resuming triggers.
[17:21:08.153] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[17:21:38.064] INFO: Resuming triggers.
[17:21:42.091] INFO: data taking finished, elapsed time: 100 seconds.
[17:22:01.152] INFO: PixTest:: pg_setup set to default.
[17:22:01.155] INFO: PixTestXray::doPhRun() done
[17:22:01.304] INFO: enter test to run
[17:22:39.317] INFO: test: HighRate no parameter change
[17:22:39.317] INFO: running: highrate
[17:22:39.331] INFO: ----------------------------------------------------------------------
[17:22:39.332] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[17:22:39.332] INFO: ----------------------------------------------------------------------
[17:22:39.486] INFO: Expecting 768 events.
[17:22:40.620] INFO: 768 events read in total (418ms).
[17:22:40.621] INFO: Test took 1269ms.
[17:22:41.424] INFO: Expecting 41600 events.
[17:22:44.511] INFO: 41600 events read in total (2560ms).
[17:22:44.512] INFO: Test took 3884ms.
[17:22:44.549] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:22:45.273] INFO: Expecting 41600 events.
[17:22:48.509] INFO: 41600 events read in total (2709ms).
[17:22:48.511] INFO: Test took 3943ms.
[17:22:48.549] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:22:49.268] INFO: Expecting 41600 events.
[17:22:52.549] INFO: 41600 events read in total (2754ms).
[17:22:52.550] INFO: Test took 3981ms.
[17:22:52.589] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:22:53.303] INFO: Expecting 41600 events.
[17:22:56.593] INFO: 41600 events read in total (2763ms).
[17:22:56.594] INFO: Test took 3985ms.
[17:22:56.639] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:22:57.344] INFO: Expecting 41600 events.
[17:23:00.629] INFO: 41600 events read in total (2758ms).
[17:23:00.630] INFO: Test took 3970ms.
[17:23:00.668] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:01.385] INFO: Expecting 41600 events.
[17:23:04.670] INFO: 41600 events read in total (2758ms).
[17:23:04.671] INFO: Test took 3985ms.
[17:23:04.710] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:05.424] INFO: Expecting 41600 events.
[17:23:08.668] INFO: 41600 events read in total (2717ms).
[17:23:08.669] INFO: Test took 3939ms.
[17:23:08.706] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:09.424] INFO: Expecting 41600 events.
[17:23:12.736] INFO: 41600 events read in total (2785ms).
[17:23:12.737] INFO: Test took 4010ms.
[17:23:12.777] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:13.490] INFO: Expecting 41600 events.
[17:23:16.781] INFO: 41600 events read in total (2764ms).
[17:23:16.782] INFO: Test took 3984ms.
[17:23:16.821] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:17.536] INFO: Expecting 41600 events.
[17:23:20.824] INFO: 41600 events read in total (2761ms).
[17:23:20.825] INFO: Test took 3984ms.
[17:23:20.864] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:21.579] INFO: Expecting 41600 events.
[17:23:24.857] INFO: 41600 events read in total (2751ms).
[17:23:24.858] INFO: Test took 3973ms.
[17:23:24.897] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:25.612] INFO: Expecting 41600 events.
[17:23:28.917] INFO: 41600 events read in total (2779ms).
[17:23:28.918] INFO: Test took 4000ms.
[17:23:28.958] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:29.672] INFO: Expecting 41600 events.
[17:23:33.037] INFO: 41600 events read in total (2839ms).
[17:23:33.038] INFO: Test took 4061ms.
[17:23:33.077] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:33.789] INFO: Expecting 41600 events.
[17:23:37.048] INFO: 41600 events read in total (2732ms).
[17:23:37.049] INFO: Test took 3952ms.
[17:23:37.088] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:37.801] INFO: Expecting 41600 events.
[17:23:41.129] INFO: 41600 events read in total (2801ms).
[17:23:41.130] INFO: Test took 4023ms.
[17:23:41.169] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:41.884] INFO: Expecting 41600 events.
[17:23:45.315] INFO: 41600 events read in total (2904ms).
[17:23:45.315] INFO: Test took 4127ms.
[17:23:45.354] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:46.066] INFO: Expecting 41600 events.
[17:23:49.386] INFO: 41600 events read in total (2793ms).
[17:23:49.387] INFO: Test took 4014ms.
[17:23:49.427] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:50.142] INFO: Expecting 41600 events.
[17:23:53.426] INFO: 41600 events read in total (2758ms).
[17:23:53.427] INFO: Test took 3981ms.
[17:23:53.467] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:54.178] INFO: Expecting 41600 events.
[17:23:57.499] INFO: 41600 events read in total (2794ms).
[17:23:57.500] INFO: Test took 4011ms.
[17:23:57.538] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:23:58.251] INFO: Expecting 41600 events.
[17:24:01.378] INFO: 41600 events read in total (2601ms).
[17:24:01.380] INFO: Test took 3822ms.
[17:24:01.419] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:01.766] INFO: enter test to run
[17:24:12.996] INFO: test: HighRate no parameter change
[17:24:12.996] INFO: running: highrate
[17:24:12.997] INFO: ----------------------------------------------------------------------
[17:24:12.997] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:24:12.997] INFO: ----------------------------------------------------------------------
[17:24:13.619] INFO: Expecting 208000 events.
[17:24:25.734] INFO: 208000 events read in total (11588ms).
[17:24:25.737] INFO: Test took 12730ms.
[17:24:25.907] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:24:26.168] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 2 4 0 0 0 0
[17:24:26.169] INFO: number of red-efficiency pixels: 74 50 87 154 208 187 162 134 105 155 120 127 137 78 28 19
[17:24:26.169] INFO: number of X-ray hits detected: 71314 48055 74607 115784 128040 132768 129344 88629 88194 120015 115184 98905 104585 63029 24842 28813
[17:24:26.169] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:24:26.169] INFO: number of Vcal hits detected: 207923 207949 207911 207843 207787 207807 207834 207863 207895 207841 207777 207674 207858 207920 207972 207981
[17:24:26.169] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[17:24:26.169] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.9 100.0 100.0 100.0
[17:24:26.169] INFO: X-ray hit rate [MHz/cm2]: 20.9 14.1 21.9 33.9 37.5 38.9 37.9 26.0 25.9 35.2 33.8 29.0 30.7 18.5 7.3 8.4
[17:24:26.169] INFO: PixTestHighRate::doXPixelAlive() done
[17:24:26.217] INFO: PixTest:: pg_setup set to default.
[17:24:26.228] INFO: enter test to run
[17:24:52.548] INFO: test: HighRate no parameter change
[17:24:52.548] INFO: running: highrate
[17:24:52.550] INFO: ----------------------------------------------------------------------
[17:24:52.550] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:24:52.550] INFO: ----------------------------------------------------------------------
[17:24:53.171] INFO: Expecting 208000 events.
[17:25:07.149] INFO: 208000 events read in total (13452ms).
[17:25:07.155] INFO: Test took 14595ms.
[17:25:07.470] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:25:07.777] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 1 4 0 0 0 0
[17:25:07.777] INFO: number of red-efficiency pixels: 230 154 297 472 602 660 489 350 355 507 389 404 398 225 44 53
[17:25:07.777] INFO: number of X-ray hits detected: 134994 90280 141626 218835 243161 250821 244187 168704 166922 227628 217984 187822 198528 120831 47199 54856
[17:25:07.777] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:25:07.777] INFO: number of Vcal hits detected: 207749 207838 207666 207477 207332 207251 207463 207616 207622 207451 207485 207365 207565 207763 207954 207944
[17:25:07.777] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.7 99.8 99.8 99.8 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[17:25:07.777] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.6 99.7 99.8 99.8 99.7 99.8 99.7 99.8 99.9 100.0 100.0
[17:25:07.777] INFO: X-ray hit rate [MHz/cm2]: 39.6 26.5 41.5 64.1 71.3 73.5 71.6 49.4 48.9 66.7 63.9 55.1 58.2 35.4 13.8 16.1
[17:25:07.777] INFO: PixTestHighRate::doXPixelAlive() done
[17:25:07.824] INFO: PixTest:: pg_setup set to default.
[17:25:07.841] INFO: enter test to run
[17:25:21.092] INFO: test: HighRate no parameter change
[17:25:21.092] INFO: running: highrate
[17:25:21.093] INFO: ----------------------------------------------------------------------
[17:25:21.093] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[17:25:21.093] INFO: ----------------------------------------------------------------------
[17:25:21.717] INFO: Expecting 208000 events.
[17:25:37.827] INFO: 208000 events read in total (15583ms).
[17:25:37.835] INFO: Test took 16733ms.
[17:25:38.303] INFO: Fetched DAQ statistics. Counters are being reset now.
[17:25:38.673] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 2 3 0 0 0 0
[17:25:38.673] INFO: number of red-efficiency pixels: 516 286 707 1223 1423 1496 1145 827 688 1252 909 863 950 555 86 93
[17:25:38.673] INFO: number of X-ray hits detected: 204495 137259 214511 331955 369170 381071 370395 256921 253879 345527 330488 285224 300905 183641 71839 83130
[17:25:38.673] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[17:25:38.673] INFO: number of Vcal hits detected: 207390 207688 207048 206458 206084 205924 206550 206970 207159 206415 206796 206728 206863 207293 207911 207902
[17:25:38.673] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.6 99.3 99.2 99.1 99.4 99.5 99.6 99.3 99.5 99.5 99.5 99.7 100.0 100.0
[17:25:38.673] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.5 99.3 99.1 99.0 99.3 99.5 99.6 99.2 99.4 99.4 99.5 99.7 100.0 100.0
[17:25:38.673] INFO: X-ray hit rate [MHz/cm2]: 59.9 40.2 62.9 97.3 108.2 111.7 108.6 75.3 74.4 101.3 96.9 83.6 88.2 53.8 21.1 24.4
[17:25:38.673] INFO: PixTestHighRate::doXPixelAlive() done
[17:25:38.718] INFO: PixTest:: pg_setup set to default.
[17:25:38.732] INFO: enter test to run
[17:25:43.948] INFO: test: exit no parameter change
[17:25:44.456] QUIET: Connection to board 33 closed.
[17:25:44.469] INFO: pXar: this is the end, my friend