[14:14:21.808] INFO: *** Welcome to pxar ***
[14:14:21.808] INFO: *** Today: 2016/06/23
[14:14:21.826] INFO: *** Version: v1.9.0-814-g7497
[14:14:21.826] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//dacParameters35_C15.dat
[14:14:21.858] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//tbmParameters_C0b.dat
[14:14:21.858] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//defaultMaskFile.dat
[14:14:21.865] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C15.dat
[14:14:21.972] INFO: clk: 4
[14:14:21.972] INFO: ctr: 4
[14:14:21.973] INFO: sda: 19
[14:14:21.973] INFO: tin: 9
[14:14:21.973] INFO: level: 15
[14:14:21.973] INFO: triggerdelay: 0
[14:14:21.973] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[14:14:21.973] INFO: Log level: INFO
[14:14:21.992] QUIET: Connection to board DTB_WREK4U opened.
[14:14:21.996] 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:
------------------------------------------------------
[14:14:21.998] INFO: RPC call hashes of host and DTB match: 398089610
[14:14:23.522] INFO: DUT info:
[14:14:23.522] INFO: The DUT currently contains the following objects:
[14:14:23.522] INFO: 2 TBM Cores tbm08c (2 ON)
[14:14:23.522] INFO: TBM Core alpha (0): 7 registers set
[14:14:23.522] INFO: TBM Core beta (1): 7 registers set
[14:14:23.522] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[14:14:23.522] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:23.522] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:23.522] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:23.522] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:23.522] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:23.522] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:23.522] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:23.522] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:23.522] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:23.522] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:23.522] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:23.522] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:23.522] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:23.522] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:23.523] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:23.523] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[14:14:23.926] INFO: enter 'restricted' command line mode
[14:14:23.926] INFO: enter test to run
[14:14:40.575] INFO: test: PixelAlive no parameter change
[14:14:40.575] INFO: running: pixelalive
[14:14:40.601] INFO: ----------------------------------------------------------------------
[14:14:40.601] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[14:14:40.601] INFO: ----------------------------------------------------------------------
[14:14:40.938] INFO: Expecting 41600 events.
[14:14:45.292] INFO: 41600 events read in total (3635ms).
[14:14:45.460] INFO: Test took 4837ms.
[14:14:45.472] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:14:45.735] INFO: PixTestAlive::aliveTest() done
[14:14:45.736] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 3 0 0
[14:14:45.773] INFO: enter test to run
[14:15:04.831] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[14:15:04.831] INFO: running: highrate
[14:15:04.831] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[14:15:05.062] INFO: ----------------------------------------------------------------------
[14:15:05.062] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[14:15:05.062] INFO: ----------------------------------------------------------------------
[14:15:05.062] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[14:15:05.063] INFO: edge/corner pixel THR is adjusted
[14:15:05.063] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:15:06.038] INFO: Collecting data for 5 seconds...
[14:15:11.054] INFO: Done with hot pixel readout
[14:15:22.551] INFO: PixTest:: pg_setup set to default.
[14:15:22.552] INFO: 6 hot pixels found in step 0
[14:15:23.560] INFO: Collecting data for 5 seconds...
[14:15:28.575] INFO: Done with hot pixel readout
[14:15:40.055] INFO: PixTest:: pg_setup set to default.
[14:15:40.056] INFO: 9 hot pixels found in step 1
[14:15:41.049] INFO: Collecting data for 5 seconds...
[14:15:46.064] INFO: Done with hot pixel readout
[14:15:57.666] INFO: PixTest:: pg_setup set to default.
[14:15:57.667] INFO: 7 hot pixels found in step 2
[14:15:58.657] INFO: Collecting data for 5 seconds...
[14:16:03.675] INFO: Done with hot pixel readout
[14:16:15.261] INFO: PixTest:: pg_setup set to default.
[14:16:15.262] INFO: 6 hot pixels found in step 3
[14:16:16.252] INFO: Collecting data for 5 seconds...
[14:16:21.270] INFO: Done with hot pixel readout
[14:16:32.762] INFO: PixTest:: pg_setup set to default.
[14:16:32.763] INFO: 8 hot pixels found in step 4
[14:16:33.754] INFO: Collecting data for 5 seconds...
[14:16:38.771] INFO: Done with hot pixel readout
[14:16:50.309] INFO: PixTest:: pg_setup set to default.
[14:16:50.310] INFO: 6 hot pixels found in step 5
[14:16:51.302] INFO: Collecting data for 5 seconds...
[14:16:56.319] INFO: Done with hot pixel readout
[14:17:07.741] INFO: PixTest:: pg_setup set to default.
[14:17:07.741] INFO: 3 hot pixels found in step 6
[14:17:08.733] INFO: Collecting data for 5 seconds...
[14:17:13.752] INFO: Done with hot pixel readout
[14:17:25.127] INFO: PixTest:: pg_setup set to default.
[14:17:25.128] INFO: 3 hot pixels found in step 7
[14:17:26.118] INFO: Collecting data for 5 seconds...
[14:17:31.136] INFO: Done with hot pixel readout
[14:17:42.442] INFO: PixTest:: pg_setup set to default.
[14:17:42.443] INFO: 0 hot pixels found in step 8
[14:17:42.479] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:17:42.482] INFO: PixTest::trimHotPixels() done
[14:17:42.496] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C0.dat
[14:17:42.509] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C1.dat
[14:17:42.516] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C2.dat
[14:17:42.521] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C3.dat
[14:17:42.526] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C4.dat
[14:17:42.532] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C5.dat
[14:17:42.537] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C6.dat
[14:17:42.542] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C7.dat
[14:17:42.547] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C8.dat
[14:17:42.553] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C9.dat
[14:17:42.558] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C10.dat
[14:17:42.564] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C11.dat
[14:17:42.569] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C12.dat
[14:17:42.574] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C13.dat
[14:17:42.580] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C14.dat
[14:17:42.585] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C15.dat
[14:17:42.591] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//defaultMaskFile.dat
[14:17:42.603] INFO: enter test to run
[14:18:22.565] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[14:18:22.565] INFO: running: highrate
[14:18:22.570] INFO: ----------------------------------------------------------------------
[14:18:22.570] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[14:18:22.570] INFO: ----------------------------------------------------------------------
[14:18:22.570] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[14:18:22.570] INFO: edge/corner pixel THR is adjusted
[14:18:22.570] INFO: PixTestHighRate::trimHotPixels: step 0...
[14:18:23.528] INFO: Collecting data for 1 seconds...
[14:18:24.532] INFO: Done with hot pixel readout
[14:18:28.532] INFO: PixTest:: pg_setup set to default.
[14:18:28.533] INFO: 0 hot pixels found in step 0
[14:18:28.539] INFO: 0 hot pixels could not be trimmed and have been masked.
[14:18:28.626] INFO: PixTest::trimHotPixels() done
[14:18:28.626] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C0.dat
[14:18:28.638] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C1.dat
[14:18:28.648] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C2.dat
[14:18:28.653] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C3.dat
[14:18:28.659] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C4.dat
[14:18:28.664] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C5.dat
[14:18:28.670] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C6.dat
[14:18:28.675] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C7.dat
[14:18:28.681] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C8.dat
[14:18:28.686] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C9.dat
[14:18:28.692] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C10.dat
[14:18:28.697] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C11.dat
[14:18:28.703] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C12.dat
[14:18:28.708] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C13.dat
[14:18:28.713] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C14.dat
[14:18:28.719] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//trimParameters35_C15.dat
[14:18:28.724] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-2-15_FPIXTest-17C-FNAL-160620-1539_2016-06-20_15h39m_1466455149/000_FPIXTest_p17//defaultMaskFile.dat
[14:18:28.734] INFO: enter test to run
[14:19:00.621] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[14:19:00.621] INFO: running: xray
[14:19:00.623] INFO: ----------------------------------------------------------------------
[14:19:00.623] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:19:00.623] INFO: ----------------------------------------------------------------------
[14:19:01.585] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:19:13.221] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[14:19:43.163] INFO: Resuming triggers.
[14:19:54.799] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[14:20:24.117] INFO: Resuming triggers.
[14:20:35.758] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:21:04.935] INFO: Resuming triggers.
[14:21:16.571] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[14:21:45.753] INFO: Resuming triggers.
[14:21:57.390] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[14:22:26.648] INFO: Resuming triggers.
[14:22:38.286] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[14:23:07.520] INFO: Resuming triggers.
[14:23:19.158] INFO: run duration 81 seconds, buffer almost full (81%), pausing triggers.
[14:23:48.431] INFO: Resuming triggers.
[14:24:00.069] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[14:24:29.306] INFO: Resuming triggers.
[14:24:36.536] INFO: data taking finished, elapsed time: 100 seconds.
[14:24:54.966] INFO: PixTest:: pg_setup set to default.
[14:24:54.969] INFO: PixTestXray::doPhRun() done
[14:24:55.105] INFO: enter test to run
[14:26:16.682] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[14:26:16.682] INFO: running: xray
[14:26:16.683] INFO: ----------------------------------------------------------------------
[14:26:16.683] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[14:26:16.683] INFO: ----------------------------------------------------------------------
[14:26:17.647] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[14:26:24.571] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[14:26:54.654] INFO: Resuming triggers.
[14:27:01.582] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[14:27:31.657] INFO: Resuming triggers.
[14:27:38.584] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[14:28:09.539] INFO: Resuming triggers.
[14:28:16.463] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[14:28:47.440] INFO: Resuming triggers.
[14:28:54.364] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[14:29:24.373] INFO: Resuming triggers.
[14:29:31.303] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[14:30:02.611] INFO: Resuming triggers.
[14:30:09.536] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[14:30:40.148] INFO: Resuming triggers.
[14:30:47.075] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[14:31:17.449] INFO: Resuming triggers.
[14:31:24.378] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[14:31:54.399] INFO: Resuming triggers.
[14:32:01.330] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[14:32:31.381] INFO: Resuming triggers.
[14:32:38.315] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[14:33:08.418] INFO: Resuming triggers.
[14:33:15.351] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[14:33:45.374] INFO: Resuming triggers.
[14:33:52.304] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[14:34:22.413] INFO: Resuming triggers.
[14:34:29.342] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[14:34:59.735] INFO: Resuming triggers.
[14:35:03.075] INFO: data taking finished, elapsed time: 100 seconds.
[14:35:17.840] INFO: PixTest:: pg_setup set to default.
[14:35:17.843] INFO: PixTestXray::doPhRun() done
[14:35:17.990] INFO: enter test to run
[14:35:56.934] INFO: test: HighRate no parameter change
[14:35:56.934] INFO: running: highrate
[14:35:56.935] INFO: ----------------------------------------------------------------------
[14:35:56.935] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[14:35:56.935] INFO: ----------------------------------------------------------------------
[14:35:57.075] INFO: Expecting 768 events.
[14:35:58.209] INFO: 768 events read in total (418ms).
[14:35:58.210] INFO: Test took 1269ms.
[14:35:59.013] INFO: Expecting 41600 events.
[14:36:02.058] INFO: 41600 events read in total (2519ms).
[14:36:02.059] INFO: Test took 3842ms.
[14:36:02.090] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:02.819] INFO: Expecting 41600 events.
[14:36:05.970] INFO: 41600 events read in total (2624ms).
[14:36:05.971] INFO: Test took 3864ms.
[14:36:06.003] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:06.731] INFO: Expecting 41600 events.
[14:36:09.944] INFO: 41600 events read in total (2686ms).
[14:36:09.945] INFO: Test took 3925ms.
[14:36:09.977] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:10.702] INFO: Expecting 41600 events.
[14:36:13.929] INFO: 41600 events read in total (2700ms).
[14:36:13.930] INFO: Test took 3936ms.
[14:36:13.962] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:14.687] INFO: Expecting 41600 events.
[14:36:17.914] INFO: 41600 events read in total (2700ms).
[14:36:17.915] INFO: Test took 3936ms.
[14:36:17.948] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:18.673] INFO: Expecting 41600 events.
[14:36:21.906] INFO: 41600 events read in total (2706ms).
[14:36:21.907] INFO: Test took 3941ms.
[14:36:21.941] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:22.666] INFO: Expecting 41600 events.
[14:36:25.907] INFO: 41600 events read in total (2714ms).
[14:36:25.907] INFO: Test took 3949ms.
[14:36:25.940] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:26.667] INFO: Expecting 41600 events.
[14:36:29.893] INFO: 41600 events read in total (2699ms).
[14:36:29.894] INFO: Test took 3935ms.
[14:36:29.926] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:30.655] INFO: Expecting 41600 events.
[14:36:33.889] INFO: 41600 events read in total (2708ms).
[14:36:33.890] INFO: Test took 3947ms.
[14:36:33.922] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:34.652] INFO: Expecting 41600 events.
[14:36:37.894] INFO: 41600 events read in total (2715ms).
[14:36:37.895] INFO: Test took 3955ms.
[14:36:37.928] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:38.652] INFO: Expecting 41600 events.
[14:36:41.879] INFO: 41600 events read in total (2700ms).
[14:36:41.879] INFO: Test took 3932ms.
[14:36:41.912] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:42.640] INFO: Expecting 41600 events.
[14:36:45.874] INFO: 41600 events read in total (2707ms).
[14:36:45.875] INFO: Test took 3944ms.
[14:36:45.908] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:46.633] INFO: Expecting 41600 events.
[14:36:49.871] INFO: 41600 events read in total (2711ms).
[14:36:49.872] INFO: Test took 3947ms.
[14:36:49.905] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:50.630] INFO: Expecting 41600 events.
[14:36:53.843] INFO: 41600 events read in total (2686ms).
[14:36:53.844] INFO: Test took 3921ms.
[14:36:53.877] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:54.604] INFO: Expecting 41600 events.
[14:36:57.850] INFO: 41600 events read in total (2719ms).
[14:36:57.851] INFO: Test took 3956ms.
[14:36:57.885] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:36:58.608] INFO: Expecting 41600 events.
[14:37:01.844] INFO: 41600 events read in total (2709ms).
[14:37:01.845] INFO: Test took 3943ms.
[14:37:01.878] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:02.602] INFO: Expecting 41600 events.
[14:37:05.829] INFO: 41600 events read in total (2700ms).
[14:37:05.830] INFO: Test took 3934ms.
[14:37:05.863] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:06.587] INFO: Expecting 41600 events.
[14:37:09.812] INFO: 41600 events read in total (2698ms).
[14:37:09.813] INFO: Test took 3933ms.
[14:37:09.846] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:10.569] INFO: Expecting 41600 events.
[14:37:13.764] INFO: 41600 events read in total (2668ms).
[14:37:13.765] INFO: Test took 3901ms.
[14:37:13.798] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:14.526] INFO: Expecting 41600 events.
[14:37:17.577] INFO: 41600 events read in total (2524ms).
[14:37:17.578] INFO: Test took 3761ms.
[14:37:17.610] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:17.977] INFO: enter test to run
[14:37:25.118] INFO: test: HighRate no parameter change
[14:37:25.118] INFO: running: highrate
[14:37:25.119] INFO: ----------------------------------------------------------------------
[14:37:25.119] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:37:25.119] INFO: ----------------------------------------------------------------------
[14:37:25.735] INFO: Expecting 208000 events.
[14:37:37.561] INFO: 208000 events read in total (11299ms).
[14:37:37.564] INFO: Test took 12439ms.
[14:37:37.702] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:37:37.953] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 0 0 3 0 0
[14:37:37.953] INFO: number of red-efficiency pixels: 67 54 82 137 129 128 94 86 86 100 97 89 92 50 17 24
[14:37:37.953] INFO: number of X-ray hits detected: 63030 40977 63358 100692 103859 105482 104221 80856 84185 97848 93223 81053 82818 46069 19203 25280
[14:37:37.953] 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:37:37.953] INFO: number of Vcal hits detected: 207932 207946 207914 207862 207869 207867 207856 207913 207913 207899 207903 207910 207903 207803 207983 207975
[14:37:37.953] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0 100.0
[14:37:37.953] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 100.0 100.0 100.0 100.0 99.9 100.0 100.0
[14:37:37.953] INFO: X-ray hit rate [MHz/cm2]: 18.5 12.0 18.6 29.5 30.4 30.9 30.5 23.7 24.7 28.7 27.3 23.8 24.3 13.5 5.6 7.4
[14:37:37.953] INFO: PixTestHighRate::doXPixelAlive() done
[14:37:38.003] INFO: PixTest:: pg_setup set to default.
[14:37:38.018] INFO: enter test to run
[14:38:01.973] INFO: test: HighRate no parameter change
[14:38:01.973] INFO: running: highrate
[14:38:01.974] INFO: ----------------------------------------------------------------------
[14:38:01.974] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:38:01.974] INFO: ----------------------------------------------------------------------
[14:38:02.587] INFO: Expecting 208000 events.
[14:38:16.015] INFO: 208000 events read in total (12901ms).
[14:38:16.020] INFO: Test took 14037ms.
[14:38:16.301] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:38:16.597] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 0 0 3 0 0
[14:38:16.597] INFO: number of red-efficiency pixels: 209 96 236 406 422 423 352 258 268 401 382 263 344 148 50 52
[14:38:16.597] INFO: number of X-ray hits detected: 128635 84601 129678 206349 212565 216569 213083 165885 172571 200769 190069 166532 170185 95371 39794 51717
[14:38:16.597] 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:38:16.597] INFO: number of Vcal hits detected: 207786 207902 207745 207560 207537 207542 207581 207736 207716 207569 207582 207728 207640 207700 207950 207948
[14:38:16.597] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[14:38:16.597] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.8 99.9 99.9 99.8 99.8 99.9 99.8 99.9 100.0 100.0
[14:38:16.597] INFO: X-ray hit rate [MHz/cm2]: 37.7 24.8 38.0 60.5 62.3 63.5 62.5 48.6 50.6 58.8 55.7 48.8 49.9 28.0 11.7 15.2
[14:38:16.597] INFO: PixTestHighRate::doXPixelAlive() done
[14:38:16.649] INFO: PixTest:: pg_setup set to default.
[14:38:16.668] INFO: enter test to run
[14:38:38.229] INFO: test: HighRate no parameter change
[14:38:38.229] INFO: running: highrate
[14:38:38.230] INFO: ----------------------------------------------------------------------
[14:38:38.230] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[14:38:38.230] INFO: ----------------------------------------------------------------------
[14:38:38.849] INFO: Expecting 208000 events.
[14:38:54.072] INFO: 208000 events read in total (14696ms).
[14:38:54.079] INFO: Test took 15838ms.
[14:38:54.495] INFO: Fetched DAQ statistics. Counters are being reset now.
[14:38:54.836] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 1 0 0 0 0 0 0 3 0 0
[14:38:54.836] INFO: number of red-efficiency pixels: 406 246 587 1010 1029 1003 752 565 560 980 864 472 661 320 95 92
[14:38:54.836] INFO: number of X-ray hits detected: 194925 127692 196792 310621 320379 326681 323308 250119 262122 303066 288181 251094 258442 144627 60570 78836
[14:38:54.836] 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:38:54.836] INFO: number of Vcal hits detected: 207525 207730 207261 206765 206768 206755 207058 207351 207350 206841 207007 207473 207246 207490 207899 207906
[14:38:54.836] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.5 99.5 99.6 99.7 99.7 99.5 99.6 99.8 99.7 99.8 100.0 100.0
[14:38:54.836] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.6 99.4 99.4 99.4 99.5 99.7 99.7 99.4 99.5 99.7 99.6 99.8 100.0 100.0
[14:38:54.836] INFO: X-ray hit rate [MHz/cm2]: 57.1 37.4 57.7 91.0 93.9 95.8 94.8 73.3 76.8 88.8 84.5 73.6 75.8 42.4 17.8 23.1
[14:38:54.836] INFO: PixTestHighRate::doXPixelAlive() done
[14:38:54.883] INFO: PixTest:: pg_setup set to default.
[14:38:54.896] INFO: enter test to run
[14:38:58.941] INFO: test: exit no parameter change
[14:38:59.255] QUIET: Connection to board 32 closed.
[14:38:59.257] INFO: pXar: this is the end, my friend