[14:28:22.797] INFO: *** Welcome to pxar ***
[14:28:22.797] INFO: *** Today: 2016/11/14
[14:28:22.833] INFO: *** Version: v1.9.0-818-g96727
[14:28:22.833] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//dacParameters35_C15.dat
[14:28:23.203] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:28:23.203] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//defaultMaskFile.dat
[14:28:23.208] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C15.dat
[14:28:23.776] INFO: clk: 4
[14:28:23.776] INFO: ctr: 4
[14:28:23.776] INFO: sda: 19
[14:28:23.776] INFO: tin: 9
[14:28:23.776] INFO: level: 15
[14:28:23.776] INFO: triggerdelay: 0
[14:28:23.776] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[14:28:23.776] INFO: Log level: INFO
[14:28:23.810] QUIET: Connection to board DTB_WREHUL opened.
[14:28:23.814] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 30
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREHUL
MAC address: 40D85511801E
Hostname: pixelDTB030
Comment:
------------------------------------------------------
[14:28:23.817] INFO: RPC call hashes of host and DTB match: 398089610
[14:28:25.352] INFO: DUT info:
[14:28:25.352] INFO: The DUT currently contains the following objects:
[14:28:25.352] INFO: 2 TBM Cores tbm08c (2 ON)
[14:28:25.365] INFO: TBM Core alpha (0): 7 registers set
[14:28:25.365] INFO: TBM Core beta (1): 7 registers set
[14:28:25.365] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:28:25.365] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:25.365] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:25.365] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:25.365] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:25.365] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:25.365] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:25.365] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:25.365] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:25.365] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:25.365] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:25.365] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:25.365] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:25.365] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:25.365] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:25.365] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:25.365] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:28:25.803] INFO: enter 'restricted' command line mode
[14:28:25.803] INFO: enter test to run
[14:28:28.604] INFO: test: PixelAlive no parameter change
[14:28:28.604] INFO: running: pixelalive
[14:28:28.707] INFO: ----------------------------------------------------------------------
[14:28:28.707] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:28:28.707] INFO: ----------------------------------------------------------------------
[14:28:29.029] INFO: Expecting 41600 events.
[14:28:33.351] INFO: 41600 events read in total (3604ms).
[14:28:33.518] INFO: Test took 4807ms.
[14:28:33.531] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:28:35.409] INFO: PixTestAlive::aliveTest() done
[14:28:35.409] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 2 0 0 7 1
[14:28:35.490] INFO: enter test to run
[14:31:59.043] INFO: test: timing no parameter change
[14:31:59.043] INFO: running: timing
[14:31:59.047] INFO: ######################################################################
[14:31:59.047] INFO: PixTestTiming::doTest()
[14:31:59.047] INFO: ######################################################################
[14:31:59.047] INFO: ----------------------------------------------------------------------
[14:31:59.047] INFO: PixTestTiming::TBMPhaseScan()
[14:31:59.047] INFO: ----------------------------------------------------------------------
[14:36:59.637] INFO: TBM Phase Settings: 232
[14:36:59.637] INFO: 400MHz Phase: 2
[14:36:59.637] INFO: 160MHz Phase: 7
[14:36:59.637] INFO: Functional Phase Area: 3
[14:36:59.649] INFO: Test took 300602 ms.
[14:36:59.649] INFO: PixTestTiming::TBMPhaseScan() done.
[14:36:59.649] INFO: ----------------------------------------------------------------------
[14:36:59.649] INFO: PixTestTiming::ROCDelayScan()
[14:36:59.649] INFO: ----------------------------------------------------------------------
[14:39:12.852] INFO: ROC Delay Settings: 228
[14:39:12.852] INFO: ROC Header-Trailer/Token Delay: 11
[14:39:12.852] INFO: ROC Port 0 Delay: 4
[14:39:12.852] INFO: ROC Port 1 Delay: 4
[14:39:12.852] INFO: Functional ROC Area: 4
[14:39:12.855] INFO: Test took 133206 ms.
[14:39:12.855] INFO: PixTestTiming::ROCDelayScan() done.
[14:39:12.855] INFO: ----------------------------------------------------------------------
[14:39:12.855] INFO: PixTestTiming::TimingTest()
[14:39:12.855] INFO: ----------------------------------------------------------------------
[14:39:29.037] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:44.039] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:39:59.026] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:13.998] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:29.049] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:43.905] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:40:58.039] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:12.667] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:27.626] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:42.511] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:42.888] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:41:42.905] INFO: ----------------------------------------------------------------------
[14:41:42.905] INFO: The fraction of properly decoded events is 100.00%: 10000000/10000000
[14:41:42.905] INFO: ----------------------------------------------------------------------
[14:41:42.905] INFO: ----------------------------------------------------------------------
[14:41:42.905] INFO: Read back bit status: 1
[14:41:42.905] INFO: ----------------------------------------------------------------------
[14:41:42.905] INFO: ----------------------------------------------------------------------
[14:41:42.905] INFO: Timings are good!
[14:41:42.905] INFO: ----------------------------------------------------------------------
[14:41:42.905] INFO: Test took 150050 ms.
[14:41:42.905] INFO: PixTestTiming::TimingTest() done.
[14:41:43.046] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//tbmParameters_C0a.dat
[14:41:43.070] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:41:43.071] INFO: PixTestTiming::doTest took 584028 ms.
[14:41:43.071] INFO: PixTestTiming::doTest() done
[14:41:43.071] INFO: Write out TBMPhaseScan_0_V0
[14:41:43.071] INFO: Write out TBMPhaseScan_1_V0
[14:41:43.071] INFO: Write out CombinedTBMPhaseScan_V0
[14:41:43.123] INFO: Write out ROCDelayScan3_V0
[14:41:43.124] INFO: enter test to run
[14:43:37.430] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:43:37.430] INFO: running: highrate
[14:43:37.463] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:43:39.419] INFO: ----------------------------------------------------------------------
[14:43:39.419] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:43:39.419] INFO: ----------------------------------------------------------------------
[14:43:39.419] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:43:39.419] INFO: edge/corner pixel THR is adjusted
[14:43:39.419] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:43:40.377] INFO: Collecting data for 5 seconds...
[14:43:45.394] INFO: Done with hot pixel readout
[14:43:56.725] INFO: PixTest:: pg_setup set to default.
[14:43:56.726] INFO: 2 hot pixels found in step 0
[14:43:57.718] INFO: Collecting data for 5 seconds...
[14:44:02.735] INFO: Done with hot pixel readout
[14:44:14.124] INFO: PixTest:: pg_setup set to default.
[14:44:14.125] INFO: 0 hot pixels found in step 1
[14:44:14.159] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:44:14.163] INFO: PixTest::trimHotPixels() done
[14:44:14.163] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C0.dat
[14:44:14.211] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C1.dat
[14:44:14.261] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C2.dat
[14:44:14.272] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C3.dat
[14:44:14.277] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C4.dat
[14:44:14.283] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C5.dat
[14:44:14.288] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C6.dat
[14:44:14.294] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C7.dat
[14:44:14.299] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C8.dat
[14:44:14.304] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C9.dat
[14:44:14.310] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C10.dat
[14:44:14.315] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C11.dat
[14:44:14.320] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C12.dat
[14:44:14.326] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C13.dat
[14:44:14.331] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C14.dat
[14:44:14.336] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C15.dat
[14:44:14.342] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//defaultMaskFile.dat
[14:44:14.352] INFO: enter test to run
[14:45:43.525] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:45:43.525] INFO: running: highrate
[14:45:43.529] INFO: ----------------------------------------------------------------------
[14:45:43.529] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:45:43.529] INFO: ----------------------------------------------------------------------
[14:45:43.529] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:45:43.529] INFO: edge/corner pixel THR is adjusted
[14:45:43.529] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:45:44.486] INFO: Collecting data for 1 seconds...
[14:45:45.490] INFO: Done with hot pixel readout
[14:45:49.323] INFO: PixTest:: pg_setup set to default.
[14:45:49.324] INFO: 0 hot pixels found in step 0
[14:45:49.329] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:45:49.419] INFO: PixTest::trimHotPixels() done
[14:45:49.419] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C0.dat
[14:45:49.431] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C1.dat
[14:45:49.436] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C2.dat
[14:45:49.441] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C3.dat
[14:45:49.446] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C4.dat
[14:45:49.452] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C5.dat
[14:45:49.457] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C6.dat
[14:45:49.462] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C7.dat
[14:45:49.468] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C8.dat
[14:45:49.473] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C9.dat
[14:45:49.478] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C10.dat
[14:45:49.483] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C11.dat
[14:45:49.489] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C12.dat
[14:45:49.560] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C13.dat
[14:45:49.567] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C14.dat
[14:45:49.572] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//trimParameters35_C15.dat
[14:45:49.577] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-3-37_FPIXTest-17C-FNAL-161108-1225-150V_2016-11-08_12h25m_1478629555/000_FPIXTest_p17//defaultMaskFile.dat
[14:45:49.589] INFO: enter test to run
[14:46:14.453] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:46:14.453] INFO: running: xray
[14:46:14.454] INFO: ----------------------------------------------------------------------
[14:46:14.454] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:46:14.454] INFO: ----------------------------------------------------------------------
[14:46:15.417] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:46:27.079] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:46:57.033] INFO: Resuming triggers.
[14:47:08.701] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[14:47:38.757] INFO: Resuming triggers.
[14:47:50.425] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:48:19.852] INFO: Resuming triggers.
[14:48:31.522] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[14:49:01.822] INFO: Resuming triggers.
[14:49:13.491] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[14:49:43.511] INFO: Resuming triggers.
[14:49:55.177] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[14:50:25.083] INFO: Resuming triggers.
[14:50:36.755] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[14:51:07.077] INFO: Resuming triggers.
[14:51:18.748] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[14:51:48.901] INFO: Resuming triggers.
[14:51:55.927] INFO: data taking finished, elapsed time: 100 seconds.
[14:52:14.166] INFO: PixTest:: pg_setup set to default.
[14:52:14.169] INFO: PixTestXray::doPhRun() done
[14:52:14.680] INFO: enter test to run
[14:54:02.106] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:54:02.106] INFO: running: xray
[14:54:02.107] INFO: ----------------------------------------------------------------------
[14:54:02.107] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:54:02.108] INFO: ----------------------------------------------------------------------
[14:54:03.081] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:54:09.980] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[14:54:40.258] INFO: Resuming triggers.
[14:54:47.155] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[14:55:17.321] INFO: Resuming triggers.
[14:55:24.216] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[14:55:54.565] INFO: Resuming triggers.
[14:56:01.467] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[14:56:31.751] INFO: Resuming triggers.
[14:56:38.647] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:57:08.875] INFO: Resuming triggers.
[14:57:15.767] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[14:57:46.158] INFO: Resuming triggers.
[14:57:53.052] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[14:58:23.822] INFO: Resuming triggers.
[14:58:30.715] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[14:59:01.750] INFO: Resuming triggers.
[14:59:08.646] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[14:59:39.235] INFO: Resuming triggers.
[14:59:46.128] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[15:00:16.941] INFO: Resuming triggers.
[15:00:23.833] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[15:00:54.549] INFO: Resuming triggers.
[15:01:01.443] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[15:01:32.331] INFO: Resuming triggers.
[15:01:39.229] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[15:02:10.176] INFO: Resuming triggers.
[15:02:17.072] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[15:02:48.108] INFO: Resuming triggers.
[15:02:51.920] INFO: data taking finished, elapsed time: 100 seconds.
[15:03:09.121] INFO: PixTest:: pg_setup set to default.
[15:03:09.124] INFO: PixTestXray::doPhRun() done
[15:03:09.321] INFO: enter test to run
[15:05:09.638] INFO: test: HighRate no parameter change
[15:05:09.638] INFO: running: highrate
[15:05:09.653] INFO: ----------------------------------------------------------------------
[15:05:09.653] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:05:09.653] INFO: ----------------------------------------------------------------------
[15:05:09.993] INFO: Expecting 768 events.
[15:05:11.127] INFO: 768 events read in total (419ms).
[15:05:11.128] INFO: Test took 1307ms.
[15:05:11.931] INFO: Expecting 41600 events.
[15:05:14.996] INFO: 41600 events read in total (2538ms).
[15:05:14.997] INFO: Test took 3672ms.
[15:05:15.028] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:05:15.759] INFO: Expecting 41600 events.
[15:05:18.929] INFO: 41600 events read in total (2643ms).
[15:05:18.931] INFO: Test took 3846ms.
[15:05:18.966] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:05:19.685] INFO: Expecting 41600 events.
[15:05:22.944] INFO: 41600 events read in total (2732ms).
[15:05:22.945] INFO: Test took 3962ms.
[15:05:22.977] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:05:23.706] INFO: Expecting 41600 events.
[15:05:26.936] INFO: 41600 events read in total (2704ms).
[15:05:26.937] INFO: Test took 3943ms.
[15:05:26.968] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:05:27.694] INFO: Expecting 41600 events.
[15:05:30.922] INFO: 41600 events read in total (2701ms).
[15:05:30.923] INFO: Test took 3938ms.
[15:05:30.954] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:05:31.681] INFO: Expecting 41600 events.
[15:05:34.919] INFO: 41600 events read in total (2711ms).
[15:05:34.920] INFO: Test took 3949ms.
[15:05:34.955] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:05:35.679] INFO: Expecting 41600 events.
[15:05:39.022] INFO: 41600 events read in total (2816ms).
[15:05:39.022] INFO: Test took 4048ms.
[15:05:39.055] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:05:39.781] INFO: Expecting 41600 events.
[15:05:43.019] INFO: 41600 events read in total (2711ms).
[15:05:43.020] INFO: Test took 3948ms.
[15:05:43.051] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:05:43.779] INFO: Expecting 41600 events.
[15:05:47.035] INFO: 41600 events read in total (2730ms).
[15:05:47.036] INFO: Test took 3968ms.
[15:05:47.072] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:05:47.784] INFO: Expecting 41600 events.
[15:05:51.032] INFO: 41600 events read in total (2721ms).
[15:05:51.033] INFO: Test took 3943ms.
[15:05:51.064] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:05:51.789] INFO: Expecting 41600 events.
[15:05:55.042] INFO: 41600 events read in total (2726ms).
[15:05:55.043] INFO: Test took 3962ms.
[15:05:55.075] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:05:55.802] INFO: Expecting 41600 events.
[15:05:59.038] INFO: 41600 events read in total (2710ms).
[15:05:59.039] INFO: Test took 3948ms.
[15:05:59.070] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:05:59.797] INFO: Expecting 41600 events.
[15:06:03.056] INFO: 41600 events read in total (2732ms).
[15:06:03.057] INFO: Test took 3970ms.
[15:06:03.089] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:03.812] INFO: Expecting 41600 events.
[15:06:07.185] INFO: 41600 events read in total (2846ms).
[15:06:07.186] INFO: Test took 4080ms.
[15:06:07.218] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:07.944] INFO: Expecting 41600 events.
[15:06:11.218] INFO: 41600 events read in total (2747ms).
[15:06:11.219] INFO: Test took 3984ms.
[15:06:11.249] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:11.978] INFO: Expecting 41600 events.
[15:06:15.179] INFO: 41600 events read in total (2674ms).
[15:06:15.180] INFO: Test took 3914ms.
[15:06:15.211] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:15.940] INFO: Expecting 41600 events.
[15:06:19.188] INFO: 41600 events read in total (2721ms).
[15:06:19.189] INFO: Test took 3961ms.
[15:06:19.221] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:19.949] INFO: Expecting 41600 events.
[15:06:23.248] INFO: 41600 events read in total (2773ms).
[15:06:23.249] INFO: Test took 4012ms.
[15:06:23.280] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:23.000] INFO: Expecting 41600 events.
[15:06:27.202] INFO: 41600 events read in total (2675ms).
[15:06:27.203] INFO: Test took 3906ms.
[15:06:27.235] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:27.964] INFO: Expecting 41600 events.
[15:06:31.038] INFO: 41600 events read in total (2547ms).
[15:06:31.039] INFO: Test took 3787ms.
[15:06:31.070] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:06:31.442] INFO: enter test to run
[15:06:48.037] INFO: test: HighRate no parameter change
[15:06:48.037] INFO: running: highrate
[15:06:48.038] INFO: ----------------------------------------------------------------------
[15:06:48.038] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:06:48.038] INFO: ----------------------------------------------------------------------
[15:06:48.670] INFO: Expecting 208000 events.
[15:07:00.470] INFO: 208000 events read in total (11273ms).
[15:07:00.473] INFO: Test took 12417ms.
[15:07:00.602] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:07:00.849] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 2 0 0 7 1
[15:07:00.869] INFO: number of red-efficiency pixels: 56 39 70 133 99 117 152 62 71 101 109 82 84 60 51 26
[15:07:00.869] INFO: number of X-ray hits detected: 51431 40106 61112 94381 100462 100959 105056 65846 60228 88298 87228 74663 79168 49668 20076 24247
[15:07:00.869] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:07:00.869] INFO: number of Vcal hits detected: 207942 207961 207928 207866 207898 207869 207843 207937 207929 207898 207889 207817 207916 207940 207606 207925
[15:07:00.869] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[15:07:00.869] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 100.0 99.9 99.9 100.0 100.0 100.0 99.9 99.9 100.0 100.0 99.8 100.0
[15:07:00.869] INFO: X-ray hit rate [MHz/cm2]: 15.1 11.8 17.9 27.7 29.4 29.6 30.8 19.3 17.7 25.9 25.6 21.9 23.2 14.6 5.9 7.1
[15:07:00.869] INFO: PixTestHighRate::doXPixelAlive() done
[15:07:00.917] INFO: PixTest:: pg_setup set to default.
[15:07:00.930] INFO: enter test to run
[15:08:54.684] INFO: test: HighRate no parameter change
[15:08:54.684] INFO: running: highrate
[15:08:54.685] INFO: ----------------------------------------------------------------------
[15:08:54.685] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:08:54.685] INFO: ----------------------------------------------------------------------
[15:08:55.303] INFO: Expecting 208000 events.
[15:09:08.738] INFO: 208000 events read in total (12908ms).
[15:09:08.743] INFO: Test took 14049ms.
[15:09:09.027] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:09.324] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 2 0 0 6 1
[15:09:09.324] INFO: number of red-efficiency pixels: 184 122 226 416 359 348 495 179 169 331 352 243 303 188 78 65
[15:09:09.324] INFO: number of X-ray hits detected: 114831 88700 135852 208476 224147 224943 231212 147623 134434 194607 194488 165747 175553 112412 43924 54277
[15:09:09.324] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:09:09.324] INFO: number of Vcal hits detected: 207807 207876 207757 207550 207619 207628 207465 207816 207826 207647 207619 207642 207683 207795 207578 207886
[15:09:09.324] 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.8 99.9 99.9 99.9 100.0 100.0
[15:09:09.325] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.9 99.9 99.8 99.8 99.8 99.8 99.9 99.8 99.9
[15:09:09.325] INFO: X-ray hit rate [MHz/cm2]: 33.7 26.0 39.8 61.1 65.7 65.9 67.8 43.3 39.4 57.0 57.0 48.6 51.5 32.9 12.9 15.9
[15:09:09.325] INFO: PixTestHighRate::doXPixelAlive() done
[15:09:09.377] INFO: PixTest:: pg_setup set to default.
[15:09:09.391] INFO: enter test to run
[15:09:27.036] INFO: test: HighRate no parameter change
[15:09:27.036] INFO: running: highrate
[15:09:27.037] INFO: ----------------------------------------------------------------------
[15:09:27.037] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:09:27.037] INFO: ----------------------------------------------------------------------
[15:09:27.654] INFO: Expecting 208000 events.
[15:09:42.857] INFO: 208000 events read in total (14677ms).
[15:09:42.864] INFO: Test took 15818ms.
[15:09:43.276] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:09:43.623] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 2 0 0 6 1
[15:09:43.623] INFO: number of red-efficiency pixels: 321 222 451 1038 879 795 1231 417 348 773 761 481 647 456 135 118
[15:09:43.623] INFO: number of X-ray hits detected: 172604 134134 206878 315928 337564 339418 350515 222447 204313 295348 293239 250772 265988 168020 67458 82506
[15:09:43.623] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[15:09:43.623] INFO: number of Vcal hits detected: 207637 207767 207433 206736 206979 207021 206412 207521 207626 207083 207117 207360 207253 207450 207512 207830
[15:09:43.623] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.4 99.6 99.6 99.3 99.8 99.8 99.6 99.6 99.8 99.7 99.8 99.9 99.9
[15:09:43.623] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.7 99.4 99.5 99.5 99.2 99.8 99.8 99.6 99.6 99.7 99.6 99.7 99.8 99.9
[15:09:43.623] INFO: X-ray hit rate [MHz/cm2]: 50.6 39.3 60.6 92.6 98.9 99.5 102.7 65.2 59.9 86.6 86.0 73.5 78.0 49.2 19.8 24.2
[15:09:43.623] INFO: PixTestHighRate::doXPixelAlive() done
[15:09:43.669] INFO: PixTest:: pg_setup set to default.
[15:09:43.686] INFO: enter test to run
[15:09:58.171] INFO: test: exit no parameter change
[15:09:59.383] QUIET: Connection to board 30 closed.
[15:09:59.587] INFO: pXar: this is the end, my friend