[16:01:27.930] INFO: *** Welcome to pxar ***
[16:01:27.930] INFO: *** Today: 2016/06/15
[16:01:27.945] INFO: *** Version: v1.9.0-814-g7497
[16:01:27.946] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//dacParameters35_C15.dat
[16:01:27.972] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//tbmParameters_C0b.dat
[16:01:27.972] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//defaultMaskFile.dat
[16:01:27.972] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C15.dat
[16:01:28.061] INFO: clk: 4
[16:01:28.061] INFO: ctr: 4
[16:01:28.061] INFO: sda: 19
[16:01:28.061] INFO: tin: 9
[16:01:28.061] INFO: level: 15
[16:01:28.061] INFO: triggerdelay: 0
[16:01:28.061] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[16:01:28.061] INFO: Log level: INFO
[16:01:28.078] QUIET: Connection to board DTB_WREKRL opened.
[16:01:28.081] 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:
------------------------------------------------------
[16:01:28.084] INFO: RPC call hashes of host and DTB match: 398089610
[16:01:29.615] INFO: DUT info:
[16:01:29.615] INFO: The DUT currently contains the following objects:
[16:01:29.615] INFO: 2 TBM Cores tbm08c (2 ON)
[16:01:29.615] INFO: TBM Core alpha (0): 7 registers set
[16:01:29.615] INFO: TBM Core beta (1): 7 registers set
[16:01:29.615] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:01:29.615] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:29.616] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:29.616] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:29.616] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:29.616] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:29.616] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:29.616] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:29.616] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:29.616] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:29.616] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:29.616] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:29.616] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:29.616] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:29.616] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:29.616] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:29.616] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:01:30.020] INFO: enter 'restricted' command line mode
[16:01:30.020] INFO: enter test to run
[16:01:33.670] INFO: test: PixelAlive no parameter change
[16:01:33.670] INFO: running: pixelalive
[16:01:33.679] INFO: ----------------------------------------------------------------------
[16:01:33.679] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[16:01:33.679] INFO: ----------------------------------------------------------------------
[16:01:34.002] INFO: Expecting 41600 events.
[16:01:38.340] INFO: 41600 events read in total (3619ms).
[16:01:38.505] INFO: Test took 4822ms.
[16:01:38.514] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:01:38.775] INFO: PixTestAlive::aliveTest() done
[16:01:38.775] INFO: number of dead pixels (per ROC): 2 1 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[16:01:38.806] INFO: enter test to run
[16:02:29.709] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[16:02:29.710] INFO: running: highrate
[16:02:29.710] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[16:02:29.928] INFO: ----------------------------------------------------------------------
[16:02:29.928] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[16:02:29.928] INFO: ----------------------------------------------------------------------
[16:02:29.928] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[16:02:29.928] INFO: edge/corner pixel THR is adjusted
[16:02:29.928] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:02:30.889] INFO: Collecting data for 5 seconds...
[16:02:35.905] INFO: Done with hot pixel readout
[16:02:48.787] INFO: PixTest:: pg_setup set to default.
[16:02:48.788] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:02:48.788] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:02:48.815] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:02:48.815] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:02:48.815] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:02:48.815] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:02:48.816] INFO: 168 hot pixels found in step 0
[16:02:49.809] INFO: Collecting data for 5 seconds...
[16:02:54.825] INFO: Done with hot pixel readout
[16:03:07.702] INFO: PixTest:: pg_setup set to default.
[16:03:07.702] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:03:07.703] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:03:07.703] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:03:07.703] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:03:07.703] INFO: 125 hot pixels found in step 1
[16:03:08.700] INFO: Collecting data for 5 seconds...
[16:03:13.717] INFO: Done with hot pixel readout
[16:03:26.599] INFO: PixTest:: pg_setup set to default.
[16:03:26.599] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:03:26.600] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:03:26.600] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:03:26.600] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:03:26.600] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:03:26.600] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:03:26.600] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:03:26.601] INFO: 122 hot pixels found in step 2
[16:03:27.597] INFO: Collecting data for 5 seconds...
[16:03:32.613] INFO: Done with hot pixel readout
[16:03:45.442] INFO: PixTest:: pg_setup set to default.
[16:03:45.442] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:03:45.442] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:03:45.442] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:03:45.443] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:03:45.443] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:03:45.443] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:03:45.443] INFO: 117 hot pixels found in step 3
[16:03:46.441] INFO: Collecting data for 5 seconds...
[16:03:51.457] INFO: Done with hot pixel readout
[16:04:04.364] INFO: PixTest:: pg_setup set to default.
[16:04:04.364] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:04.364] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:04.364] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:04.364] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:04.364] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:04.364] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:04.365] INFO: 106 hot pixels found in step 4
[16:04:05.363] INFO: Collecting data for 5 seconds...
[16:04:10.379] INFO: Done with hot pixel readout
[16:04:23.241] INFO: PixTest:: pg_setup set to default.
[16:04:23.241] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:23.241] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:23.241] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:23.241] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:23.242] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:23.242] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:23.242] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:23.242] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:23.242] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:23.242] INFO: 94 hot pixels found in step 5
[16:04:24.240] INFO: Collecting data for 5 seconds...
[16:04:29.255] INFO: Done with hot pixel readout
[16:04:42.138] INFO: PixTest:: pg_setup set to default.
[16:04:42.138] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:42.138] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:42.138] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:42.138] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:42.138] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:42.138] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:42.138] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:42.138] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:42.138] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:42.139] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:42.139] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:42.139] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:04:42.139] INFO: 99 hot pixels found in step 6
[16:04:43.136] INFO: Collecting data for 5 seconds...
[16:04:48.153] INFO: Done with hot pixel readout
[16:05:00.985] INFO: PixTest:: pg_setup set to default.
[16:05:00.985] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:00.985] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:00.985] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:00.985] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:00.985] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:00.985] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:00.985] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:00.985] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:00.986] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:00.986] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:00.986] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:00.986] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:00.986] INFO: 89 hot pixels found in step 7
[16:05:01.983] INFO: Collecting data for 5 seconds...
[16:05:06.000] INFO: Done with hot pixel readout
[16:05:19.762] INFO: PixTest:: pg_setup set to default.
[16:05:19.762] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:19.762] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:19.762] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:19.762] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:19.762] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:19.762] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:19.762] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:19.762] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:19.762] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:19.762] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:19.762] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:19.763] INFO: 81 hot pixels found in step 8
[16:05:20.760] INFO: Collecting data for 5 seconds...
[16:05:25.776] INFO: Done with hot pixel readout
[16:05:38.651] INFO: PixTest:: pg_setup set to default.
[16:05:38.651] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:38.651] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:38.651] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:38.651] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:38.651] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:38.651] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:38.651] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:38.651] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:38.652] INFO: 84 hot pixels found in step 9
[16:05:39.650] INFO: Collecting data for 5 seconds...
[16:05:44.665] INFO: Done with hot pixel readout
[16:05:57.520] INFO: PixTest:: pg_setup set to default.
[16:05:57.521] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:57.521] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:57.521] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:57.521] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:57.521] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:57.521] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:57.521] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:57.521] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:57.521] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:57.521] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:57.521] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:05:57.522] INFO: 81 hot pixels found in step 10
[16:05:58.519] INFO: Collecting data for 5 seconds...
[16:06:03.534] INFO: Done with hot pixel readout
[16:06:16.409] INFO: PixTest:: pg_setup set to default.
[16:06:16.410] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:16.410] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:16.410] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:16.410] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:16.410] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:16.411] INFO: 76 hot pixels found in step 11
[16:06:17.409] INFO: Collecting data for 5 seconds...
[16:06:22.424] INFO: Done with hot pixel readout
[16:06:35.251] INFO: PixTest:: pg_setup set to default.
[16:06:35.251] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:35.251] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:35.252] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:35.252] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:35.252] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:35.252] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:35.252] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:35.252] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:35.252] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:35.252] INFO: 60 hot pixels found in step 12
[16:06:36.250] INFO: Collecting data for 5 seconds...
[16:06:41.265] INFO: Done with hot pixel readout
[16:06:54.152] INFO: PixTest:: pg_setup set to default.
[16:06:54.153] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:54.153] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:54.153] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:54.153] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:54.153] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:54.153] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:54.153] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:54.153] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:54.153] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:54.153] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:54.153] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:54.153] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:54.153] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:06:54.154] INFO: 73 hot pixels found in step 13
[16:06:55.151] INFO: Collecting data for 5 seconds...
[16:07:00.167] INFO: Done with hot pixel readout
[16:07:13.035] INFO: PixTest:: pg_setup set to default.
[16:07:13.035] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:07:13.035] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:07:13.035] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:07:13.035] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:07:13.035] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:07:13.035] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:07:13.035] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:07:13.035] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:07:13.035] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:07:13.035] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:07:13.035] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:07:13.035] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:07:13.035] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:07:13.035] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:07:13.035] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:07:13.035] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:07:13.036] INFO: 80 hot pixels found in step 14
[16:07:13.078] INFO: 80 hot pixels could not be trimmed and have been masked.
[16:07:13.082] INFO: PixTest::trimHotPixels() done
[16:07:13.082] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C0.dat
[16:07:13.088] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C1.dat
[16:07:13.095] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C2.dat
[16:07:13.100] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C3.dat
[16:07:13.106] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C4.dat
[16:07:13.111] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C5.dat
[16:07:13.116] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C6.dat
[16:07:13.121] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C7.dat
[16:07:13.127] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C8.dat
[16:07:13.133] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C9.dat
[16:07:13.138] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C10.dat
[16:07:13.143] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C11.dat
[16:07:13.148] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C12.dat
[16:07:13.153] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C13.dat
[16:07:13.159] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C14.dat
[16:07:13.164] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C15.dat
[16:07:13.170] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//defaultMaskFile.dat
[16:07:13.180] INFO: enter test to run
[16:07:43.075] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[16:07:43.075] INFO: running: highrate
[16:07:43.080] INFO: ----------------------------------------------------------------------
[16:07:43.080] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[16:07:43.080] INFO: ----------------------------------------------------------------------
[16:07:43.080] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[16:07:43.080] INFO: edge/corner pixel THR is adjusted
[16:07:43.080] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:07:44.038] INFO: Collecting data for 1 seconds...
[16:07:45.041] INFO: Done with hot pixel readout
[16:07:49.634] INFO: PixTest:: pg_setup set to default.
[16:07:49.634] INFO: 0 hot pixels found in step 0
[16:07:49.640] INFO: 0 hot pixels could not be trimmed and have been masked.
[16:07:49.712] INFO: PixTest::trimHotPixels() done
[16:07:49.712] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C0.dat
[16:07:49.721] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C1.dat
[16:07:49.726] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C2.dat
[16:07:49.731] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C3.dat
[16:07:49.736] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C4.dat
[16:07:49.741] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C5.dat
[16:07:49.746] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C6.dat
[16:07:49.751] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C7.dat
[16:07:49.757] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C8.dat
[16:07:49.762] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C9.dat
[16:07:49.767] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C10.dat
[16:07:49.772] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C11.dat
[16:07:49.777] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C12.dat
[16:07:49.782] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C13.dat
[16:07:49.788] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C14.dat
[16:07:49.793] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//trimParameters35_C15.dat
[16:07:49.798] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-K-NN_FPIXTest-17C-FNAL-160608-1153_2016-06-08_11h53m_1465404834/000_FPIXTest_p17//defaultMaskFile.dat
[16:07:49.814] INFO: enter test to run
[16:08:08.907] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[16:08:08.907] INFO: running: xray
[16:08:08.908] INFO: ----------------------------------------------------------------------
[16:08:08.908] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:08:08.908] INFO: ----------------------------------------------------------------------
[16:08:09.872] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:08:20.152] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[16:08:49.800] INFO: Resuming triggers.
[16:09:00.091] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[16:09:29.898] INFO: Resuming triggers.
[16:09:40.181] INFO: run duration 30 seconds, buffer almost full (81%), pausing triggers.
[16:10:09.943] INFO: Resuming triggers.
[16:10:20.231] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[16:10:49.926] INFO: Resuming triggers.
[16:11:00.207] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[16:11:29.877] INFO: Resuming triggers.
[16:11:40.163] INFO: run duration 61 seconds, buffer almost full (81%), pausing triggers.
[16:12:09.857] INFO: Resuming triggers.
[16:12:20.140] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[16:12:49.927] INFO: Resuming triggers.
[16:13:00.210] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[16:13:29.683] INFO: Resuming triggers.
[16:13:39.963] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[16:14:09.654] INFO: Resuming triggers.
[16:14:17.415] INFO: data taking finished, elapsed time: 100 seconds.
[16:14:40.127] INFO: PixTest:: pg_setup set to default.
[16:14:40.130] INFO: PixTestXray::doPhRun() done
[16:14:40.269] INFO: enter test to run
[16:15:13.448] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[16:15:13.448] INFO: running: xray
[16:15:13.449] INFO: ----------------------------------------------------------------------
[16:15:13.449] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:15:13.449] INFO: ----------------------------------------------------------------------
[16:15:14.413] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:15:20.083] INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[16:15:50.220] INFO: Resuming triggers.
[16:15:55.891] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[16:16:26.054] INFO: Resuming triggers.
[16:16:31.720] INFO: run duration 16 seconds, buffer almost full (81%), pausing triggers.
[16:17:01.887] INFO: Resuming triggers.
[16:17:07.559] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[16:17:37.685] INFO: Resuming triggers.
[16:17:43.358] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[16:18:13.513] INFO: Resuming triggers.
[16:18:19.183] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[16:18:49.324] INFO: Resuming triggers.
[16:18:54.999] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[16:19:25.173] INFO: Resuming triggers.
[16:19:30.849] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[16:20:00.983] INFO: Resuming triggers.
[16:20:06.652] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[16:20:36.873] INFO: Resuming triggers.
[16:20:42.545] INFO: run duration 56 seconds, buffer almost full (81%), pausing triggers.
[16:21:12.789] INFO: Resuming triggers.
[16:21:18.460] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[16:21:48.617] INFO: Resuming triggers.
[16:21:54.285] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[16:22:24.461] INFO: Resuming triggers.
[16:22:30.132] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[16:23:00.257] INFO: Resuming triggers.
[16:23:05.924] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[16:23:36.053] INFO: Resuming triggers.
[16:23:41.724] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[16:24:11.882] INFO: Resuming triggers.
[16:24:17.549] INFO: run duration 90 seconds, buffer almost full (81%), pausing triggers.
[16:24:47.681] INFO: Resuming triggers.
[16:24:53.353] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[16:25:23.604] INFO: Resuming triggers.
[16:25:27.515] INFO: data taking finished, elapsed time: 100 seconds.
[16:25:48.535] INFO: PixTest:: pg_setup set to default.
[16:25:48.538] INFO: PixTestXray::doPhRun() done
[16:25:48.687] INFO: enter test to run
[16:29:35.843] INFO: test: HighRate no parameter change
[16:29:35.843] INFO: running: highrate
[16:29:35.844] INFO: ----------------------------------------------------------------------
[16:29:35.844] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:29:35.844] INFO: ----------------------------------------------------------------------
[16:29:35.984] INFO: Expecting 768 events.
[16:29:37.118] INFO: 768 events read in total (419ms).
[16:29:37.118] INFO: Test took 1268ms.
[16:29:37.921] INFO: Expecting 41600 events.
[16:29:41.064] INFO: 41600 events read in total (2616ms).
[16:29:41.065] INFO: Test took 3941ms.
[16:29:41.106] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:41.819] INFO: Expecting 41600 events.
[16:29:45.077] INFO: 41600 events read in total (2731ms).
[16:29:45.078] INFO: Test took 3950ms.
[16:29:45.120] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:45.831] INFO: Expecting 41600 events.
[16:29:49.145] INFO: 41600 events read in total (2788ms).
[16:29:49.146] INFO: Test took 4004ms.
[16:29:49.188] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:49.895] INFO: Expecting 41600 events.
[16:29:53.206] INFO: 41600 events read in total (2785ms).
[16:29:53.207] INFO: Test took 3999ms.
[16:29:53.249] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:53.959] INFO: Expecting 41600 events.
[16:29:57.283] INFO: 41600 events read in total (2797ms).
[16:29:57.284] INFO: Test took 4014ms.
[16:29:57.327] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:29:58.034] INFO: Expecting 41600 events.
[16:30:01.362] INFO: 41600 events read in total (2801ms).
[16:30:01.363] INFO: Test took 4015ms.
[16:30:01.405] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:02.113] INFO: Expecting 41600 events.
[16:30:05.433] INFO: 41600 events read in total (2793ms).
[16:30:05.434] INFO: Test took 4007ms.
[16:30:05.476] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:06.187] INFO: Expecting 41600 events.
[16:30:09.533] INFO: 41600 events read in total (2821ms).
[16:30:09.535] INFO: Test took 4038ms.
[16:30:09.577] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:10.283] INFO: Expecting 41600 events.
[16:30:13.607] INFO: 41600 events read in total (2797ms).
[16:30:13.608] INFO: Test took 4010ms.
[16:30:13.651] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:14.360] INFO: Expecting 41600 events.
[16:30:17.707] INFO: 41600 events read in total (2820ms).
[16:30:17.708] INFO: Test took 4035ms.
[16:30:17.749] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:18.459] INFO: Expecting 41600 events.
[16:30:21.788] INFO: 41600 events read in total (2802ms).
[16:30:21.789] INFO: Test took 4020ms.
[16:30:21.831] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:22.541] INFO: Expecting 41600 events.
[16:30:25.866] INFO: 41600 events read in total (2799ms).
[16:30:25.867] INFO: Test took 4016ms.
[16:30:25.909] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:26.616] INFO: Expecting 41600 events.
[16:30:29.948] INFO: 41600 events read in total (2805ms).
[16:30:29.949] INFO: Test took 4018ms.
[16:30:29.992] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:30.698] INFO: Expecting 41600 events.
[16:30:34.039] INFO: 41600 events read in total (2814ms).
[16:30:34.040] INFO: Test took 4026ms.
[16:30:34.082] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:34.791] INFO: Expecting 41600 events.
[16:30:38.116] INFO: 41600 events read in total (2799ms).
[16:30:38.118] INFO: Test took 4014ms.
[16:30:38.160] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:38.866] INFO: Expecting 41600 events.
[16:30:42.186] INFO: 41600 events read in total (2793ms).
[16:30:42.187] INFO: Test took 4007ms.
[16:30:42.230] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:42.936] INFO: Expecting 41600 events.
[16:30:46.277] INFO: 41600 events read in total (2814ms).
[16:30:46.278] INFO: Test took 4027ms.
[16:30:46.320] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:47.026] INFO: Expecting 41600 events.
[16:30:50.350] INFO: 41600 events read in total (2797ms).
[16:30:50.351] INFO: Test took 4009ms.
[16:30:50.393] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:51.105] INFO: Expecting 41600 events.
[16:30:54.427] INFO: 41600 events read in total (2795ms).
[16:30:54.428] INFO: Test took 4013ms.
[16:30:54.470] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:55.179] INFO: Expecting 41600 events.
[16:30:58.399] INFO: 41600 events read in total (2693ms).
[16:30:58.400] INFO: Test took 3908ms.
[16:30:58.441] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:30:58.789] INFO: enter test to run
[16:31:30.666] INFO: test: HighRate no parameter change
[16:31:30.666] INFO: running: highrate
[16:31:30.667] INFO: ----------------------------------------------------------------------
[16:31:30.667] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:31:30.667] INFO: ----------------------------------------------------------------------
[16:31:31.284] INFO: Expecting 208000 events.
[16:31:43.639] INFO: 208000 events read in total (11828ms).
[16:31:43.643] INFO: Test took 12965ms.
[16:31:43.827] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:31:44.094] INFO: number of dead pixels (per ROC): 2 1 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[16:31:44.094] INFO: number of red-efficiency pixels: 119 88 112 219 221 195 185 126 123 172 155 114 136 87 42 34
[16:31:44.094] INFO: number of X-ray hits detected: 84883 59779 91164 143472 148178 155515 154118 110057 92286 124876 121482 107772 114321 66690 28291 30695
[16:31:44.094] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:31:44.094] INFO: number of Vcal hits detected: 207783 207859 207884 207770 207773 207754 207809 207872 207874 207822 207840 207882 207861 207912 207958 207966
[16:31:44.094] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[16:31:44.094] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[16:31:44.094] INFO: X-ray hit rate [MHz/cm2]: 24.9 17.5 26.7 42.1 43.4 45.6 45.2 32.3 27.0 36.6 35.6 31.6 33.5 19.5 8.3 9.0
[16:31:44.095] INFO: PixTestHighRate::doXPixelAlive() done
[16:31:44.139] INFO: PixTest:: pg_setup set to default.
[16:31:44.153] INFO: enter test to run
[16:32:45.305] INFO: test: HighRate no parameter change
[16:32:45.305] INFO: running: highrate
[16:32:45.306] INFO: ----------------------------------------------------------------------
[16:32:45.306] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:32:45.306] INFO: ----------------------------------------------------------------------
[16:32:45.920] INFO: Expecting 208000 events.
[16:33:00.539] INFO: 208000 events read in total (14092ms).
[16:33:00.547] INFO: Test took 15229ms.
[16:33:00.924] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:33:01.261] INFO: number of dead pixels (per ROC): 2 1 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[16:33:01.261] INFO: number of red-efficiency pixels: 350 185 403 849 765 758 766 471 393 663 439 409 455 298 87 67
[16:33:01.261] INFO: number of X-ray hits detected: 173380 121848 186156 290620 301848 313483 311802 222790 190358 253577 245012 220349 232279 136179 57748 63455
[16:33:01.261] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:33:01.261] INFO: number of Vcal hits detected: 207518 207756 207535 207002 207107 207059 207101 207482 207586 207257 207520 207563 207514 207677 207908 207931
[16:33:01.261] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.6 99.6 99.6 99.6 99.8 99.8 99.7 99.8 99.8 99.8 99.9 100.0 100.0
[16:33:01.261] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.5 99.6 99.5 99.6 99.8 99.8 99.6 99.8 99.8 99.8 99.8 100.0 100.0
[16:33:01.261] INFO: X-ray hit rate [MHz/cm2]: 50.8 35.7 54.6 85.2 88.5 91.9 91.4 65.3 55.8 74.3 71.8 64.6 68.1 39.9 16.9 18.6
[16:33:01.261] INFO: PixTestHighRate::doXPixelAlive() done
[16:33:01.310] INFO: PixTest:: pg_setup set to default.
[16:33:01.325] INFO: enter test to run
[16:33:18.745] INFO: test: HighRate no parameter change
[16:33:18.745] INFO: running: highrate
[16:33:18.746] INFO: ----------------------------------------------------------------------
[16:33:18.746] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:33:18.746] INFO: ----------------------------------------------------------------------
[16:33:19.362] INFO: Expecting 208000 events.
[16:33:36.740] INFO: 208000 events read in total (16851ms).
[16:33:36.749] INFO: Test took 17993ms.
[16:33:37.310] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:33:37.702] INFO: number of dead pixels (per ROC): 2 1 0 0 0 1 0 0 0 0 0 0 0 0 0 0
[16:33:37.702] INFO: number of red-efficiency pixels: 771 397 903 2000 1701 1922 1775 1027 844 1482 1072 949 1057 732 135 108
[16:33:37.702] INFO: number of X-ray hits detected: 265405 186594 283443 444037 460534 479640 477114 341611 290301 387677 375764 335241 354338 208006 88891 97086
[16:33:37.702] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[16:33:37.702] INFO: number of Vcal hits detected: 206876 207481 206597 204858 205378 205006 205299 206626 206946 205926 206647 206812 206666 207027 207858 207890
[16:33:37.702] INFO: Vcal hit fiducial efficiency (%): 99.6 99.8 99.4 98.6 98.9 98.7 98.9 99.4 99.5 99.1 99.4 99.5 99.4 99.6 99.9 99.9
[16:33:37.702] INFO: Vcal hit overall efficiency (%): 99.5 99.8 99.3 98.5 98.7 98.6 98.7 99.3 99.5 99.0 99.3 99.4 99.4 99.5 99.9 99.9
[16:33:37.702] INFO: X-ray hit rate [MHz/cm2]: 77.8 54.7 83.1 130.2 135.0 140.6 139.8 100.1 85.1 113.6 110.1 98.3 103.9 61.0 26.1 28.5
[16:33:37.702] INFO: PixTestHighRate::doXPixelAlive() done
[16:33:37.747] INFO: PixTest:: pg_setup set to default.
[16:33:37.761] INFO: enter test to run
[16:33:54.825] INFO: test: exit no parameter change
[16:33:55.132] QUIET: Connection to board 33 closed.
[16:33:55.134] INFO: pXar: this is the end, my friend