[10:30:43.484] INFO: *** Welcome to pxar ***
[10:30:43.484] INFO: *** Today: 2016/03/30
[10:30:43.499] INFO: *** Version: v1.9.0-793-ge521
[10:30:43.499] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/dacParameters35_C15.dat
[10:30:43.527] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/tbmParameters_C0b.dat
[10:30:43.527] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/defaultMaskFile.dat
[10:30:43.539] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C15.dat
[10:30:43.637] INFO: clk: 4
[10:30:43.637] INFO: ctr: 4
[10:30:43.637] INFO: sda: 19
[10:30:43.637] INFO: tin: 9
[10:30:43.637] INFO: level: 15
[10:30:43.637] INFO: triggerdelay: 0
[10:30:43.638] QUIET: Instanciating API for pxar v1.9.0+788~g8b11555
[10:30:43.638] INFO: Log level: INFO
[10:30:43.654] QUIET: Connection to board DTB_WREKRL opened.
[10:30:43.657] 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:30:43.660] INFO: RPC call hashes of host and DTB match: 398089610
[10:30:45.190] INFO: DUT info:
[10:30:45.190] INFO: The DUT currently contains the following objects:
[10:30:45.190] INFO: 2 TBM Cores tbm08c (2 ON)
[10:30:45.190] INFO: TBM Core alpha (0): 7 registers set
[10:30:45.190] INFO: TBM Core beta (1): 7 registers set
[10:30:45.190] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:30:45.190] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:45.190] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:45.190] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:45.191] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:45.191] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:45.191] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:45.191] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:45.191] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:45.191] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:45.191] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:45.191] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:45.191] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:45.191] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:45.191] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:45.191] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:45.191] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:30:45.594] INFO: enter 'restricted' command line mode
[10:30:45.595] INFO: enter test to run
[10:30:54.684] INFO: test: PixelAlive no parameter change
[10:30:54.684] INFO: running: pixelalive
[10:30:54.694] INFO: ----------------------------------------------------------------------
[10:30:54.694] 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:30:54.694] INFO: ----------------------------------------------------------------------
[10:30:55.016] INFO: Expecting 41600 events.
[10:30:59.356] INFO: 41600 events read in total (3622ms).
[10:30:59.521] INFO: Test took 4825ms.
[10:30:59.530] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:30:59.790] INFO: PixTestAlive::aliveTest() done
[10:30:59.790] INFO: number of dead pixels (per ROC): 160 0 0 0 1 0 0 0 79 0 0 0 1 0 0 0
[10:30:59.820] INFO: enter test to run
[10:31:26.076] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:31:26.076] INFO: running: highrate
[10:31:26.076] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/phCalibrationFitErr35_C15.dat
[10:31:26.321] INFO: ----------------------------------------------------------------------
[10:31:26.321] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:31:26.321] INFO: ----------------------------------------------------------------------
[10:31:26.321] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:31:26.321] INFO: edge/corner pixel THR is adjusted
[10:31:26.321] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:31:27.280] INFO: Collecting data for 5 seconds...
[10:31:32.297] INFO: Done with hot pixel readout
[10:31:43.038] INFO: PixTest:: pg_setup set to default.
[10:31:43.038] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:31:43.038] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:31:43.039] INFO: 86 hot pixels found in step 0
[10:31:44.029] INFO: Collecting data for 5 seconds...
[10:31:49.045] INFO: Done with hot pixel readout
[10:32:01.117] INFO: PixTest:: pg_setup set to default.
[10:32:01.118] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:32:01.118] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:32:01.118] INFO: 64 hot pixels found in step 1
[10:32:02.113] INFO: Collecting data for 5 seconds...
[10:32:07.130] INFO: Done with hot pixel readout
[10:32:19.214] INFO: PixTest:: pg_setup set to default.
[10:32:19.214] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:32:19.214] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:32:19.214] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:32:19.214] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:32:19.215] INFO: 59 hot pixels found in step 2
[10:32:20.209] INFO: Collecting data for 5 seconds...
[10:32:25.225] INFO: Done with hot pixel readout
[10:32:37.313] INFO: PixTest:: pg_setup set to default.
[10:32:37.313] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:32:37.314] INFO: 60 hot pixels found in step 3
[10:32:38.309] INFO: Collecting data for 5 seconds...
[10:32:43.325] INFO: Done with hot pixel readout
[10:32:55.404] INFO: PixTest:: pg_setup set to default.
[10:32:55.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:32:55.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:32:55.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:32:55.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:32:55.404] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:32:55.405] INFO: 60 hot pixels found in step 4
[10:32:56.400] INFO: Collecting data for 5 seconds...
[10:33:01.416] INFO: Done with hot pixel readout
[10:33:13.502] INFO: PixTest:: pg_setup set to default.
[10:33:13.502] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:13.503] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:13.503] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:13.503] INFO: 46 hot pixels found in step 5
[10:33:14.497] INFO: Collecting data for 5 seconds...
[10:33:19.513] INFO: Done with hot pixel readout
[10:33:31.588] INFO: PixTest:: pg_setup set to default.
[10:33:31.588] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:31.588] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:31.589] INFO: 59 hot pixels found in step 6
[10:33:32.583] INFO: Collecting data for 5 seconds...
[10:33:37.599] INFO: Done with hot pixel readout
[10:33:49.560] INFO: PixTest:: pg_setup set to default.
[10:33:49.560] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:49.560] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:49.560] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:49.560] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:49.561] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:33:49.561] INFO: 53 hot pixels found in step 7
[10:33:50.556] INFO: Collecting data for 5 seconds...
[10:33:55.572] INFO: Done with hot pixel readout
[10:34:07.722] INFO: PixTest:: pg_setup set to default.
[10:34:07.723] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:07.723] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:07.723] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:07.723] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:07.723] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:07.723] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:07.723] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:07.723] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:07.723] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:07.724] INFO: 57 hot pixels found in step 8
[10:34:08.718] INFO: Collecting data for 5 seconds...
[10:34:13.734] INFO: Done with hot pixel readout
[10:34:24.076] INFO: PixTest:: pg_setup set to default.
[10:34:24.077] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:24.077] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:24.077] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:24.077] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:24.077] INFO: 45 hot pixels found in step 9
[10:34:25.072] INFO: Collecting data for 5 seconds...
[10:34:30.088] INFO: Done with hot pixel readout
[10:34:42.121] INFO: PixTest:: pg_setup set to default.
[10:34:42.121] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:42.122] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:42.122] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:42.122] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:42.122] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:42.122] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:42.122] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:34:42.122] INFO: 40 hot pixels found in step 10
[10:34:43.117] INFO: Collecting data for 5 seconds...
[10:34:48.133] INFO: Done with hot pixel readout
[10:35:00.192] INFO: PixTest:: pg_setup set to default.
[10:35:00.192] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:00.193] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:00.193] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:00.193] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:00.193] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:00.193] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:00.193] INFO: 38 hot pixels found in step 11
[10:35:01.188] INFO: Collecting data for 5 seconds...
[10:35:06.204] INFO: Done with hot pixel readout
[10:35:18.276] INFO: PixTest:: pg_setup set to default.
[10:35:18.276] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:18.276] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:18.277] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:18.277] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:18.277] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:18.277] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:18.277] INFO: 36 hot pixels found in step 12
[10:35:19.272] INFO: Collecting data for 5 seconds...
[10:35:24.287] INFO: Done with hot pixel readout
[10:35:36.364] INFO: PixTest:: pg_setup set to default.
[10:35:36.364] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:36.365] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:36.365] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:36.365] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:36.365] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:36.365] INFO: 34 hot pixels found in step 13
[10:35:37.361] INFO: Collecting data for 5 seconds...
[10:35:42.377] INFO: Done with hot pixel readout
[10:35:54.373] INFO: PixTest:: pg_setup set to default.
[10:35:54.373] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:54.373] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:54.373] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:54.373] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:54.373] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:54.373] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[10:35:54.374] INFO: 34 hot pixels found in step 14
[10:35:54.413] INFO: 34 hot pixels could not be trimmed and have been masked.
[10:35:54.416] INFO: PixTest::trimHotPixels() done
[10:35:54.416] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C0.dat
[10:35:54.428] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C1.dat
[10:35:54.436] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C2.dat
[10:35:54.444] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C3.dat
[10:35:54.451] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C4.dat
[10:35:54.458] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C5.dat
[10:35:54.465] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C6.dat
[10:35:54.473] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C7.dat
[10:35:54.480] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C8.dat
[10:35:54.487] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C9.dat
[10:35:54.494] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C10.dat
[10:35:54.502] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C11.dat
[10:35:54.509] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C12.dat
[10:35:54.516] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C13.dat
[10:35:54.524] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C14.dat
[10:35:54.531] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C15.dat
[10:35:54.549] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/defaultMaskFile.dat
[10:35:54.560] INFO: enter test to run
[10:36:20.753] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:36:20.754] INFO: running: highrate
[10:36:20.758] INFO: ----------------------------------------------------------------------
[10:36:20.758] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:36:20.758] INFO: ----------------------------------------------------------------------
[10:36:20.758] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:36:20.758] INFO: edge/corner pixel THR is adjusted
[10:36:20.758] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:36:21.717] INFO: Collecting data for 1 seconds...
[10:36:22.720] INFO: Done with hot pixel readout
[10:36:26.732] INFO: PixTest:: pg_setup set to default.
[10:36:26.733] INFO: 0 hot pixels found in step 0
[10:36:26.738] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:36:26.829] INFO: PixTest::trimHotPixels() done
[10:36:26.830] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C0.dat
[10:36:26.841] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C1.dat
[10:36:26.849] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C2.dat
[10:36:26.856] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C3.dat
[10:36:26.863] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C4.dat
[10:36:26.870] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C5.dat
[10:36:26.878] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C6.dat
[10:36:26.885] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C7.dat
[10:36:26.892] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C8.dat
[10:36:26.900] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C9.dat
[10:36:26.907] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C10.dat
[10:36:26.914] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C11.dat
[10:36:26.922] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C12.dat
[10:36:26.929] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C13.dat
[10:36:26.936] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C14.dat
[10:36:26.944] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/trimParameters35_C15.dat
[10:36:26.951] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-1-49_FPIXTest-17C-FNAL-160328-0918_2016-03-28_09h18m_1459174687/000_FPIXTest_p17/defaultMaskFile.dat
[10:36:26.961] INFO: enter test to run
[10:36:49.097] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:36:49.097] INFO: running: xray
[10:36:49.099] INFO: ----------------------------------------------------------------------
[10:36:49.099] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:36:49.099] INFO: ----------------------------------------------------------------------
[10:36:50.076] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:37:01.227] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:37:30.883] INFO: Resuming triggers.
[10:37:42.032] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[10:38:11.510] INFO: Resuming triggers.
[10:38:22.662] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[10:38:52.282] INFO: Resuming triggers.
[10:39:03.432] INFO: run duration 44 seconds, buffer almost full (81%), pausing triggers.
[10:39:32.974] INFO: Resuming triggers.
[10:39:44.124] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[10:40:13.598] INFO: Resuming triggers.
[10:40:24.750] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[10:40:54.366] INFO: Resuming triggers.
[10:41:05.520] INFO: run duration 77 seconds, buffer almost full (81%), pausing triggers.
[10:41:35.018] INFO: Resuming triggers.
[10:41:46.171] INFO: run duration 88 seconds, buffer almost full (81%), pausing triggers.
[10:42:14.435] INFO: Resuming triggers.
[10:42:25.543] INFO: data taking finished, elapsed time: 100 seconds.
[10:42:55.118] INFO: PixTest:: pg_setup set to default.
[10:42:55.121] INFO: PixTestXray::doPhRun() done
[10:42:55.262] INFO: enter test to run
[10:43:27.438] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:43:27.438] INFO: running: xray
[10:43:27.440] INFO: ----------------------------------------------------------------------
[10:43:27.440] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:43:27.440] INFO: ----------------------------------------------------------------------
[10:43:28.409] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:43:35.013] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:44:05.216] INFO: Resuming triggers.
[10:44:11.824] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[10:44:40.323] INFO: Resuming triggers.
[10:44:46.929] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[10:45:17.101] INFO: Resuming triggers.
[10:45:23.708] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[10:45:53.849] INFO: Resuming triggers.
[10:46:00.452] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[10:46:30.545] INFO: Resuming triggers.
[10:46:37.152] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[10:47:06.294] INFO: Resuming triggers.
[10:47:12.899] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:47:43.049] INFO: Resuming triggers.
[10:47:49.651] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[10:48:19.120] INFO: Resuming triggers.
[10:48:25.724] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[10:48:55.876] INFO: Resuming triggers.
[10:49:02.478] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[10:49:32.633] INFO: Resuming triggers.
[10:49:39.236] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[10:50:09.384] INFO: Resuming triggers.
[10:50:15.989] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[10:50:46.166] INFO: Resuming triggers.
[10:50:52.770] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[10:51:22.834] INFO: Resuming triggers.
[10:51:29.438] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[10:51:58.585] INFO: Resuming triggers.
[10:52:05.187] INFO: run duration 98 seconds, buffer almost full (81%), pausing triggers.
[10:52:35.362] INFO: Resuming triggers.
[10:52:36.612] INFO: data taking finished, elapsed time: 100 seconds.
[10:52:42.666] INFO: PixTest:: pg_setup set to default.
[10:52:42.669] INFO: PixTestXray::doPhRun() done
[10:52:42.820] INFO: enter test to run
[10:53:26.506] INFO: test: HighRate no parameter change
[10:53:26.506] INFO: running: highrate
[10:53:26.507] INFO: ----------------------------------------------------------------------
[10:53:26.507] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:53:26.507] INFO: ----------------------------------------------------------------------
[10:53:26.648] INFO: Expecting 768 events.
[10:53:27.782] INFO: 768 events read in total (419ms).
[10:53:27.782] INFO: Test took 1268ms.
[10:53:28.585] INFO: Expecting 41600 events.
[10:53:31.694] INFO: 41600 events read in total (2582ms).
[10:53:31.695] INFO: Test took 3905ms.
[10:53:31.730] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:32.455] INFO: Expecting 41600 events.
[10:53:35.668] INFO: 41600 events read in total (2686ms).
[10:53:35.669] INFO: Test took 3920ms.
[10:53:35.703] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:36.426] INFO: Expecting 41600 events.
[10:53:39.676] INFO: 41600 events read in total (2723ms).
[10:53:39.677] INFO: Test took 3957ms.
[10:53:39.713] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:40.432] INFO: Expecting 41600 events.
[10:53:43.683] INFO: 41600 events read in total (2724ms).
[10:53:43.684] INFO: Test took 3952ms.
[10:53:43.719] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:44.439] INFO: Expecting 41600 events.
[10:53:47.657] INFO: 41600 events read in total (2691ms).
[10:53:47.658] INFO: Test took 3920ms.
[10:53:47.694] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:48.415] INFO: Expecting 41600 events.
[10:53:51.674] INFO: 41600 events read in total (2732ms).
[10:53:51.675] INFO: Test took 3964ms.
[10:53:51.710] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:52.429] INFO: Expecting 41600 events.
[10:53:55.683] INFO: 41600 events read in total (2728ms).
[10:53:55.683] INFO: Test took 3955ms.
[10:53:55.719] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:53:56.437] INFO: Expecting 41600 events.
[10:53:59.681] INFO: 41600 events read in total (2717ms).
[10:53:59.682] INFO: Test took 3944ms.
[10:53:59.717] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:00.440] INFO: Expecting 41600 events.
[10:54:03.692] INFO: 41600 events read in total (2725ms).
[10:54:03.693] INFO: Test took 3957ms.
[10:54:03.728] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:04.452] INFO: Expecting 41600 events.
[10:54:07.706] INFO: 41600 events read in total (2727ms).
[10:54:07.707] INFO: Test took 3960ms.
[10:54:07.743] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:08.461] INFO: Expecting 41600 events.
[10:54:11.682] INFO: 41600 events read in total (2694ms).
[10:54:11.683] INFO: Test took 3920ms.
[10:54:11.718] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:12.441] INFO: Expecting 41600 events.
[10:54:15.700] INFO: 41600 events read in total (2732ms).
[10:54:15.701] INFO: Test took 3964ms.
[10:54:15.737] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:16.458] INFO: Expecting 41600 events.
[10:54:19.721] INFO: 41600 events read in total (2736ms).
[10:54:19.722] INFO: Test took 3966ms.
[10:54:19.757] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:20.479] INFO: Expecting 41600 events.
[10:54:23.729] INFO: 41600 events read in total (2723ms).
[10:54:23.730] INFO: Test took 3954ms.
[10:54:23.766] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:24.488] INFO: Expecting 41600 events.
[10:54:27.739] INFO: 41600 events read in total (2724ms).
[10:54:27.740] INFO: Test took 3954ms.
[10:54:27.776] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:28.499] INFO: Expecting 41600 events.
[10:54:31.604] INFO: 41600 events read in total (2578ms).
[10:54:31.605] INFO: Test took 3809ms.
[10:54:31.640] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:32.365] INFO: Expecting 41600 events.
[10:54:35.189] INFO: 41600 events read in total (2298ms).
[10:54:35.190] INFO: Test took 3532ms.
[10:54:35.226] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:35.952] INFO: Expecting 41600 events.
[10:54:39.219] INFO: 41600 events read in total (2740ms).
[10:54:39.220] INFO: Test took 3975ms.
[10:54:39.257] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:39.973] INFO: Expecting 41600 events.
[10:54:43.238] INFO: 41600 events read in total (2738ms).
[10:54:43.239] INFO: Test took 3963ms.
[10:54:43.278] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:43.994] INFO: Expecting 41600 events.
[10:54:47.080] INFO: 41600 events read in total (2559ms).
[10:54:47.081] INFO: Test took 3785ms.
[10:54:47.115] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:54:47.477] INFO: enter test to run
[10:55:54.377] INFO: test: HighRate no parameter change
[10:55:54.377] INFO: running: highrate
[10:55:54.378] INFO: ----------------------------------------------------------------------
[10:55:54.378] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:55:54.378] INFO: ----------------------------------------------------------------------
[10:55:54.994] INFO: Expecting 208000 events.
[10:56:06.020] INFO: 208000 events read in total (10499ms).
[10:56:06.024] INFO: Test took 11636ms.
[10:56:06.171] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:56:06.426] INFO: number of dead pixels (per ROC): 160 0 0 0 1 0 0 0 79 0 0 0 0 0 0 0
[10:56:06.426] INFO: number of red-efficiency pixels: 252 66 99 169 178 200 178 128 187 148 155 95 152 67 34 53
[10:56:06.426] INFO: number of X-ray hits detected: 63009 44325 73892 114947 124084 126037 123884 73306 69668 108630 108947 86657 92662 55813 24491 29276
[10:56:06.426] 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:56:06.426] INFO: number of Vcal hits detected: 199906 207933 207900 207826 207768 207791 207814 207863 203892 207836 207841 207905 207844 207933 207966 207946
[10:56:06.426] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 100.0 99.9 100.0 100.0 100.0
[10:56:06.426] INFO: Vcal hit overall efficiency (%): 96.1 100.0 100.0 99.9 99.9 99.9 99.9 99.9 98.0 99.9 99.9 100.0 99.9 100.0 100.0 100.0
[10:56:06.426] INFO: X-ray hit rate [MHz/cm2]: 18.5 13.0 21.7 33.7 36.4 36.9 36.3 21.5 20.4 31.8 31.9 25.4 27.2 16.4 7.2 8.6
[10:56:06.426] INFO: PixTestHighRate::doXPixelAlive() done
[10:56:06.475] INFO: PixTest:: pg_setup set to default.
[10:56:06.492] INFO: enter test to run
[10:56:54.624] INFO: test: HighRate no parameter change
[10:56:54.624] INFO: running: highrate
[10:56:54.626] INFO: ----------------------------------------------------------------------
[10:56:54.626] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:56:54.626] INFO: ----------------------------------------------------------------------
[10:56:55.244] INFO: Expecting 208000 events.
[10:57:08.973] INFO: 208000 events read in total (13202ms).
[10:57:08.978] INFO: Test took 14345ms.
[10:57:09.282] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:57:09.584] INFO: number of dead pixels (per ROC): 160 0 0 0 1 0 0 0 79 0 0 0 0 0 0 0
[10:57:09.584] INFO: number of red-efficiency pixels: 403 130 251 489 538 702 643 363 384 463 418 279 368 203 72 77
[10:57:09.584] INFO: number of X-ray hits detected: 128607 90392 150793 233769 250953 255301 250711 148493 142297 220065 220956 176049 187499 113561 49465 58753
[10:57:09.584] 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:57:09.584] INFO: number of Vcal hits detected: 199736 207862 207732 207465 207345 207190 207274 207594 203683 207432 207551 207706 207609 207788 207926 207921
[10:57:09.584] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.7 99.6 99.7 99.8 99.8 99.7 99.8 99.9 99.8 99.9 100.0 100.0
[10:57:09.584] INFO: Vcal hit overall efficiency (%): 96.0 99.9 99.9 99.7 99.7 99.6 99.7 99.8 97.9 99.7 99.8 99.9 99.8 99.9 100.0 100.0
[10:57:09.584] INFO: X-ray hit rate [MHz/cm2]: 37.7 26.5 44.2 68.5 73.6 74.8 73.5 43.5 41.7 64.5 64.8 51.6 55.0 33.3 14.5 17.2
[10:57:09.584] INFO: PixTestHighRate::doXPixelAlive() done
[10:57:09.632] INFO: PixTest:: pg_setup set to default.
[10:57:09.646] INFO: enter test to run
[10:57:50.704] INFO: test: HighRate no parameter change
[10:57:50.704] INFO: running: highrate
[10:57:50.705] INFO: ----------------------------------------------------------------------
[10:57:50.705] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:57:50.705] INFO: ----------------------------------------------------------------------
[10:57:51.322] INFO: Expecting 208000 events.
[10:58:06.742] INFO: 208000 events read in total (14893ms).
[10:58:06.749] INFO: Test took 16035ms.
[10:58:07.185] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:58:07.534] INFO: number of dead pixels (per ROC): 160 0 0 0 1 0 0 0 78 0 0 0 0 0 0 0
[10:58:07.534] INFO: number of red-efficiency pixels: 634 227 531 1010 1088 1339 1359 709 674 892 844 526 785 310 118 133
[10:58:07.534] INFO: number of X-ray hits detected: 186754 130840 218505 337899 365959 371422 364223 214582 207470 321151 322404 255592 272519 163106 72015 85972
[10:58:07.534] 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:58:07.534] INFO: number of Vcal hits detected: 199458 207751 207372 206768 206569 206150 206167 207038 203323 206843 207033 207411 207091 207656 207880 207865
[10:58:07.534] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.5 99.4 99.2 99.2 99.6 99.7 99.5 99.6 99.8 99.6 99.8 99.9 99.9
[10:58:07.534] INFO: Vcal hit overall efficiency (%): 95.9 99.9 99.7 99.4 99.3 99.1 99.1 99.5 97.8 99.4 99.5 99.7 99.6 99.8 99.9 99.9
[10:58:07.534] INFO: X-ray hit rate [MHz/cm2]: 54.7 38.4 64.0 99.0 107.3 108.9 106.8 62.9 60.8 94.1 94.5 74.9 79.9 47.8 21.1 25.2
[10:58:07.534] INFO: PixTestHighRate::doXPixelAlive() done
[10:58:07.581] INFO: PixTest:: pg_setup set to default.
[10:58:07.595] INFO: enter test to run
[10:58:13.744] INFO: test: exit no parameter change
[10:58:14.002] QUIET: Connection to board 33 closed.
[10:58:14.012] INFO: pXar: this is the end, my friend