[13:43:58.849] INFO: *** Welcome to pxar ***
[13:43:58.849] INFO: *** Today: 2016/09/01
[13:43:59.594] INFO: *** Version: v1.9.0-818-g96727
[13:43:59.595] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//dacParameters35_C15.dat
[13:43:59.660] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//tbmParameters_C0b.dat
[13:43:59.660] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//defaultMaskFile.dat
[13:43:59.667] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C15.dat
[13:43:59.859] INFO: clk: 4
[13:43:59.859] INFO: ctr: 4
[13:43:59.859] INFO: sda: 19
[13:43:59.859] INFO: tin: 9
[13:43:59.859] INFO: level: 15
[13:43:59.859] INFO: triggerdelay: 0
[13:43:59.859] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[13:43:59.859] INFO: Log level: INFO
[13:43:59.894] QUIET: Connection to board DTB_WREK4U opened.
[13:43:59.897] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 32
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREK4U
MAC address: 40D855118020
Hostname: pixelDTB032
Comment:
------------------------------------------------------
[13:43:59.900] INFO: RPC call hashes of host and DTB match: 398089610
[13:44:01.436] INFO: DUT info:
[13:44:01.437] INFO: The DUT currently contains the following objects:
[13:44:01.437] INFO: 2 TBM Cores tbm08c (2 ON)
[13:44:01.437] INFO: TBM Core alpha (0): 7 registers set
[13:44:01.437] INFO: TBM Core beta (1): 7 registers set
[13:44:01.437] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[13:44:01.437] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:01.437] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:01.437] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:01.437] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:01.437] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:01.437] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:01.437] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:01.437] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:01.437] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:01.437] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:01.437] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:01.437] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:01.437] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:01.437] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:01.437] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:01.437] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[13:44:01.867] INFO: enter 'restricted' command line mode
[13:44:01.867] INFO: enter test to run
[13:44:12.106] INFO: test: PixelAlive no parameter change
[13:44:12.106] INFO: running: pixelalive
[13:44:12.170] INFO: ----------------------------------------------------------------------
[13:44:12.170] 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:44:12.170] INFO: ----------------------------------------------------------------------
[13:44:12.488] INFO: Expecting 41600 events.
[13:44:16.830] INFO: 41600 events read in total (3624ms).
[13:44:16.000] INFO: Test took 4825ms.
[13:44:17.009] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:44:17.542] INFO: PixTestAlive::aliveTest() done
[13:44:17.543] INFO: number of dead pixels (per ROC): 53 52 8 0 0 0 0 0 0 0 0 0 1 0 0 0
[13:44:17.718] INFO: enter test to run
[13:45:07.162] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[13:45:07.162] INFO: running: highrate
[13:45:07.183] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[13:45:07.428] INFO: ----------------------------------------------------------------------
[13:45:07.428] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[13:45:07.428] INFO: ----------------------------------------------------------------------
[13:45:07.428] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[13:45:07.428] INFO: edge/corner pixel THR is adjusted
[13:45:07.428] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:45:08.387] INFO: Collecting data for 5 seconds...
[13:45:13.404] INFO: Done with hot pixel readout
[13:45:25.086] INFO: PixTest:: pg_setup set to default.
[13:45:25.087] INFO: 0 hot pixels found in step 0
[13:45:25.098] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:45:25.124] INFO: PixTest::trimHotPixels() done
[13:45:25.190] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C0.dat
[13:45:25.202] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C1.dat
[13:45:25.207] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C2.dat
[13:45:25.213] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C3.dat
[13:45:25.219] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C4.dat
[13:45:25.224] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C5.dat
[13:45:25.229] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C6.dat
[13:45:25.234] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C7.dat
[13:45:25.240] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C8.dat
[13:45:25.245] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C9.dat
[13:45:25.251] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C10.dat
[13:45:25.256] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C11.dat
[13:45:25.261] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C12.dat
[13:45:25.267] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C13.dat
[13:45:25.272] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C14.dat
[13:45:25.277] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C15.dat
[13:45:25.283] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//defaultMaskFile.dat
[13:45:25.300] INFO: enter test to run
[13:47:07.073] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:47:07.073] INFO: running: highrate
[13:47:07.077] INFO: ----------------------------------------------------------------------
[13:47:07.077] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:47:07.077] INFO: ----------------------------------------------------------------------
[13:47:07.077] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:47:07.077] INFO: edge/corner pixel THR is adjusted
[13:47:07.078] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:47:08.035] INFO: Collecting data for 1 seconds...
[13:47:09.038] INFO: Done with hot pixel readout
[13:47:13.056] INFO: PixTest:: pg_setup set to default.
[13:47:13.057] INFO: 0 hot pixels found in step 0
[13:47:13.063] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:47:13.156] INFO: PixTest::trimHotPixels() done
[13:47:13.156] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C0.dat
[13:47:13.165] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C1.dat
[13:47:13.171] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C2.dat
[13:47:13.176] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C3.dat
[13:47:13.182] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C4.dat
[13:47:13.187] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C5.dat
[13:47:13.192] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C6.dat
[13:47:13.198] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C7.dat
[13:47:13.203] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C8.dat
[13:47:13.208] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C9.dat
[13:47:13.214] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C10.dat
[13:47:13.219] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C11.dat
[13:47:13.224] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C12.dat
[13:47:13.230] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C13.dat
[13:47:13.235] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C14.dat
[13:47:13.240] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C15.dat
[13:47:13.246] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-6-37_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//defaultMaskFile.dat
[13:47:13.262] INFO: enter test to run
[13:47:33.833] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:47:33.833] INFO: running: xray
[13:47:33.834] INFO: ----------------------------------------------------------------------
[13:47:33.834] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:47:33.834] INFO: ----------------------------------------------------------------------
[13:47:34.808] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:47:46.301] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:48:15.509] INFO: Resuming triggers.
[13:48:26.004] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[13:48:56.960] INFO: Resuming triggers.
[13:49:08.449] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[13:49:38.245] INFO: Resuming triggers.
[13:49:49.738] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[13:50:19.090] INFO: Resuming triggers.
[13:50:30.579] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[13:50:59.878] INFO: Resuming triggers.
[13:51:11.372] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[13:51:41.954] INFO: Resuming triggers.
[13:51:53.443] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[13:52:23.741] INFO: Resuming triggers.
[13:52:35.230] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[13:53:05.622] INFO: Resuming triggers.
[13:53:14.053] INFO: data taking finished, elapsed time: 100 seconds.
[13:53:36.569] INFO: PixTest:: pg_setup set to default.
[13:53:36.573] INFO: PixTestXray::doPhRun() done
[13:53:36.858] INFO: enter test to run
[13:54:41.230] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:54:41.230] INFO: running: xray
[13:54:41.231] INFO: ----------------------------------------------------------------------
[13:54:41.231] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:54:41.231] INFO: ----------------------------------------------------------------------
[13:54:42.204] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:54:48.948] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[13:55:19.062] INFO: Resuming triggers.
[13:55:25.803] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[13:55:55.874] INFO: Resuming triggers.
[13:56:02.619] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[13:56:32.688] INFO: Resuming triggers.
[13:56:39.436] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[13:57:09.599] INFO: Resuming triggers.
[13:57:16.341] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[13:57:46.441] INFO: Resuming triggers.
[13:57:53.187] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[13:58:23.265] INFO: Resuming triggers.
[13:58:30.008] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[13:59:00.133] INFO: Resuming triggers.
[13:59:06.877] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[13:59:37.016] INFO: Resuming triggers.
[13:59:43.762] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[14:00:13.773] INFO: Resuming triggers.
[14:00:20.517] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[14:00:50.919] INFO: Resuming triggers.
[14:00:57.668] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[14:01:28.561] INFO: Resuming triggers.
[14:01:35.305] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[14:02:06.027] INFO: Resuming triggers.
[14:02:12.775] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[14:02:43.596] INFO: Resuming triggers.
[14:02:50.341] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[14:03:20.850] INFO: Resuming triggers.
[14:03:26.757] INFO: data taking finished, elapsed time: 100 seconds.
[14:03:53.954] INFO: PixTest:: pg_setup set to default.
[14:03:53.957] INFO: PixTestXray::doPhRun() done
[14:03:54.109] INFO: enter test to run
[14:04:46.170] INFO: test: HighRate no parameter change
[14:04:46.170] INFO: running: highrate
[14:04:46.182] INFO: ----------------------------------------------------------------------
[14:04:46.182] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:04:46.182] INFO: ----------------------------------------------------------------------
[14:04:46.337] INFO: Expecting 768 events.
[14:04:47.471] INFO: 768 events read in total (419ms).
[14:04:47.472] INFO: Test took 1270ms.
[14:04:48.274] INFO: Expecting 41600 events.
[14:04:51.455] INFO: 41600 events read in total (2654ms).
[14:04:51.456] INFO: Test took 3960ms.
[14:04:51.488] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:52.219] INFO: Expecting 41600 events.
[14:04:55.512] INFO: 41600 events read in total (2766ms).
[14:04:55.512] INFO: Test took 4007ms.
[14:04:55.546] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:04:56.273] INFO: Expecting 41600 events.
[14:04:59.590] INFO: 41600 events read in total (2790ms).
[14:04:59.591] INFO: Test took 4029ms.
[14:04:59.625] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:00.335] INFO: Expecting 41600 events.
[14:05:03.609] INFO: 41600 events read in total (2747ms).
[14:05:03.610] INFO: Test took 3968ms.
[14:05:03.642] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:04.362] INFO: Expecting 41600 events.
[14:05:07.635] INFO: 41600 events read in total (2746ms).
[14:05:07.636] INFO: Test took 3977ms.
[14:05:07.669] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:08.396] INFO: Expecting 41600 events.
[14:05:11.662] INFO: 41600 events read in total (2739ms).
[14:05:11.662] INFO: Test took 3976ms.
[14:05:11.695] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:12.420] INFO: Expecting 41600 events.
[14:05:15.654] INFO: 41600 events read in total (2708ms).
[14:05:15.655] INFO: Test took 3943ms.
[14:05:15.692] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:16.409] INFO: Expecting 41600 events.
[14:05:19.693] INFO: 41600 events read in total (2757ms).
[14:05:19.694] INFO: Test took 3978ms.
[14:05:19.726] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:20.454] INFO: Expecting 41600 events.
[14:05:23.751] INFO: 41600 events read in total (2770ms).
[14:05:23.752] INFO: Test took 4009ms.
[14:05:23.786] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:24.503] INFO: Expecting 41600 events.
[14:05:27.749] INFO: 41600 events read in total (2719ms).
[14:05:27.750] INFO: Test took 3946ms.
[14:05:27.783] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:28.503] INFO: Expecting 41600 events.
[14:05:31.787] INFO: 41600 events read in total (2757ms).
[14:05:31.788] INFO: Test took 3986ms.
[14:05:31.821] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:32.544] INFO: Expecting 41600 events.
[14:05:35.793] INFO: 41600 events read in total (2723ms).
[14:05:35.794] INFO: Test took 3956ms.
[14:05:35.827] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:36.550] INFO: Expecting 41600 events.
[14:05:39.857] INFO: 41600 events read in total (2780ms).
[14:05:39.858] INFO: Test took 4014ms.
[14:05:39.896] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:40.609] INFO: Expecting 41600 events.
[14:05:43.834] INFO: 41600 events read in total (2698ms).
[14:05:43.835] INFO: Test took 3920ms.
[14:05:43.867] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:44.593] INFO: Expecting 41600 events.
[14:05:47.882] INFO: 41600 events read in total (2762ms).
[14:05:47.883] INFO: Test took 3999ms.
[14:05:47.916] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:48.642] INFO: Expecting 41600 events.
[14:05:51.918] INFO: 41600 events read in total (2749ms).
[14:05:51.919] INFO: Test took 3987ms.
[14:05:51.954] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:52.675] INFO: Expecting 41600 events.
[14:05:55.932] INFO: 41600 events read in total (2730ms).
[14:05:55.933] INFO: Test took 3962ms.
[14:05:55.967] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:05:56.686] INFO: Expecting 41600 events.
[14:05:59.926] INFO: 41600 events read in total (2713ms).
[14:05:59.927] INFO: Test took 3941ms.
[14:05:59.959] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:00.684] INFO: Expecting 41600 events.
[14:06:03.909] INFO: 41600 events read in total (2699ms).
[14:06:03.910] INFO: Test took 3934ms.
[14:06:03.943] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:04.667] INFO: Expecting 41600 events.
[14:06:07.782] INFO: 41600 events read in total (2588ms).
[14:06:07.783] INFO: Test took 3823ms.
[14:06:07.816] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:08.176] INFO: enter test to run
[14:06:13.425] INFO: test: HighRate no parameter change
[14:06:13.426] INFO: running: highrate
[14:06:13.427] INFO: ----------------------------------------------------------------------
[14:06:13.427] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:06:13.427] INFO: ----------------------------------------------------------------------
[14:06:14.038] INFO: Expecting 208000 events.
[14:06:25.990] INFO: 208000 events read in total (11425ms).
[14:06:25.993] INFO: Test took 12559ms.
[14:06:26.131] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:26.380] INFO: number of dead pixels (per ROC): 53 52 8 0 0 0 0 0 0 0 0 0 1 0 0 0
[14:06:26.381] INFO: number of red-efficiency pixels: 131 102 92 135 113 118 144 111 77 131 135 105 87 67 24 26
[14:06:26.381] INFO: number of X-ray hits detected: 60191 35648 62427 99681 105475 108871 106553 74566 69822 95477 93750 80913 79665 47845 18404 21655
[14:06:26.381] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:06:26.381] INFO: number of Vcal hits detected: 205270 205347 207514 207861 207884 207880 207854 207887 207923 207866 207863 207893 207864 207930 207976 207973
[14:06:26.381] 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 100.0 100.0 100.0 100.0
[14:06:26.381] INFO: Vcal hit overall efficiency (%): 98.7 98.7 99.8 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
[14:06:26.381] INFO: X-ray hit rate [MHz/cm2]: 17.6 10.4 18.3 29.2 30.9 31.9 31.2 21.9 20.5 28.0 27.5 23.7 23.4 14.0 5.4 6.3
[14:06:26.381] INFO: PixTestHighRate::doXPixelAlive() done
[14:06:26.428] INFO: PixTest:: pg_setup set to default.
[14:06:26.444] INFO: enter test to run
[14:06:42.017] INFO: test: HighRate no parameter change
[14:06:42.017] INFO: running: highrate
[14:06:42.018] INFO: ----------------------------------------------------------------------
[14:06:42.018] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:06:42.018] INFO: ----------------------------------------------------------------------
[14:06:42.644] INFO: Expecting 208000 events.
[14:06:56.274] INFO: 208000 events read in total (13103ms).
[14:06:56.279] INFO: Test took 14252ms.
[14:06:56.568] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:06:56.865] INFO: number of dead pixels (per ROC): 53 52 8 0 0 0 0 0 0 0 0 0 1 0 0 0
[14:06:56.865] INFO: number of red-efficiency pixels: 272 179 242 378 386 499 473 324 269 430 428 294 297 190 48 61
[14:06:56.865] INFO: number of X-ray hits detected: 126926 75593 131326 211493 223013 231113 227338 158754 147825 203142 199265 172516 170093 103273 39434 46738
[14:06:56.865] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:06:56.865] INFO: number of Vcal hits detected: 205126 205267 207346 207587 207578 207462 207485 207646 207712 207536 207534 207686 207639 207799 207951 207937
[14:06:56.865] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.8 99.8 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[14:06:56.865] INFO: Vcal hit overall efficiency (%): 98.6 98.7 99.7 99.8 99.8 99.7 99.8 99.8 99.9 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[14:06:56.865] INFO: X-ray hit rate [MHz/cm2]: 37.2 22.2 38.5 62.0 65.4 67.7 66.6 46.5 43.3 59.5 58.4 50.6 49.9 30.3 11.6 13.7
[14:06:56.865] INFO: PixTestHighRate::doXPixelAlive() done
[14:06:56.911] INFO: PixTest:: pg_setup set to default.
[14:06:56.922] INFO: enter test to run
[14:07:16.489] INFO: test: HighRate no parameter change
[14:07:16.489] INFO: running: highrate
[14:07:16.490] INFO: ----------------------------------------------------------------------
[14:07:16.490] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:07:16.490] INFO: ----------------------------------------------------------------------
[14:07:17.110] INFO: Expecting 208000 events.
[14:07:32.479] INFO: 208000 events read in total (14843ms).
[14:07:32.487] INFO: Test took 15988ms.
[14:07:32.917] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:07:33.262] INFO: number of dead pixels (per ROC): 53 52 8 0 0 0 0 0 0 0 0 0 1 0 0 0
[14:07:33.262] INFO: number of red-efficiency pixels: 549 271 562 891 950 1156 1152 764 518 1064 1042 664 663 447 81 86
[14:07:33.262] INFO: number of X-ray hits detected: 194452 116387 203577 324517 343394 355258 349201 244791 228595 312034 306280 264122 262198 159427 60636 72351
[14:07:33.262] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[14:07:33.262] INFO: number of Vcal hits detected: 204791 205152 206906 206909 206886 206508 206539 206997 207395 206675 206722 207223 207180 207475 207918 207913
[14:07:33.262] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.7 99.5 99.5 99.4 99.4 99.6 99.7 99.4 99.4 99.7 99.7 99.8 100.0 100.0
[14:07:33.262] INFO: Vcal hit overall efficiency (%): 98.5 98.6 99.5 99.5 99.5 99.3 99.3 99.5 99.7 99.4 99.4 99.6 99.6 99.7 100.0 100.0
[14:07:33.262] INFO: X-ray hit rate [MHz/cm2]: 57.0 34.1 59.7 95.1 100.7 104.1 102.4 71.8 67.0 91.5 89.8 77.4 76.9 46.7 17.8 21.2
[14:07:33.262] INFO: PixTestHighRate::doXPixelAlive() done
[14:07:33.314] INFO: PixTest:: pg_setup set to default.
[14:07:33.330] INFO: enter test to run
[14:07:37.464] INFO: test: exit no parameter change
[14:07:37.817] QUIET: Connection to board 32 closed.
[14:07:37.853] INFO: pXar: this is the end, my friend