[10:20:49.295] INFO: *** Welcome to pxar ***
[10:20:49.295] INFO: *** Today: 2016/09/19
[10:20:50.330] INFO: *** Version: v1.9.0-818-g96727
[10:20:50.330] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//dacParameters35_C15.dat
[10:20:50.399] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:20:50.399] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//defaultMaskFile.dat
[10:20:50.408] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C15.dat
[10:20:50.564] INFO: clk: 4
[10:20:50.564] INFO: ctr: 4
[10:20:50.564] INFO: sda: 19
[10:20:50.564] INFO: tin: 9
[10:20:50.564] INFO: level: 15
[10:20:50.564] INFO: triggerdelay: 0
[10:20:50.564] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[10:20:50.564] INFO: Log level: INFO
[10:20:50.581] QUIET: Connection to board DTB_WREKRL opened.
[10:20:50.585] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[10:20:50.587] INFO: RPC call hashes of host and DTB match: 398089610
[10:20:52.145] INFO: DUT info:
[10:20:52.145] INFO: The DUT currently contains the following objects:
[10:20:52.145] INFO: 2 TBM Cores tbm08c (2 ON)
[10:20:52.145] INFO: TBM Core alpha (0): 7 registers set
[10:20:52.145] INFO: TBM Core beta (1): 7 registers set
[10:20:52.145] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:20:52.145] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:52.145] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:52.145] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:52.145] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:52.145] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:52.145] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:52.145] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:52.145] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:52.145] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:52.145] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:52.145] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:52.145] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:52.145] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:52.145] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:52.145] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:52.145] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:20:52.603] INFO: enter 'restricted' command line mode
[10:20:52.603] INFO: enter test to run
[10:21:03.855] INFO: test: PixelAlive no parameter change
[10:21:03.855] INFO: running: pixelalive
[10:21:03.923] INFO: ----------------------------------------------------------------------
[10:21:03.923] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[10:21:03.923] INFO: ----------------------------------------------------------------------
[10:21:04.243] INFO: Expecting 41600 events.
[10:21:08.377] INFO: 41600 events read in total (3415ms).
[10:21:08.542] INFO: Test took 4617ms.
[10:21:08.550] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:21:09.102] INFO: PixTestAlive::aliveTest() done
[10:21:09.102] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 1 1 0 0 1 0
[10:21:09.297] INFO: enter test to run
[10:21:36.295] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:21:36.295] INFO: running: highrate
[10:21:36.311] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:21:36.555] INFO: ----------------------------------------------------------------------
[10:21:36.555] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:21:36.555] INFO: ----------------------------------------------------------------------
[10:21:36.555] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:21:36.555] INFO: edge/corner pixel THR is adjusted
[10:21:36.555] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:21:37.513] INFO: Collecting data for 5 seconds...
[10:21:42.527] INFO: Done with hot pixel readout
[10:21:52.827] INFO: PixTest:: pg_setup set to default.
[10:21:52.828] INFO: 18 hot pixels found in step 0
[10:21:53.844] INFO: Collecting data for 5 seconds...
[10:21:58.857] INFO: Done with hot pixel readout
[10:22:08.835] INFO: PixTest:: pg_setup set to default.
[10:22:08.835] INFO: 20 hot pixels found in step 1
[10:22:09.824] INFO: Collecting data for 5 seconds...
[10:22:14.839] INFO: Done with hot pixel readout
[10:22:25.115] INFO: PixTest:: pg_setup set to default.
[10:22:25.116] INFO: 20 hot pixels found in step 2
[10:22:26.105] INFO: Collecting data for 5 seconds...
[10:22:31.120] INFO: Done with hot pixel readout
[10:22:41.082] INFO: PixTest:: pg_setup set to default.
[10:22:41.083] INFO: 18 hot pixels found in step 3
[10:22:42.073] INFO: Collecting data for 5 seconds...
[10:22:47.089] INFO: Done with hot pixel readout
[10:22:57.560] INFO: PixTest:: pg_setup set to default.
[10:22:57.560] INFO: 20 hot pixels found in step 4
[10:22:58.550] INFO: Collecting data for 5 seconds...
[10:23:03.565] INFO: Done with hot pixel readout
[10:23:14.050] INFO: PixTest:: pg_setup set to default.
[10:23:14.050] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:23:14.050] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:23:14.051] INFO: 17 hot pixels found in step 5
[10:23:15.040] INFO: Collecting data for 5 seconds...
[10:23:20.056] INFO: Done with hot pixel readout
[10:23:30.700] INFO: PixTest:: pg_setup set to default.
[10:23:30.700] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:23:30.700] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:23:30.700] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:23:30.701] INFO: 15 hot pixels found in step 6
[10:23:31.690] INFO: Collecting data for 5 seconds...
[10:23:36.703] INFO: Done with hot pixel readout
[10:23:46.805] INFO: PixTest:: pg_setup set to default.
[10:23:46.805] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:23:46.805] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:23:46.805] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:23:46.805] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:23:46.805] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:23:46.806] INFO: 14 hot pixels found in step 7
[10:23:47.795] INFO: Collecting data for 5 seconds...
[10:23:52.810] INFO: Done with hot pixel readout
[10:24:02.941] INFO: PixTest:: pg_setup set to default.
[10:24:02.941] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:02.941] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:02.941] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:02.941] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:02.941] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:02.941] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:02.942] INFO: 12 hot pixels found in step 8
[10:24:03.931] INFO: Collecting data for 5 seconds...
[10:24:08.945] INFO: Done with hot pixel readout
[10:24:19.623] INFO: PixTest:: pg_setup set to default.
[10:24:19.623] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:19.623] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:19.623] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:19.623] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:19.623] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:19.623] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:19.624] INFO: 9 hot pixels found in step 9
[10:24:20.612] INFO: Collecting data for 5 seconds...
[10:24:25.629] INFO: Done with hot pixel readout
[10:24:36.758] INFO: PixTest:: pg_setup set to default.
[10:24:36.758] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:36.758] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:36.758] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:36.758] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:36.758] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:36.758] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:36.759] INFO: 8 hot pixels found in step 10
[10:24:37.748] INFO: Collecting data for 5 seconds...
[10:24:42.765] INFO: Done with hot pixel readout
[10:24:53.844] INFO: PixTest:: pg_setup set to default.
[10:24:53.844] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:53.845] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:53.845] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:53.845] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:53.845] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:53.845] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:53.845] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:24:53.845] INFO: 12 hot pixels found in step 11
[10:24:54.835] INFO: Collecting data for 5 seconds...
[10:24:59.851] INFO: Done with hot pixel readout
[10:25:10.692] INFO: PixTest:: pg_setup set to default.
[10:25:10.692] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:10.692] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:10.692] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:10.692] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:10.692] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:10.692] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:10.692] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:10.693] INFO: 11 hot pixels found in step 12
[10:25:11.682] INFO: Collecting data for 5 seconds...
[10:25:16.698] INFO: Done with hot pixel readout
[10:25:27.894] INFO: PixTest:: pg_setup set to default.
[10:25:27.894] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:27.894] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:27.894] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:27.894] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:27.894] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:27.894] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:27.894] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:27.895] INFO: 10 hot pixels found in step 13
[10:25:28.885] INFO: Collecting data for 5 seconds...
[10:25:33.902] INFO: Done with hot pixel readout
[10:25:45.245] INFO: PixTest:: pg_setup set to default.
[10:25:45.245] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:45.245] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:45.245] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:45.245] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:45.245] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:45.245] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:45.245] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:25:45.246] INFO: 8 hot pixels found in step 14
[10:25:45.279] INFO: 8 hot pixels could not be trimmed and have been masked.
[10:25:45.298] INFO: PixTest::trimHotPixels() done
[10:25:45.301] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C0.dat
[10:25:45.306] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C1.dat
[10:25:45.312] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C2.dat
[10:25:45.317] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C3.dat
[10:25:45.322] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C4.dat
[10:25:45.328] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C5.dat
[10:25:45.333] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C6.dat
[10:25:45.338] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C7.dat
[10:25:45.344] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C8.dat
[10:25:45.349] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C9.dat
[10:25:45.354] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C10.dat
[10:25:45.360] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C11.dat
[10:25:45.365] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C12.dat
[10:25:45.372] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C13.dat
[10:25:45.377] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C14.dat
[10:25:45.386] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C15.dat
[10:25:45.395] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//defaultMaskFile.dat
[10:25:45.431] INFO: enter test to run
[10:26:11.837] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:26:11.837] INFO: running: highrate
[10:26:11.842] INFO: ----------------------------------------------------------------------
[10:26:11.842] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:26:11.842] INFO: ----------------------------------------------------------------------
[10:26:11.842] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:26:11.842] INFO: edge/corner pixel THR is adjusted
[10:26:11.842] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:26:12.805] INFO: Collecting data for 1 seconds...
[10:26:13.808] INFO: Done with hot pixel readout
[10:26:17.751] INFO: PixTest:: pg_setup set to default.
[10:26:17.752] INFO: 0 hot pixels found in step 0
[10:26:17.758] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:26:17.844] INFO: PixTest::trimHotPixels() done
[10:26:17.844] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C0.dat
[10:26:17.877] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C1.dat
[10:26:17.887] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C2.dat
[10:26:17.897] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C3.dat
[10:26:17.902] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C4.dat
[10:26:17.907] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C5.dat
[10:26:17.913] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C6.dat
[10:26:17.918] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C7.dat
[10:26:17.923] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C8.dat
[10:26:17.929] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C9.dat
[10:26:17.934] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C10.dat
[10:26:17.940] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C11.dat
[10:26:17.945] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C12.dat
[10:26:17.950] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C13.dat
[10:26:17.956] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C14.dat
[10:26:17.961] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//trimParameters35_C15.dat
[10:26:17.967] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-3-31_FPIXTest-17C-FNAL-160913-1633-150V_2016-09-13_16h33m_1473802423/000_FPIXTest_p17//defaultMaskFile.dat
[10:26:17.980] INFO: enter test to run
[10:26:58.341] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:26:58.341] INFO: running: xray
[10:26:58.342] INFO: ----------------------------------------------------------------------
[10:26:58.342] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:26:58.342] INFO: ----------------------------------------------------------------------
[10:26:59.316] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:27:11.203] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:27:40.048] INFO: Resuming triggers.
[10:27:51.942] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[10:28:19.975] INFO: Resuming triggers.
[10:28:31.868] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[10:29:00.458] INFO: Resuming triggers.
[10:29:12.357] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[10:29:40.817] INFO: Resuming triggers.
[10:29:52.716] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[10:30:20.928] INFO: Resuming triggers.
[10:30:32.827] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[10:31:01.382] INFO: Resuming triggers.
[10:31:13.278] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[10:31:41.634] INFO: Resuming triggers.
[10:31:53.533] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[10:32:22.037] INFO: Resuming triggers.
[10:32:27.194] INFO: data taking finished, elapsed time: 100 seconds.
[10:32:39.855] INFO: PixTest:: pg_setup set to default.
[10:32:39.859] INFO: PixTestXray::doPhRun() done
[10:32:40.079] INFO: enter test to run
[10:33:08.690] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:33:08.690] INFO: running: xray
[10:33:08.691] INFO: ----------------------------------------------------------------------
[10:33:08.691] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:33:08.691] INFO: ----------------------------------------------------------------------
[10:33:09.652] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:33:16.403] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:33:45.189] INFO: Resuming triggers.
[10:33:51.942] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[10:34:21.113] INFO: Resuming triggers.
[10:34:27.867] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[10:34:56.924] INFO: Resuming triggers.
[10:35:03.675] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[10:35:33.198] INFO: Resuming triggers.
[10:35:39.948] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[10:36:09.560] INFO: Resuming triggers.
[10:36:16.307] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[10:36:46.486] INFO: Resuming triggers.
[10:36:53.234] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[10:37:23.498] INFO: Resuming triggers.
[10:37:30.245] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[10:38:00.009] INFO: Resuming triggers.
[10:38:06.755] INFO: run duration 60 seconds, buffer almost full (81%), pausing triggers.
[10:38:36.440] INFO: Resuming triggers.
[10:38:43.188] INFO: run duration 67 seconds, buffer almost full (81%), pausing triggers.
[10:39:13.152] INFO: Resuming triggers.
[10:39:19.898] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[10:39:50.020] INFO: Resuming triggers.
[10:39:56.764] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[10:40:26.657] INFO: Resuming triggers.
[10:40:33.403] INFO: run duration 87 seconds, buffer almost full (81%), pausing triggers.
[10:41:03.389] INFO: Resuming triggers.
[10:41:10.137] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[10:41:39.993] INFO: Resuming triggers.
[10:41:45.835] INFO: data taking finished, elapsed time: 100 seconds.
[10:42:11.435] INFO: PixTest:: pg_setup set to default.
[10:42:11.439] INFO: PixTestXray::doPhRun() done
[10:42:11.629] INFO: enter test to run
[10:42:50.272] INFO: test: HighRate no parameter change
[10:42:50.272] INFO: running: highrate
[10:42:50.283] INFO: ----------------------------------------------------------------------
[10:42:50.283] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:42:50.283] INFO: ----------------------------------------------------------------------
[10:42:50.430] INFO: Expecting 768 events.
[10:42:51.569] INFO: 768 events read in total (418ms).
[10:42:51.569] INFO: Test took 1274ms.
[10:42:52.373] INFO: Expecting 41600 events.
[10:42:55.410] INFO: 41600 events read in total (2511ms).
[10:42:55.411] INFO: Test took 3821ms.
[10:42:55.448] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:42:56.174] INFO: Expecting 41600 events.
[10:42:59.332] INFO: 41600 events read in total (2632ms).
[10:42:59.333] INFO: Test took 3867ms.
[10:42:59.371] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:00.092] INFO: Expecting 41600 events.
[10:43:03.314] INFO: 41600 events read in total (2695ms).
[10:43:03.315] INFO: Test took 3926ms.
[10:43:03.352] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:04.068] INFO: Expecting 41600 events.
[10:43:07.273] INFO: 41600 events read in total (2678ms).
[10:43:07.274] INFO: Test took 3903ms.
[10:43:07.312] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:08.022] INFO: Expecting 41600 events.
[10:43:11.235] INFO: 41600 events read in total (2686ms).
[10:43:11.236] INFO: Test took 3904ms.
[10:43:11.274] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:11.991] INFO: Expecting 41600 events.
[10:43:15.208] INFO: 41600 events read in total (2690ms).
[10:43:15.209] INFO: Test took 3915ms.
[10:43:15.247] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:15.965] INFO: Expecting 41600 events.
[10:43:19.095] INFO: 41600 events read in total (2603ms).
[10:43:19.097] INFO: Test took 3829ms.
[10:43:19.134] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:19.852] INFO: Expecting 41600 events.
[10:43:23.044] INFO: 41600 events read in total (2666ms).
[10:43:23.045] INFO: Test took 3892ms.
[10:43:23.083] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:23.795] INFO: Expecting 41600 events.
[10:43:26.931] INFO: 41600 events read in total (2609ms).
[10:43:26.932] INFO: Test took 3830ms.
[10:43:26.970] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:27.686] INFO: Expecting 41600 events.
[10:43:30.926] INFO: 41600 events read in total (2713ms).
[10:43:30.928] INFO: Test took 3939ms.
[10:43:30.965] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:31.680] INFO: Expecting 41600 events.
[10:43:34.885] INFO: 41600 events read in total (2678ms).
[10:43:34.886] INFO: Test took 3901ms.
[10:43:34.924] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:35.637] INFO: Expecting 41600 events.
[10:43:38.880] INFO: 41600 events read in total (2716ms).
[10:43:38.881] INFO: Test took 3938ms.
[10:43:38.919] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:39.636] INFO: Expecting 41600 events.
[10:43:42.809] INFO: 41600 events read in total (2646ms).
[10:43:42.810] INFO: Test took 3872ms.
[10:43:42.847] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:43.566] INFO: Expecting 41600 events.
[10:43:46.778] INFO: 41600 events read in total (2685ms).
[10:43:46.779] INFO: Test took 3913ms.
[10:43:46.816] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:47.531] INFO: Expecting 41600 events.
[10:43:50.748] INFO: 41600 events read in total (2690ms).
[10:43:50.749] INFO: Test took 3914ms.
[10:43:50.786] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:51.508] INFO: Expecting 41600 events.
[10:43:54.723] INFO: 41600 events read in total (2688ms).
[10:43:54.724] INFO: Test took 3918ms.
[10:43:54.767] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:55.474] INFO: Expecting 41600 events.
[10:43:58.693] INFO: 41600 events read in total (2692ms).
[10:43:58.694] INFO: Test took 3908ms.
[10:43:58.732] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:43:59.447] INFO: Expecting 41600 events.
[10:44:02.646] INFO: 41600 events read in total (2672ms).
[10:44:02.647] INFO: Test took 3896ms.
[10:44:02.685] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:03.405] INFO: Expecting 41600 events.
[10:44:06.622] INFO: 41600 events read in total (2691ms).
[10:44:06.623] INFO: Test took 3919ms.
[10:44:06.661] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:07.376] INFO: Expecting 41600 events.
[10:44:10.473] INFO: 41600 events read in total (2570ms).
[10:44:10.474] INFO: Test took 3794ms.
[10:44:10.511] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:44:10.871] INFO: enter test to run
[10:45:06.037] INFO: test: HighRate no parameter change
[10:45:06.037] INFO: running: highrate
[10:45:06.038] INFO: ----------------------------------------------------------------------
[10:45:06.038] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:45:06.038] INFO: ----------------------------------------------------------------------
[10:45:06.665] INFO: Expecting 208000 events.
[10:45:18.095] INFO: 208000 events read in total (10903ms).
[10:45:18.099] INFO: Test took 12052ms.
[10:45:18.266] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:18.526] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 1 1 0 0 0 0
[10:45:18.527] INFO: number of red-efficiency pixels: 96 61 105 175 170 202 155 89 130 139 181 144 136 73 30 20
[10:45:18.527] INFO: number of X-ray hits detected: 77300 50566 76809 122529 121125 125295 121065 81484 85362 117103 114830 98543 101805 61797 24938 27139
[10:45:18.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
[10:45:18.527] INFO: number of Vcal hits detected: 207903 207937 207890 207776 207824 207791 207842 207909 207868 207856 207766 207804 207861 207926 207969 207979
[10:45:18.527] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[10:45:18.527] INFO: Vcal hit overall efficiency (%): 100.0 100.0 99.9 99.9 99.9 99.9 99.9 100.0 99.9 99.9 99.9 99.9 99.9 100.0 100.0 100.0
[10:45:18.527] INFO: X-ray hit rate [MHz/cm2]: 22.7 14.8 22.5 35.9 35.5 36.7 35.5 23.9 25.0 34.3 33.7 28.9 29.8 18.1 7.3 8.0
[10:45:18.527] INFO: PixTestHighRate::doXPixelAlive() done
[10:45:18.573] INFO: PixTest:: pg_setup set to default.
[10:45:18.581] INFO: enter test to run
[10:45:37.109] INFO: test: HighRate no parameter change
[10:45:37.109] INFO: running: highrate
[10:45:37.110] INFO: ----------------------------------------------------------------------
[10:45:37.110] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:45:37.110] INFO: ----------------------------------------------------------------------
[10:45:37.724] INFO: Expecting 208000 events.
[10:45:50.127] INFO: 208000 events read in total (11876ms).
[10:45:50.132] INFO: Test took 13014ms.
[10:45:50.444] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:45:50.746] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 1 1 0 0 0 0
[10:45:50.746] INFO: number of red-efficiency pixels: 250 141 288 379 508 489 364 198 248 350 431 294 342 176 60 51
[10:45:50.746] INFO: number of X-ray hits detected: 137153 90260 136417 216876 214517 222887 216323 143929 152035 207313 203366 175618 179577 108359 44486 48749
[10:45:50.746] 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:45:50.746] INFO: number of Vcal hits detected: 207721 207852 207683 207542 207457 207459 207613 207785 207740 207625 207485 207641 207645 207815 207938 207949
[10:45:50.746] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 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
[10:45:50.746] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.8 99.7 99.7 99.8 99.9 99.9 99.8 99.8 99.8 99.8 99.9 100.0 100.0
[10:45:50.746] INFO: X-ray hit rate [MHz/cm2]: 40.2 26.5 40.0 63.6 62.9 65.3 63.4 42.2 44.6 60.8 59.6 51.5 52.6 31.8 13.0 14.3
[10:45:50.746] INFO: PixTestHighRate::doXPixelAlive() done
[10:45:50.792] INFO: PixTest:: pg_setup set to default.
[10:45:50.805] INFO: enter test to run
[10:46:09.557] INFO: test: HighRate no parameter change
[10:46:09.557] INFO: running: highrate
[10:46:09.558] INFO: ----------------------------------------------------------------------
[10:46:09.558] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:46:09.558] INFO: ----------------------------------------------------------------------
[10:46:10.170] INFO: Expecting 208000 events.
[10:46:24.805] INFO: 208000 events read in total (14108ms).
[10:46:24.813] INFO: Test took 15247ms.
[10:46:25.271] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:46:25.627] INFO: number of dead pixels (per ROC): 0 0 0 1 0 0 0 0 0 0 1 1 0 0 0 0
[10:46:25.627] INFO: number of red-efficiency pixels: 603 345 704 980 1165 1299 893 481 637 891 1147 746 753 385 107 111
[10:46:25.627] INFO: number of X-ray hits detected: 214937 141949 214751 342986 338082 349862 340391 228375 241009 326680 321746 275327 283257 172018 70332 77324
[10:46:25.627] 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:46:25.627] INFO: number of Vcal hits detected: 207195 207598 207047 206758 206493 206214 206922 207443 207258 206948 206533 207084 207111 207563 207890 207886
[10:46:25.627] INFO: Vcal hit fiducial efficiency (%): 99.7 99.8 99.6 99.5 99.3 99.2 99.5 99.8 99.7 99.5 99.4 99.6 99.6 99.8 99.9 99.9
[10:46:25.627] INFO: Vcal hit overall efficiency (%): 99.6 99.8 99.5 99.4 99.3 99.1 99.5 99.7 99.6 99.5 99.3 99.6 99.6 99.8 99.9 99.9
[10:46:25.627] INFO: X-ray hit rate [MHz/cm2]: 63.0 41.6 62.9 100.5 99.1 102.5 99.8 66.9 70.6 95.8 94.3 80.7 83.0 50.4 20.6 22.7
[10:46:25.627] INFO: PixTestHighRate::doXPixelAlive() done
[10:46:25.672] INFO: PixTest:: pg_setup set to default.
[10:46:25.687] INFO: enter test to run
[10:46:32.477] INFO: test: exit no parameter change
[10:46:33.078] QUIET: Connection to board 33 closed.
[10:46:33.087] INFO: pXar: this is the end, my friend