[10:48:07.070] INFO: *** Welcome to pxar ***
[10:48:07.070] INFO: *** Today: 2016/06/24
[10:48:07.089] INFO: *** Version: v1.9.0-814-g7497
[10:48:07.089] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//dacParameters35_C15.dat
[10:48:07.090] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:48:07.090] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//defaultMaskFile.dat
[10:48:07.090] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C15.dat
[10:48:07.165] INFO: clk: 4
[10:48:07.165] INFO: ctr: 4
[10:48:07.165] INFO: sda: 19
[10:48:07.165] INFO: tin: 9
[10:48:07.165] INFO: level: 15
[10:48:07.165] INFO: triggerdelay: 0
[10:48:07.165] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[10:48:07.165] INFO: Log level: INFO
[10:48:07.183] QUIET: Connection to board DTB_WREKRL opened.
[10:48:07.187] 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:
------------------------------------------------------
[10:48:07.190] INFO: RPC call hashes of host and DTB match: 398089610
[10:48:08.713] INFO: DUT info:
[10:48:08.713] INFO: The DUT currently contains the following objects:
[10:48:08.713] INFO: 2 TBM Cores tbm08c (2 ON)
[10:48:08.713] INFO: TBM Core alpha (0): 7 registers set
[10:48:08.713] INFO: TBM Core beta (1): 7 registers set
[10:48:08.713] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:48:08.713] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:08.713] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:08.713] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:08.713] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:08.713] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:08.713] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:08.713] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:08.713] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:08.713] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:08.713] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:08.714] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:08.714] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:08.714] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:08.714] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:08.714] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:08.714] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:48:09.117] INFO: enter 'restricted' command line mode
[10:48:09.117] INFO: enter test to run
[10:48:21.022] INFO: test: PixelAlive no parameter change
[10:48:21.022] INFO: running: pixelalive
[10:48:21.031] INFO: ----------------------------------------------------------------------
[10:48:21.031] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:48:21.031] INFO: ----------------------------------------------------------------------
[10:48:21.351] INFO: Expecting 41600 events.
[10:48:25.683] INFO: 41600 events read in total (3613ms).
[10:48:25.852] INFO: Test took 4819ms.
[10:48:25.863] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:26.156] INFO: PixTestAlive::aliveTest() done
[10:48:26.156] INFO: number of dead pixels (per ROC): 155 52 50 0 0 0 0 0 0 0 0 0 0 0 0 1
[10:48:26.191] INFO: enter test to run
[10:49:33.357] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:49:33.357] INFO: running: highrate
[10:49:33.357] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:49:33.509] INFO: ----------------------------------------------------------------------
[10:49:33.509] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:49:33.509] INFO: ----------------------------------------------------------------------
[10:49:33.509] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:49:33.509] INFO: edge/corner pixel THR is adjusted
[10:49:33.509] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:49:34.469] INFO: Collecting data for 5 seconds...
[10:49:39.485] INFO: Done with hot pixel readout
[10:49:51.088] INFO: PixTest:: pg_setup set to default.
[10:49:51.089] INFO: 116 hot pixels found in step 0
[10:49:52.076] INFO: Collecting data for 5 seconds...
[10:49:57.092] INFO: Done with hot pixel readout
[10:50:08.566] INFO: PixTest:: pg_setup set to default.
[10:50:08.567] INFO: 20 hot pixels found in step 1
[10:50:09.560] INFO: Collecting data for 5 seconds...
[10:50:14.576] INFO: Done with hot pixel readout
[10:50:26.072] INFO: PixTest:: pg_setup set to default.
[10:50:26.073] INFO: 12 hot pixels found in step 2
[10:50:27.063] INFO: Collecting data for 5 seconds...
[10:50:32.079] INFO: Done with hot pixel readout
[10:50:43.510] INFO: PixTest:: pg_setup set to default.
[10:50:43.510] INFO: 7 hot pixels found in step 3
[10:50:44.500] INFO: Collecting data for 5 seconds...
[10:50:49.516] INFO: Done with hot pixel readout
[10:51:00.995] INFO: PixTest:: pg_setup set to default.
[10:51:00.996] INFO: 11 hot pixels found in step 4
[10:51:01.985] INFO: Collecting data for 5 seconds...
[10:51:06.001] INFO: Done with hot pixel readout
[10:51:18.547] INFO: PixTest:: pg_setup set to default.
[10:51:18.548] INFO: 2 hot pixels found in step 5
[10:51:19.538] INFO: Collecting data for 5 seconds...
[10:51:24.554] INFO: Done with hot pixel readout
[10:51:36.048] INFO: PixTest:: pg_setup set to default.
[10:51:36.048] INFO: 4 hot pixels found in step 6
[10:51:37.039] INFO: Collecting data for 5 seconds...
[10:51:42.056] INFO: Done with hot pixel readout
[10:51:53.629] INFO: PixTest:: pg_setup set to default.
[10:51:53.630] INFO: 0 hot pixels found in step 7
[10:51:53.665] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:51:53.668] INFO: PixTest::trimHotPixels() done
[10:51:53.668] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C0.dat
[10:51:53.674] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C1.dat
[10:51:53.680] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C2.dat
[10:51:53.685] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C3.dat
[10:51:53.691] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C4.dat
[10:51:53.696] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C5.dat
[10:51:53.702] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C6.dat
[10:51:53.707] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C7.dat
[10:51:53.712] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C8.dat
[10:51:53.718] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C9.dat
[10:51:53.723] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C10.dat
[10:51:53.728] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C11.dat
[10:51:53.734] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C12.dat
[10:51:53.739] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C13.dat
[10:51:53.744] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C14.dat
[10:51:53.750] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C15.dat
[10:51:53.755] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//defaultMaskFile.dat
[10:51:53.773] INFO: enter test to run
[10:52:57.996] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:52:57.996] INFO: running: highrate
[10:52:57.000] INFO: ----------------------------------------------------------------------
[10:52:57.000] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:52:57.000] INFO: ----------------------------------------------------------------------
[10:52:57.000] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:52:57.000] INFO: edge/corner pixel THR is adjusted
[10:52:57.000] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:52:58.959] INFO: Collecting data for 1 seconds...
[10:52:59.963] INFO: Done with hot pixel readout
[10:53:03.896] INFO: PixTest:: pg_setup set to default.
[10:53:03.897] INFO: 0 hot pixels found in step 0
[10:53:03.902] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:53:03.995] INFO: PixTest::trimHotPixels() done
[10:53:03.996] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C0.dat
[10:53:04.006] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C1.dat
[10:53:04.014] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C2.dat
[10:53:04.019] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C3.dat
[10:53:04.024] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C4.dat
[10:53:04.030] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C5.dat
[10:53:04.035] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C6.dat
[10:53:04.040] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C7.dat
[10:53:04.046] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C8.dat
[10:53:04.051] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C9.dat
[10:53:04.057] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C10.dat
[10:53:04.062] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C11.dat
[10:53:04.067] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C12.dat
[10:53:04.073] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C13.dat
[10:53:04.078] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C14.dat
[10:53:04.084] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//trimParameters35_C15.dat
[10:53:04.089] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-4-03_FPIXTest-17C-FNAL-160623-1355_2016-06-23_13h56m_1466708194/000_FPIXTest_p17//defaultMaskFile.dat
[10:53:04.105] INFO: enter test to run
[10:53:40.708] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:53:40.708] INFO: running: xray
[10:53:40.709] INFO: ----------------------------------------------------------------------
[10:53:40.709] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:53:40.709] INFO: ----------------------------------------------------------------------
[10:53:41.698] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:53:53.255] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:54:22.431] INFO: Resuming triggers.
[10:54:33.992] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[10:55:03.466] INFO: Resuming triggers.
[10:55:15.028] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:55:44.566] INFO: Resuming triggers.
[10:55:56.125] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:56:25.919] INFO: Resuming triggers.
[10:56:37.480] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[10:57:07.004] INFO: Resuming triggers.
[10:57:18.564] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[10:57:49.028] INFO: Resuming triggers.
[10:58:00.589] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[10:58:30.746] INFO: Resuming triggers.
[10:58:42.308] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[10:59:11.844] INFO: Resuming triggers.
[10:59:19.717] INFO: data taking finished, elapsed time: 100 seconds.
[10:59:39.887] INFO: PixTest:: pg_setup set to default.
[10:59:39.890] INFO: PixTestXray::doPhRun() done
[10:59:40.058] INFO: enter test to run
[11:00:03.913] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[11:00:03.913] INFO: running: xray
[11:00:03.914] INFO: ----------------------------------------------------------------------
[11:00:03.914] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[11:00:03.914] INFO: ----------------------------------------------------------------------
[11:00:04.879] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[11:00:11.675] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[11:00:41.744] INFO: Resuming triggers.
[11:00:48.535] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[11:01:18.569] INFO: Resuming triggers.
[11:01:25.356] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[11:01:55.380] INFO: Resuming triggers.
[11:02:02.167] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[11:02:32.351] INFO: Resuming triggers.
[11:02:39.141] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[11:03:09.144] INFO: Resuming triggers.
[11:03:15.932] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[11:03:45.939] INFO: Resuming triggers.
[11:03:52.722] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[11:04:22.863] INFO: Resuming triggers.
[11:04:29.643] INFO: run duration 54 seconds, buffer almost full (81%), pausing triggers.
[11:04:59.767] INFO: Resuming triggers.
[11:05:06.551] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[11:05:36.615] INFO: Resuming triggers.
[11:05:43.395] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[11:06:13.482] INFO: Resuming triggers.
[11:06:20.261] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[11:06:50.381] INFO: Resuming triggers.
[11:06:57.160] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[11:07:27.648] INFO: Resuming triggers.
[11:07:34.428] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[11:08:05.368] INFO: Resuming triggers.
[11:08:12.142] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[11:08:42.826] INFO: Resuming triggers.
[11:08:48.175] INFO: data taking finished, elapsed time: 100 seconds.
[11:09:13.507] INFO: PixTest:: pg_setup set to default.
[11:09:13.510] INFO: PixTestXray::doPhRun() done
[11:09:13.662] INFO: enter test to run
[11:09:48.229] INFO: test: HighRate no parameter change
[11:09:48.229] INFO: running: highrate
[11:09:48.230] INFO: ----------------------------------------------------------------------
[11:09:48.230] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[11:09:48.230] INFO: ----------------------------------------------------------------------
[11:09:48.370] INFO: Expecting 768 events.
[11:09:49.504] INFO: 768 events read in total (419ms).
[11:09:49.504] INFO: Test took 1268ms.
[11:09:50.307] INFO: Expecting 41600 events.
[11:09:53.455] INFO: 41600 events read in total (2621ms).
[11:09:53.456] INFO: Test took 3927ms.
[11:09:53.488] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:54.200] INFO: Expecting 41600 events.
[11:09:57.369] INFO: 41600 events read in total (2642ms).
[11:09:57.370] INFO: Test took 3865ms.
[11:09:57.404] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:09:58.126] INFO: Expecting 41600 events.
[11:10:01.334] INFO: 41600 events read in total (2681ms).
[11:10:01.335] INFO: Test took 3914ms.
[11:10:01.370] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:02.093] INFO: Expecting 41600 events.
[11:10:05.388] INFO: 41600 events read in total (2768ms).
[11:10:05.389] INFO: Test took 4002ms.
[11:10:05.424] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:06.148] INFO: Expecting 41600 events.
[11:10:09.412] INFO: 41600 events read in total (2738ms).
[11:10:09.413] INFO: Test took 3971ms.
[11:10:09.448] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:10.163] INFO: Expecting 41600 events.
[11:10:13.439] INFO: 41600 events read in total (2749ms).
[11:10:13.440] INFO: Test took 3975ms.
[11:10:13.475] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:14.195] INFO: Expecting 41600 events.
[11:10:17.501] INFO: 41600 events read in total (2780ms).
[11:10:17.502] INFO: Test took 4010ms.
[11:10:17.538] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:18.248] INFO: Expecting 41600 events.
[11:10:21.478] INFO: 41600 events read in total (2703ms).
[11:10:21.479] INFO: Test took 3923ms.
[11:10:21.514] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:22.232] INFO: Expecting 41600 events.
[11:10:25.501] INFO: 41600 events read in total (2742ms).
[11:10:25.502] INFO: Test took 3971ms.
[11:10:25.537] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:26.259] INFO: Expecting 41600 events.
[11:10:29.535] INFO: 41600 events read in total (2749ms).
[11:10:29.535] INFO: Test took 3980ms.
[11:10:29.570] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:30.294] INFO: Expecting 41600 events.
[11:10:33.503] INFO: 41600 events read in total (2682ms).
[11:10:33.504] INFO: Test took 3917ms.
[11:10:33.538] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:34.261] INFO: Expecting 41600 events.
[11:10:37.499] INFO: 41600 events read in total (2711ms).
[11:10:37.500] INFO: Test took 3945ms.
[11:10:37.534] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:38.257] INFO: Expecting 41600 events.
[11:10:41.485] INFO: 41600 events read in total (2701ms).
[11:10:41.486] INFO: Test took 3933ms.
[11:10:41.520] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:42.244] INFO: Expecting 41600 events.
[11:10:45.477] INFO: 41600 events read in total (2706ms).
[11:10:45.478] INFO: Test took 3940ms.
[11:10:45.512] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:46.232] INFO: Expecting 41600 events.
[11:10:49.454] INFO: 41600 events read in total (2695ms).
[11:10:49.455] INFO: Test took 3924ms.
[11:10:49.489] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:50.216] INFO: Expecting 41600 events.
[11:10:53.461] INFO: 41600 events read in total (2719ms).
[11:10:53.462] INFO: Test took 3956ms.
[11:10:53.496] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:54.221] INFO: Expecting 41600 events.
[11:10:57.446] INFO: 41600 events read in total (2698ms).
[11:10:57.447] INFO: Test took 3934ms.
[11:10:57.480] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:10:58.207] INFO: Expecting 41600 events.
[11:11:01.440] INFO: 41600 events read in total (2706ms).
[11:11:01.441] INFO: Test took 3943ms.
[11:11:01.475] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:11:02.200] INFO: Expecting 41600 events.
[11:11:05.422] INFO: 41600 events read in total (2695ms).
[11:11:05.423] INFO: Test took 3929ms.
[11:11:05.456] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:11:06.182] INFO: Expecting 41600 events.
[11:11:09.248] INFO: 41600 events read in total (2539ms).
[11:11:09.249] INFO: Test took 3774ms.
[11:11:09.283] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:11:09.648] INFO: enter test to run
[11:11:20.796] INFO: test: HighRate no parameter change
[11:11:20.796] INFO: running: highrate
[11:11:20.798] INFO: ----------------------------------------------------------------------
[11:11:20.798] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:11:20.798] INFO: ----------------------------------------------------------------------
[11:11:21.415] INFO: Expecting 208000 events.
[11:11:32.884] INFO: 208000 events read in total (10942ms).
[11:11:32.888] INFO: Test took 12082ms.
[11:11:33.040] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:11:33.294] INFO: number of dead pixels (per ROC): 100 52 12 0 0 0 0 0 0 0 0 0 0 0 0 1
[11:11:33.294] INFO: number of red-efficiency pixels: 256 99 84 112 131 156 143 94 82 114 118 97 136 62 19 32
[11:11:33.294] INFO: number of X-ray hits detected: 64192 41278 55702 96290 109351 117464 111466 83223 81456 103177 101801 86626 90525 52139 20622 21470
[11:11:33.294] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:11:33.294] INFO: number of Vcal hits detected: 202795 205352 207322 207888 207865 207840 207851 207903 207918 207885 207877 207903 207862 207937 207981 207919
[11:11:33.294] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 99.9 100.0 99.9 100.0 100.0 100.0
[11:11:33.294] INFO: Vcal hit overall efficiency (%): 97.5 98.7 99.7 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 99.9 100.0 100.0 100.0
[11:11:33.294] INFO: X-ray hit rate [MHz/cm2]: 18.8 12.1 16.3 28.2 32.1 34.4 32.7 24.4 23.9 30.2 29.8 25.4 26.5 15.3 6.0 6.3
[11:11:33.294] INFO: PixTestHighRate::doXPixelAlive() done
[11:11:33.338] INFO: PixTest:: pg_setup set to default.
[11:11:33.347] INFO: enter test to run
[11:11:57.668] INFO: test: HighRate no parameter change
[11:11:57.668] INFO: running: highrate
[11:11:57.669] INFO: ----------------------------------------------------------------------
[11:11:57.669] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:11:57.669] INFO: ----------------------------------------------------------------------
[11:11:58.288] INFO: Expecting 208000 events.
[11:12:11.260] INFO: 208000 events read in total (12445ms).
[11:12:11.265] INFO: Test took 13587ms.
[11:12:11.556] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:11.854] INFO: number of dead pixels (per ROC): 99 52 12 0 0 0 0 0 0 0 0 0 0 0 0 1
[11:12:11.854] INFO: number of red-efficiency pixels: 325 177 217 367 370 535 539 354 262 324 458 327 349 171 47 40
[11:12:11.854] INFO: number of X-ray hits detected: 119419 81641 111413 193309 219247 236238 224149 169471 164065 207213 205258 174134 182959 105577 41968 43626
[11:12:11.854] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:12:11.854] INFO: number of Vcal hits detected: 202711 205269 207175 207600 207588 207390 207393 207608 207721 207660 207500 207654 207632 207813 207952 207910
[11:12:11.854] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.7 99.7 99.8 99.9 99.9 99.8 99.8 99.8 99.9 100.0 100.0
[11:12:11.854] INFO: Vcal hit overall efficiency (%): 97.5 98.7 99.6 99.8 99.8 99.7 99.7 99.8 99.9 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[11:12:11.854] INFO: X-ray hit rate [MHz/cm2]: 35.0 23.9 32.7 56.7 64.3 69.2 65.7 49.7 48.1 60.7 60.2 51.0 53.6 30.9 12.3 12.8
[11:12:11.854] INFO: PixTestHighRate::doXPixelAlive() done
[11:12:11.902] INFO: PixTest:: pg_setup set to default.
[11:12:11.919] INFO: enter test to run
[11:12:28.804] INFO: test: HighRate no parameter change
[11:12:28.804] INFO: running: highrate
[11:12:28.805] INFO: ----------------------------------------------------------------------
[11:12:28.805] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[11:12:28.805] INFO: ----------------------------------------------------------------------
[11:12:29.435] INFO: Expecting 208000 events.
[11:12:44.158] INFO: 208000 events read in total (14196ms).
[11:12:44.164] INFO: Test took 15350ms.
[11:12:44.588] INFO: Fetched DAQ statistics. Counters are being reset now.
[11:12:44.928] INFO: number of dead pixels (per ROC): 100 52 12 0 0 0 0 0 0 0 0 0 0 0 0 1
[11:12:44.928] INFO: number of red-efficiency pixels: 503 251 455 857 929 1192 1163 802 578 727 978 721 771 428 83 85
[11:12:44.928] INFO: number of X-ray hits detected: 174308 121701 166864 289384 327960 352538 336257 253081 245889 310288 308081 260555 274025 158619 62787 66181
[11:12:44.928] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[11:12:44.928] INFO: number of Vcal hits detected: 202500 205179 206867 206986 206864 206347 206530 207008 207334 207172 206774 207145 207088 207495 207916 207863
[11:12:44.928] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.6 99.5 99.3 99.4 99.6 99.7 99.6 99.5 99.6 99.6 99.8 100.0 100.0
[11:12:44.928] INFO: Vcal hit overall efficiency (%): 97.4 98.6 99.5 99.5 99.5 99.2 99.3 99.5 99.7 99.6 99.4 99.6 99.6 99.8 100.0 99.9
[11:12:44.928] INFO: X-ray hit rate [MHz/cm2]: 51.1 35.7 48.9 84.8 96.1 103.3 98.6 74.2 72.1 90.9 90.3 76.4 80.3 46.5 18.4 19.4
[11:12:44.928] INFO: PixTestHighRate::doXPixelAlive() done
[11:12:44.981] INFO: PixTest:: pg_setup set to default.
[11:12:44.995] INFO: enter test to run
[11:12:52.300] INFO: test: exit no parameter change
[11:12:52.600] QUIET: Connection to board 33 closed.
[11:12:52.635] INFO: pXar: this is the end, my friend