[13:13:54.124] INFO: *** Welcome to pxar ***
[13:13:54.124] INFO: *** Today: 2016/10/13
[13:13:54.142] INFO: *** Version: v1.9.0-818-g96727
[13:13:54.142] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//dacParameters35_C15.dat
[13:13:54.143] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:13:54.143] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//defaultMaskFile.dat
[13:13:54.143] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//trimParameters35_C15.dat
[13:13:54.211] INFO: clk: 4
[13:13:54.211] INFO: ctr: 4
[13:13:54.211] INFO: sda: 19
[13:13:54.211] INFO: tin: 9
[13:13:54.211] INFO: level: 15
[13:13:54.212] INFO: triggerdelay: 0
[13:13:54.212] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[13:13:54.212] INFO: Log level: INFO
[13:13:54.229] QUIET: Connection to board DTB_WREKRL opened.
[13:13:54.232] 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:
------------------------------------------------------
[13:13:54.235] INFO: RPC call hashes of host and DTB match: 398089610
[13:13:55.765] INFO: DUT info:
[13:13:55.765] INFO: The DUT currently contains the following objects:
[13:13:55.765] INFO: 2 TBM Cores tbm08c (2 ON)
[13:13:55.765] INFO: TBM Core alpha (0): 7 registers set
[13:13:55.765] INFO: TBM Core beta (1): 7 registers set
[13:13:55.765] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:13:55.765] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:55.765] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:55.765] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:55.765] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:55.765] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:55.765] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:55.765] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:55.765] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:55.765] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:55.765] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:55.765] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:55.766] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:55.766] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:55.766] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:55.766] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:55.766] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:13:56.169] INFO: enter 'restricted' command line mode
[13:13:56.169] INFO: enter test to run
[13:14:09.555] INFO: test: PixelAlive no parameter change
[13:14:09.555] INFO: running: pixelalive
[13:14:09.609] INFO: ----------------------------------------------------------------------
[13:14:09.609] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[13:14:09.609] INFO: ----------------------------------------------------------------------
[13:14:09.929] INFO: Expecting 41600 events.
[13:14:14.211] INFO: 41600 events read in total (3564ms).
[13:14:14.378] INFO: Test took 4765ms.
[13:14:14.391] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:14:14.672] INFO: PixTestAlive::aliveTest() done
[13:14:14.672] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 1 0 0 0 0 0 0 2 0
[13:14:14.726] INFO: enter test to run
[13:14:26.835] INFO: test: timing no parameter change
[13:14:26.835] INFO: running: timing
[13:14:26.838] INFO: ######################################################################
[13:14:26.838] INFO: PixTestTiming::doTest()
[13:14:26.838] INFO: ######################################################################
[13:14:26.838] INFO: ----------------------------------------------------------------------
[13:14:26.838] INFO: PixTestTiming::TBMPhaseScan()
[13:14:26.838] INFO: ----------------------------------------------------------------------
[13:18:59.962] INFO: TBM Phase Settings: 244
[13:18:59.962] INFO: 400MHz Phase: 5
[13:18:59.962] INFO: 160MHz Phase: 7
[13:18:59.962] INFO: Functional Phase Area: 3
[13:18:59.974] INFO: Test took 273136 ms.
[13:18:59.974] INFO: PixTestTiming::TBMPhaseScan() done.
[13:18:59.974] INFO: ----------------------------------------------------------------------
[13:18:59.974] INFO: PixTestTiming::ROCDelayScan()
[13:18:59.974] INFO: ----------------------------------------------------------------------
[13:21:15.984] INFO: ROC Delay Settings: 228
[13:21:15.984] INFO: ROC Header-Trailer/Token Delay: 11
[13:21:15.984] INFO: ROC Port 0 Delay: 4
[13:21:15.984] INFO: ROC Port 1 Delay: 4
[13:21:15.984] INFO: Functional ROC Area: 4
[13:21:15.987] INFO: Test took 136013 ms.
[13:21:15.987] INFO: PixTestTiming::ROCDelayScan() done.
[13:21:15.988] INFO: ----------------------------------------------------------------------
[13:21:15.988] INFO: PixTestTiming::TimingTest()
[13:21:15.988] INFO: ----------------------------------------------------------------------
[13:21:32.241] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:47.296] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:02.264] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:17.230] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:32.170] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:47.144] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:02.104] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:17.085] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:32.033] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:47.032] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:47.410] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:23:47.548] INFO: ----------------------------------------------------------------------
[13:23:47.548] INFO: The fraction of properly decoded events is 100.00%: 10000000/10000000
[13:23:47.548] INFO: ----------------------------------------------------------------------
[13:23:47.548] INFO: ----------------------------------------------------------------------
[13:23:47.548] INFO: Read back bit status: 1
[13:23:47.548] INFO: ----------------------------------------------------------------------
[13:23:47.548] INFO: ----------------------------------------------------------------------
[13:23:47.548] INFO: Timings are good!
[13:23:47.548] INFO: ----------------------------------------------------------------------
[13:23:47.548] INFO: Test took 151561 ms.
[13:23:47.548] INFO: PixTestTiming::TimingTest() done.
[13:23:47.561] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//tbmParameters_C0a.dat
[13:23:47.561] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:23:47.562] INFO: PixTestTiming::doTest took 560727 ms.
[13:23:47.562] INFO: PixTestTiming::doTest() done
[13:23:47.562] INFO: Write out TBMPhaseScan_0_V0
[13:23:47.562] INFO: Write out TBMPhaseScan_1_V0
[13:23:47.562] INFO: Write out CombinedTBMPhaseScan_V0
[13:23:47.604] INFO: Write out ROCDelayScan3_V0
[13:23:47.605] INFO: enter test to run
[13:24:16.687] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:24:16.687] INFO: running: highrate
[13:24:16.705] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:24:16.970] INFO: ----------------------------------------------------------------------
[13:24:16.971] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:24:16.971] INFO: ----------------------------------------------------------------------
[13:24:16.971] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:24:16.971] INFO: edge/corner pixel THR is adjusted
[13:24:16.971] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:24:17.929] INFO: Collecting data for 5 seconds...
[13:24:22.946] INFO: Done with hot pixel readout
[13:24:34.620] INFO: PixTest:: pg_setup set to default.
[13:24:34.621] INFO: 3 hot pixels found in step 0
[13:24:35.614] INFO: Collecting data for 5 seconds...
[13:24:40.631] INFO: Done with hot pixel readout
[13:24:52.412] INFO: PixTest:: pg_setup set to default.
[13:24:52.413] INFO: 7 hot pixels found in step 1
[13:24:53.406] INFO: Collecting data for 5 seconds...
[13:24:58.423] INFO: Done with hot pixel readout
[13:25:10.198] INFO: PixTest:: pg_setup set to default.
[13:25:10.199] INFO: 7 hot pixels found in step 2
[13:25:11.193] INFO: Collecting data for 5 seconds...
[13:25:16.209] INFO: Done with hot pixel readout
[13:25:27.972] INFO: PixTest:: pg_setup set to default.
[13:25:27.972] INFO: 4 hot pixels found in step 3
[13:25:28.966] INFO: Collecting data for 5 seconds...
[13:25:33.983] INFO: Done with hot pixel readout
[13:25:45.758] INFO: PixTest:: pg_setup set to default.
[13:25:45.759] INFO: 5 hot pixels found in step 4
[13:25:46.753] INFO: Collecting data for 5 seconds...
[13:25:51.770] INFO: Done with hot pixel readout
[13:26:03.587] INFO: PixTest:: pg_setup set to default.
[13:26:03.588] INFO: 7 hot pixels found in step 5
[13:26:04.591] INFO: Collecting data for 5 seconds...
[13:26:09.607] INFO: Done with hot pixel readout
[13:26:21.408] INFO: PixTest:: pg_setup set to default.
[13:26:21.409] INFO: 6 hot pixels found in step 6
[13:26:22.402] INFO: Collecting data for 5 seconds...
[13:26:27.418] INFO: Done with hot pixel readout
[13:26:39.280] INFO: PixTest:: pg_setup set to default.
[13:26:39.281] INFO: 7 hot pixels found in step 7
[13:26:40.274] INFO: Collecting data for 5 seconds...
[13:26:45.291] INFO: Done with hot pixel readout
[13:26:56.998] INFO: PixTest:: pg_setup set to default.
[13:26:56.999] INFO: 2 hot pixels found in step 8
[13:26:57.993] INFO: Collecting data for 5 seconds...
[13:27:03.009] INFO: Done with hot pixel readout
[13:27:14.860] INFO: PixTest:: pg_setup set to default.
[13:27:14.861] INFO: 3 hot pixels found in step 9
[13:27:15.855] INFO: Collecting data for 5 seconds...
[13:27:20.871] INFO: Done with hot pixel readout
[13:27:32.596] INFO: PixTest:: pg_setup set to default.
[13:27:32.597] INFO: 5 hot pixels found in step 10
[13:27:33.590] INFO: Collecting data for 5 seconds...
[13:27:38.606] INFO: Done with hot pixel readout
[13:27:50.421] INFO: PixTest:: pg_setup set to default.
[13:27:50.421] INFO: 4 hot pixels found in step 11
[13:27:51.415] INFO: Collecting data for 5 seconds...
[13:27:56.431] INFO: Done with hot pixel readout
[13:28:08.160] INFO: PixTest:: pg_setup set to default.
[13:28:08.161] INFO: 1 hot pixels found in step 12
[13:28:09.154] INFO: Collecting data for 5 seconds...
[13:28:14.170] INFO: Done with hot pixel readout
[13:28:25.928] INFO: PixTest:: pg_setup set to default.
[13:28:25.928] INFO: 3 hot pixels found in step 13
[13:28:26.923] INFO: Collecting data for 5 seconds...
[13:28:31.940] INFO: Done with hot pixel readout
[13:28:43.769] INFO: PixTest:: pg_setup set to default.
[13:28:43.770] INFO: 6 hot pixels found in step 14
[13:28:43.808] INFO: 6 hot pixels could not be trimmed and have been masked.
[13:28:43.811] INFO: PixTest::trimHotPixels() done
[13:28:43.811] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//trimParameters35_C0.dat
[13:28:43.817] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//trimParameters35_C1.dat
[13:28:43.823] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//trimParameters35_C2.dat
[13:28:43.829] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//trimParameters35_C3.dat
[13:28:43.834] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//trimParameters35_C4.dat
[13:28:43.840] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//trimParameters35_C5.dat
[13:28:43.845] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//trimParameters35_C6.dat
[13:28:43.850] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//trimParameters35_C7.dat
[13:28:43.855] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//trimParameters35_C8.dat
[13:28:43.861] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//trimParameters35_C9.dat
[13:28:43.866] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//trimParameters35_C10.dat
[13:28:43.871] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//trimParameters35_C11.dat
[13:28:43.876] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//trimParameters35_C12.dat
[13:28:43.882] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//trimParameters35_C13.dat
[13:28:43.887] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//trimParameters35_C14.dat
[13:28:43.892] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//trimParameters35_C15.dat
[13:28:43.898] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-2-46_FPIXTest-17C-FNAL-161004-1227-150V_2016-10-04_12h27m_1475602047/000_FPIXTest_p17//defaultMaskFile.dat
[13:28:43.908] INFO: enter test to run
[13:29:24.693] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:29:24.693] INFO: running: xray
[13:29:24.694] INFO: ----------------------------------------------------------------------
[13:29:24.694] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:29:24.694] INFO: ----------------------------------------------------------------------
[13:29:25.685] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:29:37.033] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:30:06.692] INFO: Resuming triggers.
[13:30:18.040] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[13:30:48.162] INFO: Resuming triggers.
[13:30:59.511] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[13:31:29.084] INFO: Resuming triggers.
[13:31:40.432] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[13:32:10.366] INFO: Resuming triggers.
[13:32:21.716] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[13:32:50.562] INFO: Resuming triggers.
[13:33:01.912] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[13:33:32.063] INFO: Resuming triggers.
[13:33:43.413] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[13:34:14.082] INFO: Resuming triggers.
[13:34:25.434] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[13:34:55.710] INFO: Resuming triggers.
[13:35:05.281] INFO: data taking finished, elapsed time: 100 seconds.
[13:35:31.395] INFO: PixTest:: pg_setup set to default.
[13:35:31.398] INFO: PixTestXray::doPhRun() done
[13:35:31.660] INFO: enter test to run
[13:37:47.730] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:37:47.730] INFO: running: xray
[13:37:47.731] INFO: ----------------------------------------------------------------------
[13:37:47.731] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:37:47.731] INFO: ----------------------------------------------------------------------
[13:37:48.695] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:37:55.512] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[13:38:25.856] INFO: Resuming triggers.
[13:38:32.671] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[13:39:03.096] INFO: Resuming triggers.
[13:39:09.912] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[13:39:40.306] INFO: Resuming triggers.
[13:39:47.125] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[13:40:17.408] INFO: Resuming triggers.
[13:40:24.224] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[13:40:54.644] INFO: Resuming triggers.
[13:41:01.457] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[13:41:31.771] INFO: Resuming triggers.
[13:41:38.588] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[13:42:09.096] INFO: Resuming triggers.
[13:42:15.909] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[13:42:46.343] INFO: Resuming triggers.
[13:42:53.157] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[13:43:24.092] INFO: Resuming triggers.
[13:43:30.910] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[13:44:01.962] INFO: Resuming triggers.
[13:44:08.778] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[13:44:39.872] INFO: Resuming triggers.
[13:44:46.689] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[13:45:17.770] INFO: Resuming triggers.
[13:45:24.587] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[13:45:55.301] INFO: Resuming triggers.
[13:46:02.120] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[13:46:33.490] INFO: Resuming triggers.
[13:46:38.402] INFO: data taking finished, elapsed time: 100 seconds.
[13:47:01.172] INFO: PixTest:: pg_setup set to default.
[13:47:01.175] INFO: PixTestXray::doPhRun() done
[13:47:01.354] INFO: enter test to run
[13:47:18.950] INFO: test: HighRate no parameter change
[13:47:18.950] INFO: running: highrate
[13:47:18.960] INFO: ----------------------------------------------------------------------
[13:47:18.960] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:47:18.960] INFO: ----------------------------------------------------------------------
[13:47:19.135] INFO: Expecting 768 events.
[13:47:20.283] INFO: 768 events read in total (433ms).
[13:47:20.283] INFO: Test took 1282ms.
[13:47:21.086] INFO: Expecting 41600 events.
[13:47:24.237] INFO: 41600 events read in total (2624ms).
[13:47:24.238] INFO: Test took 3905ms.
[13:47:24.271] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:24.998] INFO: Expecting 41600 events.
[13:47:28.233] INFO: 41600 events read in total (2709ms).
[13:47:28.234] INFO: Test took 3929ms.
[13:47:28.268] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:28.989] INFO: Expecting 41600 events.
[13:47:32.244] INFO: 41600 events read in total (2728ms).
[13:47:32.245] INFO: Test took 3960ms.
[13:47:32.280] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:32.000] INFO: Expecting 41600 events.
[13:47:36.251] INFO: 41600 events read in total (2724ms).
[13:47:36.252] INFO: Test took 3955ms.
[13:47:36.287] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:37.007] INFO: Expecting 41600 events.
[13:47:40.335] INFO: 41600 events read in total (2801ms).
[13:47:40.336] INFO: Test took 4032ms.
[13:47:40.369] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:41.094] INFO: Expecting 41600 events.
[13:47:44.553] INFO: 41600 events read in total (2932ms).
[13:47:44.554] INFO: Test took 4166ms.
[13:47:44.596] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:45.300] INFO: Expecting 41600 events.
[13:47:48.683] INFO: 41600 events read in total (2857ms).
[13:47:48.684] INFO: Test took 4068ms.
[13:47:48.718] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:49.439] INFO: Expecting 41600 events.
[13:47:52.714] INFO: 41600 events read in total (2748ms).
[13:47:52.715] INFO: Test took 3979ms.
[13:47:52.750] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:53.469] INFO: Expecting 41600 events.
[13:47:56.787] INFO: 41600 events read in total (2791ms).
[13:47:56.788] INFO: Test took 4021ms.
[13:47:56.822] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:47:57.547] INFO: Expecting 41600 events.
[13:48:00.811] INFO: 41600 events read in total (2737ms).
[13:48:00.812] INFO: Test took 3972ms.
[13:48:00.847] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:01.564] INFO: Expecting 41600 events.
[13:48:04.849] INFO: 41600 events read in total (2759ms).
[13:48:04.850] INFO: Test took 3984ms.
[13:48:04.885] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:05.608] INFO: Expecting 41600 events.
[13:48:08.987] INFO: 41600 events read in total (2852ms).
[13:48:08.987] INFO: Test took 4085ms.
[13:48:09.023] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:09.743] INFO: Expecting 41600 events.
[13:48:13.164] INFO: 41600 events read in total (2894ms).
[13:48:13.164] INFO: Test took 4124ms.
[13:48:13.199] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:13.920] INFO: Expecting 41600 events.
[13:48:17.230] INFO: 41600 events read in total (2783ms).
[13:48:17.231] INFO: Test took 4013ms.
[13:48:17.265] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:17.985] INFO: Expecting 41600 events.
[13:48:21.278] INFO: 41600 events read in total (2766ms).
[13:48:21.279] INFO: Test took 3996ms.
[13:48:21.313] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:22.037] INFO: Expecting 41600 events.
[13:48:25.368] INFO: 41600 events read in total (2804ms).
[13:48:25.369] INFO: Test took 4038ms.
[13:48:25.403] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:26.128] INFO: Expecting 41600 events.
[13:48:29.482] INFO: 41600 events read in total (2827ms).
[13:48:29.483] INFO: Test took 4062ms.
[13:48:29.517] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:30.237] INFO: Expecting 41600 events.
[13:48:33.567] INFO: 41600 events read in total (2803ms).
[13:48:33.568] INFO: Test took 4034ms.
[13:48:33.602] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:34.317] INFO: Expecting 41600 events.
[13:48:37.607] INFO: 41600 events read in total (2763ms).
[13:48:37.608] INFO: Test took 3988ms.
[13:48:37.642] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:38.365] INFO: Expecting 41600 events.
[13:48:41.553] INFO: 41600 events read in total (2661ms).
[13:48:41.554] INFO: Test took 3892ms.
[13:48:41.586] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:48:41.943] INFO: enter test to run
[13:48:59.845] INFO: test: HighRate no parameter change
[13:48:59.845] INFO: running: highrate
[13:48:59.846] INFO: ----------------------------------------------------------------------
[13:48:59.846] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:48:59.846] INFO: ----------------------------------------------------------------------
[13:49:00.468] INFO: Expecting 208000 events.
[13:49:12.640] INFO: 208000 events read in total (11646ms).
[13:49:12.643] INFO: Test took 12788ms.
[13:49:12.787] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:13.040] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 1 0 0 0 0 0 0 2 0
[13:49:13.040] INFO: number of red-efficiency pixels: 75 51 59 115 112 145 103 68 96 163 134 117 128 65 28 24
[13:49:13.040] INFO: number of X-ray hits detected: 67069 43079 58417 90266 93981 96903 94381 70308 81715 109446 109495 95271 98711 59097 23221 26695
[13:49:13.041] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:49:13.041] INFO: number of Vcal hits detected: 207924 207948 207939 207885 207837 207820 207895 207880 207902 207834 207864 207879 207869 207935 207874 207976
[13:49:13.041] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 100.0 99.9 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[13:49:13.041] INFO: Vcal hit overall 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 99.9 100.0
[13:49:13.041] INFO: X-ray hit rate [MHz/cm2]: 19.7 12.6 17.1 26.5 27.5 28.4 27.7 20.6 24.0 32.1 32.1 27.9 28.9 17.3 6.8 7.8
[13:49:13.041] INFO: PixTestHighRate::doXPixelAlive() done
[13:49:13.087] INFO: PixTest:: pg_setup set to default.
[13:49:13.104] INFO: enter test to run
[13:49:30.397] INFO: test: HighRate no parameter change
[13:49:30.397] INFO: running: highrate
[13:49:30.398] INFO: ----------------------------------------------------------------------
[13:49:30.398] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:49:30.398] INFO: ----------------------------------------------------------------------
[13:49:31.019] INFO: Expecting 208000 events.
[13:49:44.818] INFO: 208000 events read in total (13272ms).
[13:49:44.823] INFO: Test took 14416ms.
[13:49:45.113] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:49:45.416] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 1 0 0 0 0 0 0 2 0
[13:49:45.416] INFO: number of red-efficiency pixels: 207 139 209 366 299 331 312 232 318 529 503 332 304 180 50 63
[13:49:45.416] INFO: number of X-ray hits detected: 140967 91019 122690 189933 197728 203612 199490 149254 172273 229636 229301 200366 207677 125070 48964 57434
[13:49:45.416] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:49:45.416] INFO: number of Vcal hits detected: 207779 207852 207779 207610 207634 207606 207664 207711 207666 207417 207451 207642 207678 207808 207852 207937
[13:49:45.416] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.9 99.8 99.9 99.9 99.9 99.7 99.8 99.9 99.9 99.9 100.0 100.0
[13:49:45.416] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.8 99.7 99.7 99.8 99.8 99.9 99.9 100.0
[13:49:45.416] INFO: X-ray hit rate [MHz/cm2]: 41.3 26.7 36.0 55.7 58.0 59.7 58.5 43.7 50.5 67.3 67.2 58.7 60.9 36.7 14.4 16.8
[13:49:45.416] INFO: PixTestHighRate::doXPixelAlive() done
[13:49:45.465] INFO: PixTest:: pg_setup set to default.
[13:49:45.477] INFO: enter test to run
[13:49:58.709] INFO: test: HighRate no parameter change
[13:49:58.709] INFO: running: highrate
[13:49:58.710] INFO: ----------------------------------------------------------------------
[13:49:58.710] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:49:58.710] INFO: ----------------------------------------------------------------------
[13:49:59.327] INFO: Expecting 208000 events.
[13:50:15.357] INFO: 208000 events read in total (15503ms).
[13:50:15.364] INFO: Test took 16644ms.
[13:50:15.816] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:50:16.170] INFO: number of dead pixels (per ROC): 0 0 0 0 1 0 0 1 0 0 0 0 0 0 2 0
[13:50:16.170] INFO: number of red-efficiency pixels: 442 296 444 818 651 722 670 484 709 1314 1098 686 713 403 98 106
[13:50:16.170] INFO: number of X-ray hits detected: 215366 139924 186585 292728 303476 311562 305681 227084 263562 352210 349039 307363 317440 191009 75530 87253
[13:50:16.170] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:50:16.170] INFO: number of Vcal hits detected: 207492 207681 207451 207047 207219 207091 207234 207401 207148 206314 206592 207202 207176 207515 207802 207892
[13:50:16.170] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.6 99.7 99.6 99.7 99.8 99.6 99.3 99.4 99.7 99.6 99.8 100.0 99.9
[13:50:16.170] INFO: Vcal hit overall efficiency (%): 99.8 99.8 99.7 99.5 99.6 99.6 99.6 99.7 99.6 99.2 99.3 99.6 99.6 99.8 99.9 99.9
[13:50:16.170] INFO: X-ray hit rate [MHz/cm2]: 63.1 41.0 54.7 85.8 89.0 91.3 89.6 66.6 77.3 103.2 102.3 90.1 93.0 56.0 22.1 25.6
[13:50:16.170] INFO: PixTestHighRate::doXPixelAlive() done
[13:50:16.216] INFO: PixTest:: pg_setup set to default.
[13:50:16.231] INFO: enter test to run
[13:50:22.772] INFO: test: exit no parameter change
[13:50:23.858] QUIET: Connection to board 33 closed.
[13:50:24.147] INFO: pXar: this is the end, my friend