[14:52:05.357] INFO: *** Welcome to pxar ***
[14:52:05.357] INFO: *** Today: 2016/08/23
[14:52:07.899] INFO: *** Version: v1.9.0-818-g96727
[14:52:07.899] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//dacParameters35_C15.dat
[14:52:07.900] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:52:07.901] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//defaultMaskFile.dat
[14:52:07.901] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C15.dat
[14:52:07.999] INFO: clk: 4
[14:52:07.999] INFO: ctr: 4
[14:52:07.999] INFO: sda: 19
[14:52:07.999] INFO: tin: 9
[14:52:07.999] INFO: level: 15
[14:52:07.999] INFO: triggerdelay: 0
[14:52:07.999] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[14:52:07.999] INFO: Log level: INFO
[14:52:08.016] QUIET: Connection to board DTB_WREKRL opened.
[14:52:08.019] 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:
------------------------------------------------------
[14:52:08.037] INFO: RPC call hashes of host and DTB match: 398089610
[14:52:09.585] INFO: DUT info:
[14:52:09.599] INFO: The DUT currently contains the following objects:
[14:52:09.600] INFO: 2 TBM Cores tbm08c (2 ON)
[14:52:09.600] INFO: TBM Core alpha (0): 7 registers set
[14:52:09.600] INFO: TBM Core beta (1): 7 registers set
[14:52:09.600] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:52:09.600] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:09.600] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:09.600] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:09.600] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:09.600] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:09.600] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:09.600] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:09.600] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:09.600] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:09.600] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:09.600] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:09.600] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:09.600] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:09.600] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:09.600] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:09.600] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:52:10.077] INFO: enter 'restricted' command line mode
[14:52:10.077] INFO: enter test to run
[14:52:14.436] INFO: test: PixelAlive no parameter change
[14:52:14.436] INFO: running: pixelalive
[14:52:14.446] INFO: ----------------------------------------------------------------------
[14:52:14.446] 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:52:14.446] INFO: ----------------------------------------------------------------------
[14:52:14.796] INFO: Expecting 41600 events.
[14:52:19.202] INFO: 41600 events read in total (3688ms).
[14:52:19.367] INFO: Test took 4918ms.
[14:52:19.382] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:52:19.692] INFO: PixTestAlive::aliveTest() done
[14:52:19.692] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[14:52:19.725] INFO: enter test to run
[14:52:43.284] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:52:43.284] INFO: running: highrate
[14:52:43.299] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:52:44.491] INFO: ----------------------------------------------------------------------
[14:52:44.491] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:52:44.491] INFO: ----------------------------------------------------------------------
[14:52:44.491] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:52:44.491] INFO: edge/corner pixel THR is adjusted
[14:52:44.491] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:52:45.479] INFO: Collecting data for 5 seconds...
[14:52:50.495] INFO: Done with hot pixel readout
[14:53:00.648] INFO: PixTest:: pg_setup set to default.
[14:53:00.649] INFO: 0 hot pixels found in step 0
[14:53:00.662] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:53:00.665] INFO: PixTest::trimHotPixels() done
[14:53:00.709] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C0.dat
[14:53:00.720] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C1.dat
[14:53:00.727] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C2.dat
[14:53:00.732] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C3.dat
[14:53:00.737] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C4.dat
[14:53:00.743] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C5.dat
[14:53:00.748] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C6.dat
[14:53:00.753] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C7.dat
[14:53:00.759] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C8.dat
[14:53:00.764] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C9.dat
[14:53:00.769] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C10.dat
[14:53:00.774] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C11.dat
[14:53:00.780] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C12.dat
[14:53:00.785] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C13.dat
[14:53:00.791] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C14.dat
[14:53:00.796] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C15.dat
[14:53:00.802] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//defaultMaskFile.dat
[14:53:00.817] INFO: enter test to run
[14:54:34.491] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:54:34.491] INFO: running: highrate
[14:54:34.495] INFO: ----------------------------------------------------------------------
[14:54:34.495] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:54:34.495] INFO: ----------------------------------------------------------------------
[14:54:34.495] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:54:34.495] INFO: edge/corner pixel THR is adjusted
[14:54:34.495] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:54:35.452] INFO: Collecting data for 1 seconds...
[14:54:36.456] INFO: Done with hot pixel readout
[14:54:40.243] INFO: PixTest:: pg_setup set to default.
[14:54:40.244] INFO: 0 hot pixels found in step 0
[14:54:40.250] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:54:40.349] INFO: PixTest::trimHotPixels() done
[14:54:40.350] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C0.dat
[14:54:40.359] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C1.dat
[14:54:40.365] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C2.dat
[14:54:40.370] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C3.dat
[14:54:40.375] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C4.dat
[14:54:40.381] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C5.dat
[14:54:40.386] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C6.dat
[14:54:40.391] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C7.dat
[14:54:40.397] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C8.dat
[14:54:40.402] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C9.dat
[14:54:40.408] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C10.dat
[14:54:40.414] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C11.dat
[14:54:40.419] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C12.dat
[14:54:40.424] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C13.dat
[14:54:40.429] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C14.dat
[14:54:40.435] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//trimParameters35_C15.dat
[14:54:40.440] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-7-37_FPIXTest-17C-FNAL-160822-1256-150V_2016-08-22_12h57m_1471888628/000_FPIXTest_p17//defaultMaskFile.dat
[14:54:40.449] INFO: enter test to run
[14:55:01.803] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:55:01.803] INFO: running: xray
[14:55:01.804] INFO: ----------------------------------------------------------------------
[14:55:01.804] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:55:01.804] INFO: ----------------------------------------------------------------------
[14:55:02.766] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:55:14.745] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:55:44.320] INFO: Resuming triggers.
[14:55:56.294] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[14:56:25.887] INFO: Resuming triggers.
[14:56:37.863] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[14:57:03.866] INFO: Resuming triggers.
[14:57:15.842] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[14:57:42.700] INFO: Resuming triggers.
[14:57:54.675] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[14:58:24.056] INFO: Resuming triggers.
[14:58:36.035] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[14:59:05.495] INFO: Resuming triggers.
[14:59:17.472] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[14:59:46.944] INFO: Resuming triggers.
[14:59:58.924] INFO: run duration 95 seconds, buffer almost full (81%), pausing triggers.
[15:00:28.462] INFO: Resuming triggers.
[15:00:32.973] INFO: data taking finished, elapsed time: 100 seconds.
[15:00:43.728] INFO: PixTest:: pg_setup set to default.
[15:00:43.731] INFO: PixTestXray::doPhRun() done
[15:00:43.927] INFO: enter test to run
[15:01:14.449] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[15:01:14.449] INFO: running: xray
[15:01:14.450] INFO: ----------------------------------------------------------------------
[15:01:14.450] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[15:01:14.450] INFO: ----------------------------------------------------------------------
[15:01:15.414] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[15:01:22.564] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[15:01:52.438] INFO: Resuming triggers.
[15:01:59.585] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[15:02:30.237] INFO: Resuming triggers.
[15:02:37.383] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[15:03:07.909] INFO: Resuming triggers.
[15:03:15.058] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[15:03:45.572] INFO: Resuming triggers.
[15:03:52.724] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[15:04:22.902] INFO: Resuming triggers.
[15:04:30.047] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[15:05:00.528] INFO: Resuming triggers.
[15:05:07.673] INFO: run duration 49 seconds, buffer almost full (81%), pausing triggers.
[15:05:38.614] INFO: Resuming triggers.
[15:05:45.759] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[15:06:17.061] INFO: Resuming triggers.
[15:06:24.209] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[15:06:55.958] INFO: Resuming triggers.
[15:07:03.107] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[15:07:34.235] INFO: Resuming triggers.
[15:07:41.382] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[15:08:12.925] INFO: Resuming triggers.
[15:08:20.068] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[15:08:50.250] INFO: Resuming triggers.
[15:08:57.392] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[15:09:27.586] INFO: Resuming triggers.
[15:09:34.731] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[15:10:05.016] INFO: Resuming triggers.
[15:10:05.315] INFO: data taking finished, elapsed time: 100 seconds.
[15:10:06.958] INFO: PixTest:: pg_setup set to default.
[15:10:06.961] INFO: PixTestXray::doPhRun() done
[15:10:07.111] INFO: enter test to run
[15:10:31.597] INFO: test: HighRate no parameter change
[15:10:31.597] INFO: running: highrate
[15:10:31.644] INFO: ----------------------------------------------------------------------
[15:10:31.644] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[15:10:31.645] INFO: ----------------------------------------------------------------------
[15:10:31.799] INFO: Expecting 768 events.
[15:10:32.932] INFO: 768 events read in total (417ms).
[15:10:32.932] INFO: Test took 1267ms.
[15:10:33.735] INFO: Expecting 41600 events.
[15:10:36.822] INFO: 41600 events read in total (2560ms).
[15:10:36.823] INFO: Test took 3863ms.
[15:10:36.854] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:37.583] INFO: Expecting 41600 events.
[15:10:40.770] INFO: 41600 events read in total (2660ms).
[15:10:40.771] INFO: Test took 3900ms.
[15:10:40.802] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:41.529] INFO: Expecting 41600 events.
[15:10:44.650] INFO: 41600 events read in total (2595ms).
[15:10:44.651] INFO: Test took 3832ms.
[15:10:44.681] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:45.412] INFO: Expecting 41600 events.
[15:10:48.608] INFO: 41600 events read in total (2669ms).
[15:10:48.609] INFO: Test took 3910ms.
[15:10:48.639] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:49.367] INFO: Expecting 41600 events.
[15:10:52.557] INFO: 41600 events read in total (2663ms).
[15:10:52.558] INFO: Test took 3901ms.
[15:10:52.589] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:53.316] INFO: Expecting 41600 events.
[15:10:56.480] INFO: 41600 events read in total (2637ms).
[15:10:56.481] INFO: Test took 3875ms.
[15:10:56.511] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:10:57.241] INFO: Expecting 41600 events.
[15:11:00.416] INFO: 41600 events read in total (2648ms).
[15:11:00.417] INFO: Test took 3889ms.
[15:11:00.447] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:01.177] INFO: Expecting 41600 events.
[15:11:04.256] INFO: 41600 events read in total (2552ms).
[15:11:04.257] INFO: Test took 3793ms.
[15:11:04.289] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:05.021] INFO: Expecting 41600 events.
[15:11:08.110] INFO: 41600 events read in total (2562ms).
[15:11:08.111] INFO: Test took 3805ms.
[15:11:08.143] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:08.873] INFO: Expecting 41600 events.
[15:11:12.010] INFO: 41600 events read in total (2610ms).
[15:11:12.011] INFO: Test took 3851ms.
[15:11:12.041] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:12.772] INFO: Expecting 41600 events.
[15:11:15.777] INFO: 41600 events read in total (2478ms).
[15:11:15.778] INFO: Test took 3719ms.
[15:11:15.811] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:16.540] INFO: Expecting 41600 events.
[15:11:19.370] INFO: 41600 events read in total (2303ms).
[15:11:19.371] INFO: Test took 3543ms.
[15:11:19.406] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:20.129] INFO: Expecting 41600 events.
[15:11:23.312] INFO: 41600 events read in total (2657ms).
[15:11:23.313] INFO: Test took 3888ms.
[15:11:23.344] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:24.072] INFO: Expecting 41600 events.
[15:11:27.258] INFO: 41600 events read in total (2659ms).
[15:11:27.259] INFO: Test took 3898ms.
[15:11:27.294] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:28.011] INFO: Expecting 41600 events.
[15:11:31.113] INFO: 41600 events read in total (2576ms).
[15:11:31.114] INFO: Test took 3802ms.
[15:11:31.145] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:31.869] INFO: Expecting 41600 events.
[15:11:34.956] INFO: 41600 events read in total (2560ms).
[15:11:34.958] INFO: Test took 3795ms.
[15:11:34.991] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:35.717] INFO: Expecting 41600 events.
[15:11:38.852] INFO: 41600 events read in total (2608ms).
[15:11:38.853] INFO: Test took 3844ms.
[15:11:38.884] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:39.611] INFO: Expecting 41600 events.
[15:11:42.811] INFO: 41600 events read in total (2674ms).
[15:11:42.812] INFO: Test took 3912ms.
[15:11:42.843] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:43.569] INFO: Expecting 41600 events.
[15:11:46.761] INFO: 41600 events read in total (2665ms).
[15:11:46.762] INFO: Test took 3902ms.
[15:11:46.792] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:47.522] INFO: Expecting 41600 events.
[15:11:50.330] INFO: 41600 events read in total (2281ms).
[15:11:50.331] INFO: Test took 3522ms.
[15:11:50.364] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:11:50.733] INFO: enter test to run
[15:12:27.892] INFO: test: HighRate no parameter change
[15:12:27.892] INFO: running: highrate
[15:12:27.893] INFO: ----------------------------------------------------------------------
[15:12:27.893] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:12:27.893] INFO: ----------------------------------------------------------------------
[15:12:28.513] INFO: Expecting 208000 events.
[15:12:40.044] INFO: 208000 events read in total (11004ms).
[15:12:40.047] INFO: Test took 12145ms.
[15:12:40.178] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:12:40.425] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:12:40.444] INFO: number of red-efficiency pixels: 80 39 62 121 136 113 137 65 66 90 71 65 97 53 22 30
[15:12:40.444] INFO: number of X-ray hits detected: 60441 37090 59516 91461 100435 100703 99713 66864 62870 84472 86448 71872 76516 45655 18488 22080
[15:12:40.444] 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:12:40.444] INFO: number of Vcal hits detected: 207920 207961 207889 207877 207861 207884 207861 207935 207934 207908 207928 207935 207902 207946 207977 207970
[15:12:40.444] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[15:12:40.444] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[15:12:40.444] INFO: X-ray hit rate [MHz/cm2]: 17.7 10.9 17.4 26.8 29.4 29.5 29.2 19.6 18.4 24.8 25.3 21.1 22.4 13.4 5.4 6.5
[15:12:40.444] INFO: PixTestHighRate::doXPixelAlive() done
[15:12:40.497] INFO: PixTest:: pg_setup set to default.
[15:12:40.508] INFO: enter test to run
[15:12:56.420] INFO: test: HighRate no parameter change
[15:12:56.420] INFO: running: highrate
[15:12:56.421] INFO: ----------------------------------------------------------------------
[15:12:56.421] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:12:56.421] INFO: ----------------------------------------------------------------------
[15:12:57.044] INFO: Expecting 208000 events.
[15:13:09.811] INFO: 208000 events read in total (12240ms).
[15:13:09.816] INFO: Test took 13384ms.
[15:13:10.094] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:13:10.386] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:13:10.386] INFO: number of red-efficiency pixels: 227 85 253 376 456 384 460 200 208 306 313 237 276 145 43 60
[15:13:10.386] INFO: number of X-ray hits detected: 127711 78428 125656 192553 212474 211947 211475 141536 132902 177897 181666 151634 161065 96579 39469 47467
[15:13:10.386] 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:13:10.387] INFO: number of Vcal hits detected: 207754 207915 207677 207601 207511 207584 207498 207789 207785 207679 207670 207754 207711 207849 207956 207940
[15:13:10.387] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0
[15:13:10.387] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.8 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:13:10.387] INFO: X-ray hit rate [MHz/cm2]: 37.4 23.0 36.8 56.4 62.3 62.1 62.0 41.5 39.0 52.1 53.2 44.4 47.2 28.3 11.6 13.9
[15:13:10.387] INFO: PixTestHighRate::doXPixelAlive() done
[15:13:10.435] INFO: PixTest:: pg_setup set to default.
[15:13:10.448] INFO: enter test to run
[15:13:26.724] INFO: test: HighRate no parameter change
[15:13:26.724] INFO: running: highrate
[15:13:26.725] INFO: ----------------------------------------------------------------------
[15:13:26.725] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[15:13:26.725] INFO: ----------------------------------------------------------------------
[15:13:27.335] INFO: Expecting 208000 events.
[15:13:41.856] INFO: 208000 events read in total (13995ms).
[15:13:41.862] INFO: Test took 15130ms.
[15:13:42.272] INFO: Fetched DAQ statistics. Counters are being reset now.
[15:13:42.608] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[15:13:42.608] INFO: number of red-efficiency pixels: 491 213 577 850 1106 898 1014 432 457 692 711 558 600 362 65 101
[15:13:42.609] INFO: number of X-ray hits detected: 193504 120076 191015 293548 322588 321607 320744 215956 202436 271218 277647 231269 244930 147098 60259 72714
[15:13:42.609] 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:13:42.609] INFO: number of Vcal hits detected: 207403 207773 207230 206987 206646 206897 206746 207513 207518 207205 207180 207344 207329 207585 207934 207899
[15:13:42.609] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.6 99.4 99.5 99.5 99.8 99.8 99.7 99.6 99.7 99.7 99.8 100.0 100.0
[15:13:42.609] INFO: Vcal hit overall efficiency (%): 99.7 99.9 99.6 99.5 99.3 99.5 99.4 99.8 99.8 99.6 99.6 99.7 99.7 99.8 100.0 100.0
[15:13:42.609] INFO: X-ray hit rate [MHz/cm2]: 56.7 35.2 56.0 86.0 94.6 94.3 94.0 63.3 59.3 79.5 81.4 67.8 71.8 43.1 17.7 21.3
[15:13:42.609] INFO: PixTestHighRate::doXPixelAlive() done
[15:13:42.658] INFO: PixTest:: pg_setup set to default.
[15:13:42.677] INFO: enter test to run
[15:13:45.804] INFO: test: exit no parameter change
[15:13:46.603] QUIET: Connection to board 33 closed.
[15:13:46.814] INFO: pXar: this is the end, my friend