[09:55:07.989] INFO: *** Welcome to pxar ***
[09:55:07.989] INFO: *** Today: 2016/09/08
[09:55:08.010] INFO: *** Version: v1.9.0-818-g96727
[09:55:08.010] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//dacParameters35_C15.dat
[09:55:08.036] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//tbmParameters_C0b.dat
[09:55:08.036] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//defaultMaskFile.dat
[09:55:08.044] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C15.dat
[09:55:08.172] INFO: clk: 4
[09:55:08.172] INFO: ctr: 4
[09:55:08.172] INFO: sda: 19
[09:55:08.172] INFO: tin: 9
[09:55:08.172] INFO: level: 15
[09:55:08.172] INFO: triggerdelay: 0
[09:55:08.172] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[09:55:08.173] INFO: Log level: INFO
[09:55:08.191] QUIET: Connection to board DTB_WREKRL opened.
[09:55:08.195] 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:
------------------------------------------------------
[09:55:08.198] INFO: RPC call hashes of host and DTB match: 398089610
[09:55:09.726] INFO: DUT info:
[09:55:09.726] INFO: The DUT currently contains the following objects:
[09:55:09.726] INFO: 2 TBM Cores tbm08c (2 ON)
[09:55:09.726] INFO: TBM Core alpha (0): 7 registers set
[09:55:09.726] INFO: TBM Core beta (1): 7 registers set
[09:55:09.726] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:55:09.726] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:09.726] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:09.726] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:09.726] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:09.726] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:09.726] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:09.726] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:09.726] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:09.726] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:09.726] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:09.727] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:09.727] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:09.727] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:09.727] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:09.727] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:09.727] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:55:10.130] INFO: enter 'restricted' command line mode
[09:55:10.130] INFO: enter test to run
[09:55:15.489] INFO: test: PixelAlive no parameter change
[09:55:15.489] INFO: running: pixelalive
[09:55:15.498] INFO: ----------------------------------------------------------------------
[09:55:15.498] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:55:15.498] INFO: ----------------------------------------------------------------------
[09:55:15.812] INFO: Expecting 41600 events.
[09:55:20.114] INFO: 41600 events read in total (3583ms).
[09:55:20.283] INFO: Test took 4782ms.
[09:55:20.293] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:20.556] INFO: PixTestAlive::aliveTest() done
[09:55:20.556] INFO: number of dead pixels (per ROC): 180 100 106 106 106 106 107 207 262 106 105 106 106 99 106 185
[09:55:20.589] INFO: enter test to run
[09:55:45.160] INFO: test: PixelAlive no parameter change
[09:55:45.160] INFO: running: pixelalive
[09:55:45.164] INFO: ----------------------------------------------------------------------
[09:55:45.164] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:55:45.164] INFO: ----------------------------------------------------------------------
[09:55:45.484] INFO: Expecting 41600 events.
[09:55:49.815] INFO: 41600 events read in total (3615ms).
[09:55:49.815] INFO: Test took 4649ms.
[09:55:49.822] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:55:50.217] INFO: PixTestAlive::aliveTest() done
[09:55:50.217] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 2 1 0 0 0 0 0 0 0 0
[09:55:50.220] INFO: enter test to run
[09:56:10.120] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[09:56:10.120] INFO: running: highrate
[09:56:10.120] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[09:56:10.492] INFO: ----------------------------------------------------------------------
[09:56:10.492] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[09:56:10.492] INFO: ----------------------------------------------------------------------
[09:56:10.492] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[09:56:10.492] INFO: edge/corner pixel THR is adjusted
[09:56:10.492] INFO: PixTestHighRate::trimHotPixels: step 0...
[09:56:11.452] INFO: Collecting data for 5 seconds...
[09:56:16.467] INFO: Done with hot pixel readout
[09:56:28.267] INFO: PixTest:: pg_setup set to default.
[09:56:28.268] INFO: 29 hot pixels found in step 0
[09:56:29.279] INFO: Collecting data for 5 seconds...
[09:56:34.296] INFO: Done with hot pixel readout
[09:56:46.125] INFO: PixTest:: pg_setup set to default.
[09:56:46.125] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[09:56:46.126] INFO: 32 hot pixels found in step 1
[09:56:47.115] INFO: Collecting data for 5 seconds...
[09:56:52.131] INFO: Done with hot pixel readout
[09:57:03.929] INFO: PixTest:: pg_setup set to default.
[09:57:03.929] INFO: 34 hot pixels found in step 2
[09:57:04.919] INFO: Collecting data for 5 seconds...
[09:57:09.935] INFO: Done with hot pixel readout
[09:57:21.673] INFO: PixTest:: pg_setup set to default.
[09:57:21.674] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[09:57:21.674] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[09:57:21.674] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[09:57:21.674] INFO: 35 hot pixels found in step 3
[09:57:22.664] INFO: Collecting data for 5 seconds...
[09:57:27.681] INFO: Done with hot pixel readout
[09:57:39.458] INFO: PixTest:: pg_setup set to default.
[09:57:39.459] INFO: 29 hot pixels found in step 4
[09:57:40.449] INFO: Collecting data for 5 seconds...
[09:57:45.465] INFO: Done with hot pixel readout
[09:57:57.184] INFO: PixTest:: pg_setup set to default.
[09:57:57.184] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[09:57:57.184] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[09:57:57.185] INFO: 21 hot pixels found in step 5
[09:57:58.174] INFO: Collecting data for 5 seconds...
[09:58:03.190] INFO: Done with hot pixel readout
[09:58:14.978] INFO: PixTest:: pg_setup set to default.
[09:58:14.979] INFO: 20 hot pixels found in step 6
[09:58:15.968] INFO: Collecting data for 5 seconds...
[09:58:20.985] INFO: Done with hot pixel readout
[09:58:32.764] INFO: PixTest:: pg_setup set to default.
[09:58:32.765] INFO: 15 hot pixels found in step 7
[09:58:33.753] INFO: Collecting data for 5 seconds...
[09:58:38.770] INFO: Done with hot pixel readout
[09:58:50.561] INFO: PixTest:: pg_setup set to default.
[09:58:50.561] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[09:58:50.561] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[09:58:50.562] INFO: 23 hot pixels found in step 8
[09:58:51.550] INFO: Collecting data for 5 seconds...
[09:58:56.566] INFO: Done with hot pixel readout
[09:59:08.344] INFO: PixTest:: pg_setup set to default.
[09:59:08.344] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[09:59:08.345] INFO: 16 hot pixels found in step 9
[09:59:09.334] INFO: Collecting data for 5 seconds...
[09:59:14.349] INFO: Done with hot pixel readout
[09:59:26.278] INFO: PixTest:: pg_setup set to default.
[09:59:26.279] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[09:59:26.279] INFO: 15 hot pixels found in step 10
[09:59:27.268] INFO: Collecting data for 5 seconds...
[09:59:32.287] INFO: Done with hot pixel readout
[09:59:43.843] INFO: PixTest:: pg_setup set to default.
[09:59:43.843] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[09:59:43.844] INFO: 17 hot pixels found in step 11
[09:59:44.833] INFO: Collecting data for 5 seconds...
[09:59:49.851] INFO: Done with hot pixel readout
[10:00:01.243] INFO: PixTest:: pg_setup set to default.
[10:00:01.243] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:00:01.244] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:00:01.244] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:00:01.244] INFO: 14 hot pixels found in step 12
[10:00:02.233] INFO: Collecting data for 5 seconds...
[10:00:07.252] INFO: Done with hot pixel readout
[10:00:18.622] INFO: PixTest:: pg_setup set to default.
[10:00:18.622] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:00:18.622] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:00:18.622] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:00:18.623] INFO: 15 hot pixels found in step 13
[10:00:19.612] INFO: Collecting data for 5 seconds...
[10:00:24.631] INFO: Done with hot pixel readout
[10:00:36.454] INFO: PixTest:: pg_setup set to default.
[10:00:36.455] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:00:36.455] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:00:36.455] INFO: 15 hot pixels found in step 14
[10:00:36.491] INFO: 15 hot pixels could not be trimmed and have been masked.
[10:00:36.495] INFO: PixTest::trimHotPixels() done
[10:00:36.516] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C0.dat
[10:00:36.528] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C1.dat
[10:00:36.535] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C2.dat
[10:00:36.541] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C3.dat
[10:00:36.547] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C4.dat
[10:00:36.552] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C5.dat
[10:00:36.558] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C6.dat
[10:00:36.563] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C7.dat
[10:00:36.569] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C8.dat
[10:00:36.575] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C9.dat
[10:00:36.581] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C10.dat
[10:00:36.586] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C11.dat
[10:00:36.592] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C12.dat
[10:00:36.597] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C13.dat
[10:00:36.603] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C14.dat
[10:00:36.609] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C15.dat
[10:00:36.614] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//defaultMaskFile.dat
[10:00:36.625] INFO: enter test to run
[10:01:01.854] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:01:01.854] INFO: running: highrate
[10:01:01.859] INFO: ----------------------------------------------------------------------
[10:01:01.859] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:01:01.859] INFO: ----------------------------------------------------------------------
[10:01:01.859] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:01:01.859] INFO: edge/corner pixel THR is adjusted
[10:01:01.859] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:01:02.816] INFO: Collecting data for 1 seconds...
[10:01:03.821] INFO: Done with hot pixel readout
[10:01:07.854] INFO: PixTest:: pg_setup set to default.
[10:01:07.855] INFO: 1 hot pixels found in step 0
[10:01:08.895] INFO: Collecting data for 1 seconds...
[10:01:09.899] INFO: Done with hot pixel readout
[10:01:13.959] INFO: PixTest:: pg_setup set to default.
[10:01:13.960] INFO: 1 hot pixels found in step 1
[10:01:14.004] INFO: Collecting data for 1 seconds...
[10:01:16.007] INFO: Done with hot pixel readout
[10:01:20.155] INFO: PixTest:: pg_setup set to default.
[10:01:20.156] INFO: 1 hot pixels found in step 2
[10:01:21.196] INFO: Collecting data for 1 seconds...
[10:01:22.200] INFO: Done with hot pixel readout
[10:01:26.263] INFO: PixTest:: pg_setup set to default.
[10:01:26.264] INFO: 1 hot pixels found in step 3
[10:01:27.308] INFO: Collecting data for 1 seconds...
[10:01:28.312] INFO: Done with hot pixel readout
[10:01:32.474] INFO: PixTest:: pg_setup set to default.
[10:01:32.475] INFO: 1 hot pixels found in step 4
[10:01:33.520] INFO: Collecting data for 1 seconds...
[10:01:34.524] INFO: Done with hot pixel readout
[10:01:38.654] INFO: PixTest:: pg_setup set to default.
[10:01:38.655] INFO: 1 hot pixels found in step 5
[10:01:39.700] INFO: Collecting data for 1 seconds...
[10:01:40.704] INFO: Done with hot pixel readout
[10:01:44.623] INFO: PixTest:: pg_setup set to default.
[10:01:44.623] INFO: 1 hot pixels found in step 6
[10:01:45.673] INFO: Collecting data for 1 seconds...
[10:01:46.677] INFO: Done with hot pixel readout
[10:01:50.692] INFO: PixTest:: pg_setup set to default.
[10:01:50.693] INFO: 1 hot pixels found in step 7
[10:01:51.733] INFO: Collecting data for 1 seconds...
[10:01:52.738] INFO: Done with hot pixel readout
[10:01:56.823] INFO: PixTest:: pg_setup set to default.
[10:01:56.824] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:01:56.824] INFO: 1 hot pixels found in step 8
[10:01:56.830] INFO: 1 hot pixels could not be trimmed and have been masked.
[10:01:56.916] INFO: PixTest::trimHotPixels() done
[10:01:56.916] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C0.dat
[10:01:56.928] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C1.dat
[10:01:56.935] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C2.dat
[10:01:56.940] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C3.dat
[10:01:56.946] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C4.dat
[10:01:56.951] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C5.dat
[10:01:56.957] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C6.dat
[10:01:56.962] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C7.dat
[10:01:56.968] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C8.dat
[10:01:56.973] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C9.dat
[10:01:56.979] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C10.dat
[10:01:56.984] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C11.dat
[10:01:56.990] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C12.dat
[10:01:56.995] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C13.dat
[10:01:56.001] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C14.dat
[10:01:56.006] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//trimParameters35_C15.dat
[10:01:57.011] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-A-34_FPIXTest-17C-FNAL-160906-1408-150V_2016-09-06_14h08m_1473188910/000_FPIXTest_p17//defaultMaskFile.dat
[10:01:57.022] INFO: enter test to run
[10:02:28.270] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:02:28.270] INFO: running: xray
[10:02:28.271] INFO: ----------------------------------------------------------------------
[10:02:28.271] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:02:28.271] INFO: ----------------------------------------------------------------------
[10:02:29.252] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:02:40.809] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:03:10.933] INFO: Resuming triggers.
[10:03:22.487] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[10:03:52.367] INFO: Resuming triggers.
[10:04:03.920] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:04:33.492] INFO: Resuming triggers.
[10:04:45.046] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:05:14.522] INFO: Resuming triggers.
[10:05:26.080] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[10:05:55.413] INFO: Resuming triggers.
[10:06:06.968] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[10:06:36.454] INFO: Resuming triggers.
[10:06:48.011] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[10:07:17.530] INFO: Resuming triggers.
[10:07:29.094] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[10:07:58.449] INFO: Resuming triggers.
[10:08:06.337] INFO: data taking finished, elapsed time: 100 seconds.
[10:08:26.636] INFO: PixTest:: pg_setup set to default.
[10:08:26.639] INFO: PixTestXray::doPhRun() done
[10:08:26.840] INFO: enter test to run
[10:09:17.315] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:09:17.315] INFO: running: xray
[10:09:17.316] INFO: ----------------------------------------------------------------------
[10:09:17.316] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:09:17.316] INFO: ----------------------------------------------------------------------
[10:09:18.284] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:09:24.977] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:09:55.145] INFO: Resuming triggers.
[10:10:01.840] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[10:10:32.040] INFO: Resuming triggers.
[10:10:38.735] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[10:11:08.745] INFO: Resuming triggers.
[10:11:15.439] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[10:11:45.518] INFO: Resuming triggers.
[10:11:52.211] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[10:12:23.035] INFO: Resuming triggers.
[10:12:29.724] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[10:12:59.856] INFO: Resuming triggers.
[10:13:06.547] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:13:37.153] INFO: Resuming triggers.
[10:13:43.847] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[10:14:14.578] INFO: Resuming triggers.
[10:14:21.265] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[10:14:52.636] INFO: Resuming triggers.
[10:14:59.327] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[10:15:30.240] INFO: Resuming triggers.
[10:15:36.928] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[10:16:08.143] INFO: Resuming triggers.
[10:16:14.835] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[10:16:45.042] INFO: Resuming triggers.
[10:16:51.737] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[10:17:21.943] INFO: Resuming triggers.
[10:17:28.639] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[10:17:58.802] INFO: Resuming triggers.
[10:18:05.457] INFO: data taking finished, elapsed time: 100 seconds.
[10:18:35.555] INFO: PixTest:: pg_setup set to default.
[10:18:35.559] INFO: PixTestXray::doPhRun() done
[10:18:35.706] INFO: enter test to run
[10:19:28.975] INFO: test: HighRate no parameter change
[10:19:28.975] INFO: running: highrate
[10:19:28.985] INFO: ----------------------------------------------------------------------
[10:19:28.985] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:19:28.985] INFO: ----------------------------------------------------------------------
[10:19:29.132] INFO: Expecting 768 events.
[10:19:30.272] INFO: 768 events read in total (418ms).
[10:19:30.273] INFO: Test took 1276ms.
[10:19:31.076] INFO: Expecting 41600 events.
[10:19:34.139] INFO: 41600 events read in total (2536ms).
[10:19:34.140] INFO: Test took 3847ms.
[10:19:34.173] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:34.902] INFO: Expecting 41600 events.
[10:19:38.064] INFO: 41600 events read in total (2635ms).
[10:19:38.065] INFO: Test took 3874ms.
[10:19:38.099] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:38.824] INFO: Expecting 41600 events.
[10:19:42.039] INFO: 41600 events read in total (2688ms).
[10:19:42.040] INFO: Test took 3924ms.
[10:19:42.074] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:42.800] INFO: Expecting 41600 events.
[10:19:46.035] INFO: 41600 events read in total (2708ms).
[10:19:46.036] INFO: Test took 3945ms.
[10:19:46.070] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:46.796] INFO: Expecting 41600 events.
[10:19:50.030] INFO: 41600 events read in total (2707ms).
[10:19:50.031] INFO: Test took 3942ms.
[10:19:50.065] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:50.790] INFO: Expecting 41600 events.
[10:19:54.015] INFO: 41600 events read in total (2698ms).
[10:19:54.016] INFO: Test took 3933ms.
[10:19:54.050] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:54.775] INFO: Expecting 41600 events.
[10:19:58.017] INFO: 41600 events read in total (2715ms).
[10:19:58.018] INFO: Test took 3950ms.
[10:19:58.053] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:19:58.771] INFO: Expecting 41600 events.
[10:20:01.958] INFO: 41600 events read in total (2660ms).
[10:20:01.959] INFO: Test took 3889ms.
[10:20:01.993] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:20:02.718] INFO: Expecting 41600 events.
[10:20:05.966] INFO: 41600 events read in total (2721ms).
[10:20:05.967] INFO: Test took 3956ms.
[10:20:05.001] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:20:06.726] INFO: Expecting 41600 events.
[10:20:09.960] INFO: 41600 events read in total (2707ms).
[10:20:09.961] INFO: Test took 3943ms.
[10:20:09.995] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:20:10.718] INFO: Expecting 41600 events.
[10:20:13.967] INFO: 41600 events read in total (2722ms).
[10:20:13.968] INFO: Test took 3954ms.
[10:20:13.002] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:20:14.724] INFO: Expecting 41600 events.
[10:20:17.974] INFO: 41600 events read in total (2723ms).
[10:20:17.975] INFO: Test took 3955ms.
[10:20:18.010] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:20:18.729] INFO: Expecting 41600 events.
[10:20:21.960] INFO: 41600 events read in total (2704ms).
[10:20:21.961] INFO: Test took 3933ms.
[10:20:21.995] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:20:22.719] INFO: Expecting 41600 events.
[10:20:25.967] INFO: 41600 events read in total (2721ms).
[10:20:25.968] INFO: Test took 3954ms.
[10:20:25.002] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:20:26.724] INFO: Expecting 41600 events.
[10:20:29.966] INFO: 41600 events read in total (2715ms).
[10:20:29.967] INFO: Test took 3946ms.
[10:20:29.002] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:20:30.725] INFO: Expecting 41600 events.
[10:20:33.971] INFO: 41600 events read in total (2719ms).
[10:20:33.972] INFO: Test took 3951ms.
[10:20:34.007] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:20:34.728] INFO: Expecting 41600 events.
[10:20:37.959] INFO: 41600 events read in total (2705ms).
[10:20:37.960] INFO: Test took 3933ms.
[10:20:37.994] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:20:38.718] INFO: Expecting 41600 events.
[10:20:41.964] INFO: 41600 events read in total (2719ms).
[10:20:41.965] INFO: Test took 3952ms.
[10:20:41.998] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:20:42.722] INFO: Expecting 41600 events.
[10:20:45.949] INFO: 41600 events read in total (2700ms).
[10:20:45.950] INFO: Test took 3933ms.
[10:20:45.984] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:20:46.709] INFO: Expecting 41600 events.
[10:20:49.810] INFO: 41600 events read in total (2574ms).
[10:20:49.811] INFO: Test took 3807ms.
[10:20:49.845] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:20:50.207] INFO: enter test to run
[10:21:59.694] INFO: test: HighRate no parameter change
[10:21:59.694] INFO: running: highrate
[10:21:59.695] INFO: ----------------------------------------------------------------------
[10:21:59.695] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:21:59.695] INFO: ----------------------------------------------------------------------
[10:22:00.309] INFO: Expecting 208000 events.
[10:22:12.134] INFO: 208000 events read in total (11298ms).
[10:22:12.139] INFO: Test took 12435ms.
[10:22:12.286] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:22:12.539] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 2 1 0 0 0 0 0 0 0 0
[10:22:12.539] INFO: number of red-efficiency pixels: 86 43 91 148 134 155 151 118 72 94 120 126 98 51 14 41
[10:22:12.539] INFO: number of X-ray hits detected: 67980 45785 71300 108434 115684 121458 114533 82310 70762 96735 96687 82241 85360 53286 19954 26980
[10:22:12.539] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:22:12.539] INFO: number of Vcal hits detected: 207911 207956 207906 207850 207863 207836 207746 207789 207927 207904 207877 207873 207901 207949 207986 207958
[10:22:12.539] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0
[10:22:12.539] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0
[10:22:12.539] INFO: X-ray hit rate [MHz/cm2]: 19.9 13.4 20.9 31.8 33.9 35.6 33.6 24.1 20.7 28.4 28.3 24.1 25.0 15.6 5.8 7.9
[10:22:12.539] INFO: PixTestHighRate::doXPixelAlive() done
[10:22:12.588] INFO: PixTest:: pg_setup set to default.
[10:22:12.604] INFO: enter test to run
[10:22:27.726] INFO: test: HighRate no parameter change
[10:22:27.726] INFO: running: highrate
[10:22:27.727] INFO: ----------------------------------------------------------------------
[10:22:27.727] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:22:27.727] INFO: ----------------------------------------------------------------------
[10:22:28.339] INFO: Expecting 208000 events.
[10:22:41.932] INFO: 208000 events read in total (13066ms).
[10:22:41.937] INFO: Test took 14201ms.
[10:22:42.242] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:22:42.543] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 2 1 0 0 0 0 0 0 0 0
[10:22:42.543] INFO: number of red-efficiency pixels: 258 141 350 472 414 532 570 348 199 327 405 313 255 151 49 64
[10:22:42.543] INFO: number of X-ray hits detected: 138932 93563 146119 220490 235077 248547 234054 163515 144333 199121 197096 168676 174775 108849 40817 54410
[10:22:42.543] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:22:42.543] INFO: number of Vcal hits detected: 207723 207851 207601 207487 207558 207394 207271 207524 207790 207653 207576 207670 207735 207845 207950 207936
[10:22:42.543] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.7 99.7 99.8 99.9 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[10:22:42.543] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.8 99.7 99.6 99.8 99.9 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[10:22:42.543] INFO: X-ray hit rate [MHz/cm2]: 40.7 27.4 42.8 64.6 68.9 72.9 68.6 47.9 42.3 58.4 57.8 49.4 51.2 31.9 12.0 15.9
[10:22:42.543] INFO: PixTestHighRate::doXPixelAlive() done
[10:22:42.598] INFO: PixTest:: pg_setup set to default.
[10:22:42.609] INFO: enter test to run
[10:22:59.541] INFO: test: HighRate no parameter change
[10:22:59.541] INFO: running: highrate
[10:22:59.543] INFO: ----------------------------------------------------------------------
[10:22:59.543] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:22:59.543] INFO: ----------------------------------------------------------------------
[10:23:00.158] INFO: Expecting 208000 events.
[10:23:15.771] INFO: 208000 events read in total (15086ms).
[10:23:15.779] INFO: Test took 16225ms.
[10:23:16.236] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:23:16.587] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 2 1 0 0 0 0 0 0 0 0
[10:23:16.587] INFO: number of red-efficiency pixels: 570 292 797 1174 985 1220 1440 802 494 759 1001 783 692 326 77 138
[10:23:16.587] INFO: number of X-ray hits detected: 215952 145967 228104 343558 367421 385542 363280 253399 226129 308938 307207 263133 271019 169666 63568 85955
[10:23:16.587] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[10:23:16.587] INFO: number of Vcal hits detected: 207278 207672 206811 206457 206787 206269 205927 206839 207448 207116 206746 207070 207201 207627 207923 207860
[10:23:16.587] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.5 99.4 99.5 99.2 99.2 99.5 99.8 99.6 99.5 99.6 99.7 99.8 100.0 99.9
[10:23:16.587] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.4 99.3 99.4 99.2 99.0 99.4 99.7 99.6 99.4 99.6 99.6 99.8 100.0 99.9
[10:23:16.587] INFO: X-ray hit rate [MHz/cm2]: 63.3 42.8 66.9 100.7 107.7 113.0 106.5 74.3 66.3 90.6 90.0 77.1 79.4 49.7 18.6 25.2
[10:23:16.587] INFO: PixTestHighRate::doXPixelAlive() done
[10:23:16.636] INFO: PixTest:: pg_setup set to default.
[10:23:16.651] INFO: enter test to run
[10:23:21.669] INFO: test: exit no parameter change
[10:23:22.127] QUIET: Connection to board 33 closed.
[10:23:22.139] INFO: pXar: this is the end, my friend