[16:02:59.299] INFO: *** Welcome to pxar ***
[16:02:59.299] INFO: *** Today: 2016/08/25
[16:03:00.364] INFO: *** Version: v1.9.0-818-g96727
[16:03:00.364] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//dacParameters35_C15.dat
[16:03:00.392] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//tbmParameters_C0b.dat
[16:03:00.392] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//defaultMaskFile.dat
[16:03:00.393] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C15.dat
[16:03:00.534] INFO: clk: 4
[16:03:00.534] INFO: ctr: 4
[16:03:00.534] INFO: sda: 19
[16:03:00.534] INFO: tin: 9
[16:03:00.534] INFO: level: 15
[16:03:00.534] INFO: triggerdelay: 0
[16:03:00.550] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[16:03:00.550] INFO: Log level: INFO
[16:03:00.574] QUIET: Connection to board DTB_WREKRL opened.
[16:03:00.578] 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:03:00.580] INFO: RPC call hashes of host and DTB match: 398089610
[16:03:02.115] INFO: DUT info:
[16:03:02.115] INFO: The DUT currently contains the following objects:
[16:03:02.115] INFO: 2 TBM Cores tbm08c (2 ON)
[16:03:02.115] INFO: TBM Core alpha (0): 7 registers set
[16:03:02.115] INFO: TBM Core beta (1): 7 registers set
[16:03:02.115] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[16:03:02.115] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:02.115] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:02.115] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:02.115] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:02.115] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:02.115] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:02.115] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:02.115] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:02.115] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:02.115] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:02.115] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:02.115] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:02.115] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:02.115] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:02.115] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:02.115] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[16:03:02.548] INFO: enter 'restricted' command line mode
[16:03:02.548] INFO: enter test to run
[16:03:21.360] INFO: test: PixelAlive no parameter change
[16:03:21.360] INFO: running: pixelalive
[16:03:21.421] INFO: ----------------------------------------------------------------------
[16:03:21.421] 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:03:21.421] INFO: ----------------------------------------------------------------------
[16:03:21.761] INFO: Expecting 41600 events.
[16:03:26.056] INFO: 41600 events read in total (3576ms).
[16:03:26.223] INFO: Test took 4778ms.
[16:03:26.236] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:03:26.589] INFO: PixTestAlive::aliveTest() done
[16:03:26.589] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:03:26.617] INFO: enter test to run
[16:10:46.173] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[16:10:46.173] INFO: running: highrate
[16:10:46.197] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[16:10:46.460] INFO: ----------------------------------------------------------------------
[16:10:46.460] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[16:10:46.461] INFO: ----------------------------------------------------------------------
[16:10:46.461] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[16:10:46.461] INFO: edge/corner pixel THR is adjusted
[16:10:46.461] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:10:47.445] INFO: Collecting data for 5 seconds...
[16:10:52.462] INFO: Done with hot pixel readout
[16:11:04.536] INFO: PixTest:: pg_setup set to default.
[16:11:04.537] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:11:04.538] INFO: 64 hot pixels found in step 0
[16:11:05.527] INFO: Collecting data for 5 seconds...
[16:11:10.544] INFO: Done with hot pixel readout
[16:11:22.542] INFO: PixTest:: pg_setup set to default.
[16:11:22.542] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:11:22.543] INFO: 77 hot pixels found in step 1
[16:11:23.539] INFO: Collecting data for 5 seconds...
[16:11:28.556] INFO: Done with hot pixel readout
[16:11:40.566] INFO: PixTest:: pg_setup set to default.
[16:11:40.567] INFO: 58 hot pixels found in step 2
[16:11:41.562] INFO: Collecting data for 5 seconds...
[16:11:46.579] INFO: Done with hot pixel readout
[16:11:58.614] INFO: PixTest:: pg_setup set to default.
[16:11:58.614] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:11:58.614] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:11:58.615] INFO: 58 hot pixels found in step 3
[16:11:59.610] INFO: Collecting data for 5 seconds...
[16:12:04.627] INFO: Done with hot pixel readout
[16:12:16.665] INFO: PixTest:: pg_setup set to default.
[16:12:16.665] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:12:16.665] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:12:16.665] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:12:16.665] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:12:16.665] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:12:16.665] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:12:16.666] INFO: 56 hot pixels found in step 4
[16:12:17.662] INFO: Collecting data for 5 seconds...
[16:12:22.678] INFO: Done with hot pixel readout
[16:12:34.758] INFO: PixTest:: pg_setup set to default.
[16:12:34.758] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:12:34.758] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:12:34.758] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:12:34.758] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:12:34.759] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:12:34.759] INFO: 50 hot pixels found in step 5
[16:12:35.753] INFO: Collecting data for 5 seconds...
[16:12:40.770] INFO: Done with hot pixel readout
[16:12:52.783] INFO: PixTest:: pg_setup set to default.
[16:12:52.783] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:12:52.783] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:12:52.784] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:12:52.784] INFO: 52 hot pixels found in step 6
[16:12:53.778] INFO: Collecting data for 5 seconds...
[16:12:58.794] INFO: Done with hot pixel readout
[16:13:10.846] INFO: PixTest:: pg_setup set to default.
[16:13:10.846] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:13:10.846] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:13:10.846] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:13:10.847] INFO: 54 hot pixels found in step 7
[16:13:11.840] INFO: Collecting data for 5 seconds...
[16:13:16.857] INFO: Done with hot pixel readout
[16:13:28.925] INFO: PixTest:: pg_setup set to default.
[16:13:28.925] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:13:28.925] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:13:28.925] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:13:28.926] INFO: 46 hot pixels found in step 8
[16:13:29.921] INFO: Collecting data for 5 seconds...
[16:13:34.938] INFO: Done with hot pixel readout
[16:13:46.935] INFO: PixTest:: pg_setup set to default.
[16:13:46.935] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:13:46.936] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:13:46.936] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:13:46.936] INFO: 40 hot pixels found in step 9
[16:13:47.930] INFO: Collecting data for 5 seconds...
[16:13:52.948] INFO: Done with hot pixel readout
[16:14:04.954] INFO: PixTest:: pg_setup set to default.
[16:14:04.954] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:04.954] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:04.954] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:04.954] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:04.955] INFO: 36 hot pixels found in step 10
[16:14:05.950] INFO: Collecting data for 5 seconds...
[16:14:10.966] INFO: Done with hot pixel readout
[16:14:22.997] INFO: PixTest:: pg_setup set to default.
[16:14:22.997] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:22.997] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:22.997] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:22.997] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:22.997] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:22.997] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:22.997] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:22.997] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:22.997] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:22.998] INFO: 49 hot pixels found in step 11
[16:14:23.992] INFO: Collecting data for 5 seconds...
[16:14:29.009] INFO: Done with hot pixel readout
[16:14:41.033] INFO: PixTest:: pg_setup set to default.
[16:14:41.034] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:41.034] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:41.034] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:41.034] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:41.034] INFO: 32 hot pixels found in step 12
[16:14:42.029] INFO: Collecting data for 5 seconds...
[16:14:47.045] INFO: Done with hot pixel readout
[16:14:59.086] INFO: PixTest:: pg_setup set to default.
[16:14:59.086] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:59.086] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:59.086] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:59.086] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:59.086] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:59.086] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:59.086] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:14:59.087] INFO: 34 hot pixels found in step 13
[16:15:00.081] INFO: Collecting data for 5 seconds...
[16:15:05.098] INFO: Done with hot pixel readout
[16:15:17.168] INFO: PixTest:: pg_setup set to default.
[16:15:17.168] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:15:17.168] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:15:17.168] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:15:17.168] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:15:17.168] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:15:17.169] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:15:17.169] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:15:17.169] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:15:17.169] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:15:17.169] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[16:15:17.169] INFO: 37 hot pixels found in step 14
[16:15:17.207] INFO: 37 hot pixels could not be trimmed and have been masked.
[16:15:17.211] INFO: PixTest::trimHotPixels() done
[16:15:17.211] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C0.dat
[16:15:17.216] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C1.dat
[16:15:17.222] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C2.dat
[16:15:17.228] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C3.dat
[16:15:17.233] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C4.dat
[16:15:17.238] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C5.dat
[16:15:17.243] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C6.dat
[16:15:17.249] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C7.dat
[16:15:17.254] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C8.dat
[16:15:17.259] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C9.dat
[16:15:17.264] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C10.dat
[16:15:17.269] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C11.dat
[16:15:17.275] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C12.dat
[16:15:17.280] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C13.dat
[16:15:17.285] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C14.dat
[16:15:17.290] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C15.dat
[16:15:17.296] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//defaultMaskFile.dat
[16:15:17.306] INFO: enter test to run
[16:16:04.571] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[16:16:04.571] INFO: running: highrate
[16:16:04.576] INFO: ----------------------------------------------------------------------
[16:16:04.576] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[16:16:04.576] INFO: ----------------------------------------------------------------------
[16:16:04.576] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[16:16:04.576] INFO: edge/corner pixel THR is adjusted
[16:16:04.576] INFO: PixTestHighRate::trimHotPixels: step 0...
[16:16:05.533] INFO: Collecting data for 1 seconds...
[16:16:06.538] INFO: Done with hot pixel readout
[16:16:10.713] INFO: PixTest:: pg_setup set to default.
[16:16:10.714] INFO: 0 hot pixels found in step 0
[16:16:10.719] INFO: 0 hot pixels could not be trimmed and have been masked.
[16:16:10.804] INFO: PixTest::trimHotPixels() done
[16:16:10.804] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C0.dat
[16:16:10.815] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C1.dat
[16:16:10.820] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C2.dat
[16:16:10.826] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C3.dat
[16:16:10.831] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C4.dat
[16:16:10.836] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C5.dat
[16:16:10.841] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C6.dat
[16:16:10.847] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C7.dat
[16:16:10.852] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C8.dat
[16:16:10.857] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C9.dat
[16:16:10.863] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C10.dat
[16:16:10.868] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C11.dat
[16:16:10.873] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C12.dat
[16:16:10.878] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C13.dat
[16:16:10.883] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C14.dat
[16:16:10.889] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//trimParameters35_C15.dat
[16:16:10.894] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//defaultMaskFile.dat
[16:16:10.903] INFO: enter test to run
[16:16:35.547] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[16:16:35.547] INFO: running: xray
[16:16:35.548] INFO: ----------------------------------------------------------------------
[16:16:35.548] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:16:35.548] INFO: ----------------------------------------------------------------------
[16:16:36.511] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:16:47.665] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[16:17:17.316] INFO: Resuming triggers.
[16:17:28.472] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[16:17:57.988] INFO: Resuming triggers.
[16:18:09.144] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[16:18:39.450] INFO: Resuming triggers.
[16:18:50.606] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[16:19:20.200] INFO: Resuming triggers.
[16:19:31.356] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[16:20:00.922] INFO: Resuming triggers.
[16:20:12.081] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[16:20:41.645] INFO: Resuming triggers.
[16:20:52.807] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[16:21:21.952] INFO: Resuming triggers.
[16:21:33.111] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[16:22:03.167] INFO: Resuming triggers.
[16:22:14.271] INFO: data taking finished, elapsed time: 100 seconds.
[16:22:44.047] INFO: PixTest:: pg_setup set to default.
[16:22:44.050] INFO: PixTestXray::doPhRun() done
[16:22:44.206] INFO: enter test to run
[16:23:09.904] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[16:23:09.904] INFO: running: xray
[16:23:09.905] INFO: ----------------------------------------------------------------------
[16:23:09.905] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:23:09.906] INFO: ----------------------------------------------------------------------
[16:23:10.894] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:23:17.324] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[16:23:47.591] INFO: Resuming triggers.
[16:23:54.020] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[16:24:24.099] INFO: Resuming triggers.
[16:24:30.529] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[16:25:00.589] INFO: Resuming triggers.
[16:25:07.017] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[16:25:36.999] INFO: Resuming triggers.
[16:25:43.424] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[16:25:45.628] WARNING: Channel 0 ROC 4: Readback start marker after 1 readouts!
[16:25:45.628] ERROR: <datapipe.cc/CheckEventValidity:L491> Channel 0 Number of ROCs (9) != Token Chain Length (8)
[16:25:45.628] ERROR: <datapipe.cc/Read:L164> Dumping the flawed event +- 3 events:
[16:25:45.628] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e3 8040 43e8 55 204f 650 244a 43e8 262 2042 554 264f 43e8 43e8 75a 282f 43e8 d5 264e 43e9 109 204e 43e8 9 2242 422 226a 858 284f 854 244f 85c 2664 43e8 d 282a 343 204f 459 224f 4d9 2a62 e000 c000
[16:25:45.628] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0dd 80c0 43e8 d0 2862 db 2a6e 295 2642 413 206b 43e8 43e8 d5 246b 15c 282f 404 2644 813 2640 43e8 251 2265 43e8 413 284f 43e8 452 244f 43e8 252 2a6a 43e8 2dd 224a 603 2045 658 284a e000 c000
[16:25:45.628] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0de 8000 43e8 51d 2a4a 43e8 c2 204c 153 204f 43e8 813 2842 43e8 48c 2248 43e8 154 2488 64a 2682 43e8 2da 264e 310 2662 44a 2248 43e8 43ea 12 2842 b 204f 250 2a42 e000 c000
[16:25:45.628] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0df 8040 43ea 22 2a48 6d5 284e 43e8 43ea 43e9 99 2664 705 284f 43ea 714 264c 758 286a 43ea 304 284f 48c 2446 4c4 2a6a 6e1 264f 43e8 14 2a6f 684 2a42 718 2046 43e8 e000 c000
[16:25:45.628] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e0 80b1 43e8 4db 2046 43e8 43e8 53 284c 2cc 262e 705 2243 43e9 43ea 455 206a 43e8 43e8 151 204e 520 286e 50c 262c 43e8 43e9 e000 c000
[16:25:45.628] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e1 80c0 43e8 43e8 43e8 45a 2446 43e8 43e9 309 2a4a 4d3 246e 515 2a69 43e9 49a 2866 43e8 6da 2464 43e9 93 2448 8a 2a62 41d 204f 504 2663 812 2a2e e000 c000
[16:25:45.628] ERROR: <datapipe.cc/Read:L166> ====== 0 ====== a0e2 8000 43e8 352 2a42 559 2842 712 264e 815 2042 43e8 215 2a48 28b 204f 29c 2a2a 2c5 244a 43e9 43e8 419 2648 43e8 d 2063 43e8 350 208a 43e8 118 286d 719 246a 43e9 450 2646 509 266b 510 224a 705 264a e000 c000
[16:26:13.495] INFO: Resuming triggers.
[16:26:19.928] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[16:26:50.062] INFO: Resuming triggers.
[16:26:56.494] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[16:27:26.701] INFO: Resuming triggers.
[16:27:33.127] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[16:28:03.063] INFO: Resuming triggers.
[16:28:09.498] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[16:28:39.655] INFO: Resuming triggers.
[16:28:46.083] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[16:29:16.168] INFO: Resuming triggers.
[16:29:22.596] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[16:29:52.620] INFO: Resuming triggers.
[16:29:59.054] INFO: run duration 76 seconds, buffer almost full (81%), pausing triggers.
[16:30:29.109] INFO: Resuming triggers.
[16:30:35.542] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[16:31:05.565] INFO: Resuming triggers.
[16:31:11.997] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[16:31:42.432] INFO: Resuming triggers.
[16:31:48.869] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[16:32:19.333] INFO: Resuming triggers.
[16:32:23.211] INFO: data taking finished, elapsed time: 100 seconds.
[16:32:42.020] INFO: PixTest:: pg_setup set to default.
[16:32:42.023] INFO: PixTestXray::doPhRun() done
[16:32:42.175] INFO: enter test to run
[16:33:29.100] INFO: test: timing no parameter change
[16:33:29.100] INFO: running: timing
[16:33:29.121] INFO: ######################################################################
[16:33:29.127] INFO: PixTestTiming::doTest()
[16:33:29.127] INFO: ######################################################################
[16:33:29.128] INFO: ----------------------------------------------------------------------
[16:33:29.128] INFO: PixTestTiming::TBMPhaseScan()
[16:33:29.128] INFO: ----------------------------------------------------------------------
[16:38:27.803] INFO: TBM Phase Settings: 236
[16:38:27.803] INFO: 400MHz Phase: 3
[16:38:27.803] INFO: 160MHz Phase: 7
[16:38:27.803] INFO: Functional Phase Area: 4
[16:38:27.814] INFO: Test took 298687 ms.
[16:38:27.814] INFO: PixTestTiming::TBMPhaseScan() done.
[16:38:27.814] INFO: ----------------------------------------------------------------------
[16:38:27.814] INFO: PixTestTiming::ROCDelayScan()
[16:38:27.814] INFO: ----------------------------------------------------------------------
[16:41:33.467] INFO: ROC Delay Settings: 228
[16:41:33.467] INFO: ROC Header-Trailer/Token Delay: 11
[16:41:33.467] INFO: ROC Port 0 Delay: 4
[16:41:33.467] INFO: ROC Port 1 Delay: 4
[16:41:33.467] INFO: Functional ROC Area: 5
[16:41:33.471] INFO: Test took 185657 ms.
[16:41:33.471] INFO: PixTestTiming::ROCDelayScan() done.
[16:41:33.471] INFO: ----------------------------------------------------------------------
[16:41:33.471] INFO: PixTestTiming::TimingTest()
[16:41:33.471] INFO: ----------------------------------------------------------------------
[16:41:49.607] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:04.583] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:19.580] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:34.552] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:42:49.530] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:04.496] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:19.472] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:34.576] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:43:49.680] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:04.676] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:05.059] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:44:05.077] INFO: ----------------------------------------------------------------------
[16:44:05.077] INFO: The fraction of properly decoded events is 100.00%: 10000000/10000000
[16:44:05.077] INFO: ----------------------------------------------------------------------
[16:44:05.077] INFO: ----------------------------------------------------------------------
[16:44:05.077] INFO: Read back bit status: 1
[16:44:05.077] INFO: ----------------------------------------------------------------------
[16:44:05.077] INFO: ----------------------------------------------------------------------
[16:44:05.077] INFO: Timings are good!
[16:44:05.077] INFO: ----------------------------------------------------------------------
[16:44:05.077] INFO: Test took 151606 ms.
[16:44:05.077] INFO: PixTestTiming::TimingTest() done.
[16:44:05.092] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//tbmParameters_C0a.dat
[16:44:05.092] INFO: write tbm parameters into /home/uicpirepix2/ProductionTestResults/M-Q-6-33_FPIXTest-17C-FNAL-160819-1322-300V_2016-08-19_13h22m_1471630970/000_FPIXTest_p17//tbmParameters_C0b.dat
[16:44:05.093] INFO: PixTestTiming::doTest took 635976 ms.
[16:44:05.093] INFO: PixTestTiming::doTest() done
[16:44:05.093] INFO: Write out TBMPhaseScan_0_V0
[16:44:05.093] INFO: Write out TBMPhaseScan_1_V0
[16:44:05.093] INFO: Write out CombinedTBMPhaseScan_V0
[16:44:05.118] INFO: Write out ROCDelayScan3_V0
[16:44:05.119] INFO: enter test to run
[16:44:35.743] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[16:44:35.744] INFO: running: xray
[16:44:35.745] INFO: ----------------------------------------------------------------------
[16:44:35.745] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[16:44:35.745] INFO: ----------------------------------------------------------------------
[16:44:36.713] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[16:44:43.159] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[16:45:13.702] INFO: Resuming triggers.
[16:45:20.143] INFO: run duration 12 seconds, buffer almost full (81%), pausing triggers.
[16:45:50.414] INFO: Resuming triggers.
[16:45:56.857] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[16:46:28.402] INFO: Resuming triggers.
[16:46:34.842] INFO: run duration 25 seconds, buffer almost full (81%), pausing triggers.
[16:47:06.074] INFO: Resuming triggers.
[16:47:12.515] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[16:47:43.285] INFO: Resuming triggers.
[16:47:49.726] INFO: run duration 38 seconds, buffer almost full (81%), pausing triggers.
[16:48:19.663] INFO: Resuming triggers.
[16:48:26.103] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[16:48:56.152] INFO: Resuming triggers.
[16:49:02.592] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[16:49:32.836] INFO: Resuming triggers.
[16:49:39.278] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[16:50:09.265] INFO: Resuming triggers.
[16:50:15.708] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[16:50:45.809] INFO: Resuming triggers.
[16:50:52.255] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[16:51:22.370] INFO: Resuming triggers.
[16:51:28.814] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[16:51:58.841] INFO: Resuming triggers.
[16:52:05.286] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[16:52:35.366] INFO: Resuming triggers.
[16:52:41.812] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[16:53:11.932] INFO: Resuming triggers.
[16:53:18.376] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[16:53:48.681] INFO: Resuming triggers.
[16:53:52.384] INFO: data taking finished, elapsed time: 100 seconds.
[16:54:09.923] INFO: PixTest:: pg_setup set to default.
[16:54:09.926] INFO: PixTestXray::doPhRun() done
[16:54:10.077] INFO: enter test to run
[16:54:35.604] INFO: test: HighRate no parameter change
[16:54:35.604] INFO: running: highrate
[16:54:35.627] INFO: ----------------------------------------------------------------------
[16:54:35.627] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[16:54:35.627] INFO: ----------------------------------------------------------------------
[16:54:35.781] INFO: Expecting 768 events.
[16:54:36.916] INFO: 768 events read in total (419ms).
[16:54:36.916] INFO: Test took 1269ms.
[16:54:37.719] INFO: Expecting 41600 events.
[16:54:40.810] INFO: 41600 events read in total (2564ms).
[16:54:40.811] INFO: Test took 3888ms.
[16:54:40.846] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:54:41.572] INFO: Expecting 41600 events.
[16:54:44.784] INFO: 41600 events read in total (2685ms).
[16:54:44.785] INFO: Test took 3921ms.
[16:54:44.822] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:54:45.538] INFO: Expecting 41600 events.
[16:54:48.796] INFO: 41600 events read in total (2731ms).
[16:54:48.797] INFO: Test took 3956ms.
[16:54:48.833] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:54:49.554] INFO: Expecting 41600 events.
[16:54:52.813] INFO: 41600 events read in total (2732ms).
[16:54:52.814] INFO: Test took 3961ms.
[16:54:52.851] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:54:53.570] INFO: Expecting 41600 events.
[16:54:56.824] INFO: 41600 events read in total (2727ms).
[16:54:56.825] INFO: Test took 3956ms.
[16:54:56.861] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:54:57.583] INFO: Expecting 41600 events.
[16:55:00.841] INFO: 41600 events read in total (2731ms).
[16:55:00.842] INFO: Test took 3962ms.
[16:55:00.878] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:55:01.596] INFO: Expecting 41600 events.
[16:55:04.886] INFO: 41600 events read in total (2763ms).
[16:55:04.887] INFO: Test took 3991ms.
[16:55:04.923] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:55:05.643] INFO: Expecting 41600 events.
[16:55:08.917] INFO: 41600 events read in total (2747ms).
[16:55:08.918] INFO: Test took 3978ms.
[16:55:08.954] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:55:09.672] INFO: Expecting 41600 events.
[16:55:12.929] INFO: 41600 events read in total (2730ms).
[16:55:12.930] INFO: Test took 3957ms.
[16:55:12.967] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:55:13.686] INFO: Expecting 41600 events.
[16:55:16.957] INFO: 41600 events read in total (2744ms).
[16:55:16.958] INFO: Test took 3972ms.
[16:55:16.994] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:55:17.711] INFO: Expecting 41600 events.
[16:55:20.981] INFO: 41600 events read in total (2743ms).
[16:55:20.982] INFO: Test took 3969ms.
[16:55:21.018] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:55:21.740] INFO: Expecting 41600 events.
[16:55:25.010] INFO: 41600 events read in total (2743ms).
[16:55:25.011] INFO: Test took 3974ms.
[16:55:25.047] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:55:25.765] INFO: Expecting 41600 events.
[16:55:29.021] INFO: 41600 events read in total (2729ms).
[16:55:29.022] INFO: Test took 3955ms.
[16:55:29.058] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:55:29.781] INFO: Expecting 41600 events.
[16:55:33.052] INFO: 41600 events read in total (2744ms).
[16:55:33.053] INFO: Test took 3976ms.
[16:55:33.089] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:55:33.810] INFO: Expecting 41600 events.
[16:55:37.068] INFO: 41600 events read in total (2731ms).
[16:55:37.069] INFO: Test took 3960ms.
[16:55:37.105] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:55:37.823] INFO: Expecting 41600 events.
[16:55:41.088] INFO: 41600 events read in total (2737ms).
[16:55:41.089] INFO: Test took 3966ms.
[16:55:41.125] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:55:41.843] INFO: Expecting 41600 events.
[16:55:45.122] INFO: 41600 events read in total (2752ms).
[16:55:45.123] INFO: Test took 3979ms.
[16:55:45.163] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:55:45.875] INFO: Expecting 41600 events.
[16:55:49.193] INFO: 41600 events read in total (2792ms).
[16:55:49.194] INFO: Test took 4012ms.
[16:55:49.231] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:55:49.943] INFO: Expecting 41600 events.
[16:55:53.173] INFO: 41600 events read in total (2703ms).
[16:55:53.174] INFO: Test took 3924ms.
[16:55:53.212] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:55:53.926] INFO: Expecting 41600 events.
[16:55:57.070] INFO: 41600 events read in total (2617ms).
[16:55:57.071] INFO: Test took 3841ms.
[16:55:57.107] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:55:57.460] INFO: enter test to run
[16:56:25.595] INFO: test: HighRate no parameter change
[16:56:25.595] INFO: running: highrate
[16:56:25.596] INFO: ----------------------------------------------------------------------
[16:56:25.596] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:56:25.596] INFO: ----------------------------------------------------------------------
[16:56:26.222] INFO: Expecting 208000 events.
[16:56:38.262] INFO: 208000 events read in total (11514ms).
[16:56:38.265] INFO: Test took 12656ms.
[16:56:38.424] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:56:38.682] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:56:38.682] INFO: number of red-efficiency pixels: 81 56 98 155 179 164 155 144 107 130 108 111 107 72 28 30
[16:56:38.682] INFO: number of X-ray hits detected: 72876 46377 73355 121464 125151 130764 128283 90533 78912 106057 103275 85538 89404 54457 24439 27732
[16:56:38.682] 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:56:38.682] INFO: number of Vcal hits detected: 207916 207943 207899 207844 207818 207830 207843 207852 207893 207866 207892 207887 207893 207927 207971 207969
[16:56:38.682] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 100.0 99.9 99.9 100.0 100.0 100.0
[16:56:38.682] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 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:56:38.682] INFO: X-ray hit rate [MHz/cm2]: 21.4 13.6 21.5 35.6 36.7 38.3 37.6 26.5 23.1 31.1 30.3 25.1 26.2 16.0 7.2 8.1
[16:56:38.682] INFO: PixTestHighRate::doXPixelAlive() done
[16:56:38.727] INFO: PixTest:: pg_setup set to default.
[16:56:38.742] INFO: enter test to run
[16:56:57.674] INFO: test: HighRate no parameter change
[16:56:57.674] INFO: running: highrate
[16:56:57.676] INFO: ----------------------------------------------------------------------
[16:56:57.676] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:56:57.676] INFO: ----------------------------------------------------------------------
[16:56:58.297] INFO: Expecting 208000 events.
[16:57:12.273] INFO: 208000 events read in total (13449ms).
[16:57:12.278] INFO: Test took 14592ms.
[16:57:12.594] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:12.905] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:57:12.905] INFO: number of red-efficiency pixels: 306 131 348 626 500 630 536 436 285 356 477 337 376 228 64 68
[16:57:12.905] INFO: number of X-ray hits detected: 147494 94443 149380 245152 252657 264115 258927 183859 159811 215301 210674 173652 182172 110665 49243 56118
[16:57:12.905] 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:57:12.905] INFO: number of Vcal hits detected: 207668 207864 207611 207309 207452 207304 207401 207505 207691 207627 207480 207640 207599 207754 207936 207929
[16:57:12.905] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.8 99.7 99.7 99.8 99.9 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[16:57:12.905] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.7 99.7 99.7 99.7 99.8 99.9 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[16:57:12.905] INFO: X-ray hit rate [MHz/cm2]: 43.2 27.7 43.8 71.9 74.1 77.4 75.9 53.9 46.8 63.1 61.8 50.9 53.4 32.4 14.4 16.4
[16:57:12.905] INFO: PixTestHighRate::doXPixelAlive() done
[16:57:12.952] INFO: PixTest:: pg_setup set to default.
[16:57:12.975] INFO: enter test to run
[16:57:31.234] INFO: test: HighRate no parameter change
[16:57:31.234] INFO: running: highrate
[16:57:31.235] INFO: ----------------------------------------------------------------------
[16:57:31.235] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[16:57:31.235] INFO: ----------------------------------------------------------------------
[16:57:31.860] INFO: Expecting 208000 events.
[16:57:47.646] INFO: 208000 events read in total (15259ms).
[16:57:47.654] INFO: Test took 16408ms.
[16:57:48.162] INFO: Fetched DAQ statistics. Counters are being reset now.
[16:57:48.526] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[16:57:48.527] INFO: number of red-efficiency pixels: 599 220 771 1463 1153 1374 1207 947 617 814 1016 766 749 465 99 94
[16:57:48.527] INFO: number of X-ray hits detected: 221191 141703 223246 368358 380035 397403 389087 276556 239898 323687 316022 259972 273554 166776 74857 84760
[16:57:48.527] 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:57:48.527] INFO: number of Vcal hits detected: 207221 207759 206962 205982 206525 206139 206387 206664 207286 207062 206754 207068 207158 207444 207899 207904
[16:57:48.527] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.6 99.1 99.4 99.2 99.3 99.4 99.7 99.6 99.5 99.6 99.6 99.8 100.0 100.0
[16:57:48.527] INFO: Vcal hit overall efficiency (%): 99.6 99.9 99.5 99.0 99.3 99.1 99.2 99.4 99.7 99.5 99.4 99.6 99.6 99.7 100.0 100.0
[16:57:48.527] INFO: X-ray hit rate [MHz/cm2]: 64.8 41.5 65.4 108.0 111.4 116.5 114.0 81.1 70.3 94.9 92.6 76.2 80.2 48.9 21.9 24.8
[16:57:48.527] INFO: PixTestHighRate::doXPixelAlive() done
[16:57:48.572] INFO: PixTest:: pg_setup set to default.
[16:57:48.585] INFO: enter test to run
[16:58:05.610] INFO: test: exit no parameter change
[16:58:05.006] QUIET: Connection to board 33 closed.
[16:58:06.056] INFO: pXar: this is the end, my friend