[10:16:46.658] INFO: *** Welcome to pxar ***
[10:16:46.658] INFO: *** Today: 2016/06/10
[10:16:47.496] INFO: *** Version: v1.9.0-814-g7497
[10:16:47.496] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//dacParameters35_C15.dat
[10:16:47.562] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//tbmParameters_C0b.dat
[10:16:47.563] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//defaultMaskFile.dat
[10:16:47.569] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C15.dat
[10:16:47.706] INFO: clk: 4
[10:16:47.706] INFO: ctr: 4
[10:16:47.706] INFO: sda: 19
[10:16:47.706] INFO: tin: 9
[10:16:47.706] INFO: level: 15
[10:16:47.706] INFO: triggerdelay: 0
[10:16:47.707] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[10:16:47.707] INFO: Log level: INFO
[10:16:47.725] QUIET: Connection to board DTB_WREKRL opened.
[10:16:47.729] 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:16:47.732] INFO: RPC call hashes of host and DTB match: 398089610
[10:16:49.270] INFO: DUT info:
[10:16:49.270] INFO: The DUT currently contains the following objects:
[10:16:49.270] INFO: 2 TBM Cores tbm08c (2 ON)
[10:16:49.282] INFO: TBM Core alpha (0): 7 registers set
[10:16:49.282] INFO: TBM Core beta (1): 7 registers set
[10:16:49.282] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[10:16:49.282] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:49.282] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:49.282] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:49.282] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:49.282] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:49.282] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:49.282] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:49.283] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:49.283] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:49.283] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:49.283] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:49.283] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:49.283] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:49.283] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:49.283] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:49.283] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[10:16:49.726] INFO: enter 'restricted' command line mode
[10:16:49.726] INFO: enter test to run
[10:16:56.282] INFO: test: PixelAlive no parameter change
[10:16:56.282] INFO: running: pixelalive
[10:16:56.351] INFO: ----------------------------------------------------------------------
[10:16:56.351] 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:16:56.351] INFO: ----------------------------------------------------------------------
[10:16:56.667] INFO: Expecting 41600 events.
[10:17:00.848] INFO: 41600 events read in total (3462ms).
[10:17:01.014] INFO: Test took 4659ms.
[10:17:01.025] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:17:01.574] INFO: PixTestAlive::aliveTest() done
[10:17:01.574] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 2 0 0 0 0 0 0
[10:17:01.693] INFO: enter test to run
[10:17:08.874] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:17:08.874] INFO: running: highrate
[10:17:08.883] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[10:17:09.145] INFO: ----------------------------------------------------------------------
[10:17:09.145] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:17:09.145] INFO: ----------------------------------------------------------------------
[10:17:09.145] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:17:09.145] INFO: edge/corner pixel THR is adjusted
[10:17:09.145] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:17:10.130] INFO: Collecting data for 5 seconds...
[10:17:15.146] INFO: Done with hot pixel readout
[10:17:23.134] INFO: PixTest:: pg_setup set to default.
[10:17:23.135] INFO: 0 hot pixels found in step 0
[10:17:23.138] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:17:23.314] INFO: PixTest::trimHotPixels() done
[10:17:23.314] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C0.dat
[10:17:23.324] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C1.dat
[10:17:23.332] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C2.dat
[10:17:23.337] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C3.dat
[10:17:23.342] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C4.dat
[10:17:23.348] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C5.dat
[10:17:23.353] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C6.dat
[10:17:23.359] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C7.dat
[10:17:23.373] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C8.dat
[10:17:23.378] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C9.dat
[10:17:23.384] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C10.dat
[10:17:23.389] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C11.dat
[10:17:23.394] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C12.dat
[10:17:23.400] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C13.dat
[10:17:23.405] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C14.dat
[10:17:23.410] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C15.dat
[10:17:23.416] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//defaultMaskFile.dat
[10:17:23.421] INFO: enter test to run
[10:17:49.754] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[10:17:49.754] INFO: running: highrate
[10:17:49.758] INFO: ----------------------------------------------------------------------
[10:17:49.758] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[10:17:49.758] INFO: ----------------------------------------------------------------------
[10:17:49.758] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[10:17:49.758] INFO: edge/corner pixel THR is adjusted
[10:17:49.758] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:17:50.716] INFO: Collecting data for 5 seconds...
[10:17:55.731] INFO: Done with hot pixel readout
[10:18:06.281] INFO: PixTest:: pg_setup set to default.
[10:18:06.282] INFO: 7 hot pixels found in step 0
[10:18:07.275] INFO: Collecting data for 5 seconds...
[10:18:12.290] INFO: Done with hot pixel readout
[10:18:23.047] INFO: PixTest:: pg_setup set to default.
[10:18:23.048] INFO: 10 hot pixels found in step 1
[10:18:24.039] INFO: Collecting data for 5 seconds...
[10:18:29.054] INFO: Done with hot pixel readout
[10:18:40.303] INFO: PixTest:: pg_setup set to default.
[10:18:40.304] INFO: 9 hot pixels found in step 2
[10:18:41.295] INFO: Collecting data for 5 seconds...
[10:18:46.310] INFO: Done with hot pixel readout
[10:18:57.561] INFO: PixTest:: pg_setup set to default.
[10:18:57.562] INFO: 7 hot pixels found in step 3
[10:18:58.553] INFO: Collecting data for 5 seconds...
[10:19:03.569] INFO: Done with hot pixel readout
[10:19:14.508] INFO: PixTest:: pg_setup set to default.
[10:19:14.509] INFO: 6 hot pixels found in step 4
[10:19:15.502] INFO: Collecting data for 5 seconds...
[10:19:20.520] INFO: Done with hot pixel readout
[10:19:31.423] INFO: PixTest:: pg_setup set to default.
[10:19:31.423] INFO: 4 hot pixels found in step 5
[10:19:32.417] INFO: Collecting data for 5 seconds...
[10:19:37.433] INFO: Done with hot pixel readout
[10:19:48.092] INFO: PixTest:: pg_setup set to default.
[10:19:48.093] INFO: 8 hot pixels found in step 6
[10:19:49.085] INFO: Collecting data for 5 seconds...
[10:19:54.100] INFO: Done with hot pixel readout
[10:20:04.733] INFO: PixTest:: pg_setup set to default.
[10:20:04.734] INFO: 1 hot pixels found in step 7
[10:20:05.727] INFO: Collecting data for 5 seconds...
[10:20:10.744] INFO: Done with hot pixel readout
[10:20:20.887] INFO: PixTest:: pg_setup set to default.
[10:20:20.887] INFO: 3 hot pixels found in step 8
[10:20:21.879] INFO: Collecting data for 5 seconds...
[10:20:26.898] INFO: Done with hot pixel readout
[10:20:37.985] INFO: PixTest:: pg_setup set to default.
[10:20:37.986] INFO: 1 hot pixels found in step 9
[10:20:38.979] INFO: Collecting data for 5 seconds...
[10:20:43.995] INFO: Done with hot pixel readout
[10:20:54.970] INFO: PixTest:: pg_setup set to default.
[10:20:54.972] INFO: 2 hot pixels found in step 10
[10:20:55.975] INFO: Collecting data for 5 seconds...
[10:21:00.992] INFO: Done with hot pixel readout
[10:21:11.847] INFO: PixTest:: pg_setup set to default.
[10:21:11.848] INFO: 2 hot pixels found in step 11
[10:21:12.848] INFO: Collecting data for 5 seconds...
[10:21:17.865] INFO: Done with hot pixel readout
[10:21:28.475] INFO: PixTest:: pg_setup set to default.
[10:21:28.476] INFO: 6 hot pixels found in step 12
[10:21:29.467] INFO: Collecting data for 5 seconds...
[10:21:34.484] INFO: Done with hot pixel readout
[10:21:45.998] INFO: PixTest:: pg_setup set to default.
[10:21:45.999] INFO: 2 hot pixels found in step 13
[10:21:46.991] INFO: Collecting data for 5 seconds...
[10:21:52.007] INFO: Done with hot pixel readout
[10:22:03.181] INFO: PixTest:: pg_setup set to default.
[10:22:03.182] INFO: 3 hot pixels found in step 14
[10:22:03.219] INFO: 3 hot pixels could not be trimmed and have been masked.
[10:22:03.222] INFO: PixTest::trimHotPixels() done
[10:22:03.222] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C0.dat
[10:22:03.228] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C1.dat
[10:22:03.234] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C2.dat
[10:22:03.239] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C3.dat
[10:22:03.245] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C4.dat
[10:22:03.250] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C5.dat
[10:22:03.255] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C6.dat
[10:22:03.261] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C7.dat
[10:22:03.266] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C8.dat
[10:22:03.271] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C9.dat
[10:22:03.277] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C10.dat
[10:22:03.282] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C11.dat
[10:22:03.288] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C12.dat
[10:22:03.293] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C13.dat
[10:22:03.298] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C14.dat
[10:22:03.304] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C15.dat
[10:22:03.309] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//defaultMaskFile.dat
[10:22:03.319] INFO: enter test to run
[10:22:30.599] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[10:22:30.600] INFO: running: highrate
[10:22:30.605] INFO: ----------------------------------------------------------------------
[10:22:30.605] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[10:22:30.605] INFO: ----------------------------------------------------------------------
[10:22:30.605] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[10:22:30.605] INFO: edge/corner pixel THR is adjusted
[10:22:30.605] INFO: PixTestHighRate::trimHotPixels: step 0...
[10:22:31.562] INFO: Collecting data for 1 seconds...
[10:22:32.567] INFO: Done with hot pixel readout
[10:22:36.681] INFO: PixTest:: pg_setup set to default.
[10:22:36.681] INFO: 0 hot pixels found in step 0
[10:22:36.687] INFO: 0 hot pixels could not be trimmed and have been masked.
[10:22:36.763] INFO: PixTest::trimHotPixels() done
[10:22:36.768] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C0.dat
[10:22:36.774] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C1.dat
[10:22:36.779] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C2.dat
[10:22:36.784] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C3.dat
[10:22:36.790] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C4.dat
[10:22:36.795] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C5.dat
[10:22:36.801] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C6.dat
[10:22:36.806] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C7.dat
[10:22:36.811] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C8.dat
[10:22:36.816] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C9.dat
[10:22:36.822] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C10.dat
[10:22:36.827] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C11.dat
[10:22:36.833] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C12.dat
[10:22:36.838] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C13.dat
[10:22:36.843] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C14.dat
[10:22:36.849] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//trimParameters35_C15.dat
[10:22:36.854] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-I-3-20_FPIXTest-17C-FNAL-160607-1308_2016-06-07_13h08m_1465322892/000_FPIXTest_p17//defaultMaskFile.dat
[10:22:36.864] INFO: enter test to run
[10:22:54.231] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[10:22:54.231] INFO: running: xray
[10:22:54.244] INFO: ----------------------------------------------------------------------
[10:22:54.244] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:22:54.244] INFO: ----------------------------------------------------------------------
[10:22:55.225] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:23:06.780] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[10:23:36.705] INFO: Resuming triggers.
[10:23:48.261] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[10:24:18.162] INFO: Resuming triggers.
[10:24:29.712] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[10:24:59.059] INFO: Resuming triggers.
[10:25:10.610] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:25:39.005] INFO: Resuming triggers.
[10:25:51.559] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[10:26:20.918] INFO: Resuming triggers.
[10:26:32.473] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[10:27:01.921] INFO: Resuming triggers.
[10:27:13.479] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[10:27:42.872] INFO: Resuming triggers.
[10:27:54.427] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[10:28:23.703] INFO: Resuming triggers.
[10:28:31.611] INFO: data taking finished, elapsed time: 100 seconds.
[10:28:51.903] INFO: PixTest:: pg_setup set to default.
[10:28:51.906] INFO: PixTestXray::doPhRun() done
[10:28:52.076] INFO: enter test to run
[10:38:27.264] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[10:38:27.265] INFO: running: xray
[10:38:27.266] INFO: ----------------------------------------------------------------------
[10:38:27.266] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[10:38:27.266] INFO: ----------------------------------------------------------------------
[10:38:28.229] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[10:38:34.896] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[10:39:05.053] INFO: Resuming triggers.
[10:39:11.722] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[10:39:41.475] INFO: Resuming triggers.
[10:39:48.150] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[10:40:18.161] INFO: Resuming triggers.
[10:40:24.829] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[10:40:54.876] INFO: Resuming triggers.
[10:41:01.544] INFO: run duration 33 seconds, buffer almost full (81%), pausing triggers.
[10:41:31.574] INFO: Resuming triggers.
[10:41:38.247] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[10:42:08.143] INFO: Resuming triggers.
[10:42:14.811] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[10:42:44.895] INFO: Resuming triggers.
[10:42:51.563] INFO: run duration 53 seconds, buffer almost full (81%), pausing triggers.
[10:43:21.684] INFO: Resuming triggers.
[10:43:28.359] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[10:43:59.111] INFO: Resuming triggers.
[10:44:05.782] INFO: run duration 66 seconds, buffer almost full (81%), pausing triggers.
[10:44:36.908] INFO: Resuming triggers.
[10:44:43.579] INFO: run duration 73 seconds, buffer almost full (81%), pausing triggers.
[10:45:14.451] INFO: Resuming triggers.
[10:45:21.127] INFO: run duration 79 seconds, buffer almost full (81%), pausing triggers.
[10:45:52.768] INFO: Resuming triggers.
[10:45:59.442] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[10:46:31.050] INFO: Resuming triggers.
[10:46:37.723] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[10:47:08.601] INFO: Resuming triggers.
[10:47:15.276] INFO: run duration 99 seconds, buffer almost full (81%), pausing triggers.
[10:47:45.415] INFO: Resuming triggers.
[10:47:45.698] INFO: data taking finished, elapsed time: 100 seconds.
[10:47:47.362] INFO: PixTest:: pg_setup set to default.
[10:47:47.366] INFO: PixTestXray::doPhRun() done
[10:47:47.512] INFO: enter test to run
[10:48:51.364] INFO: test: HighRate no parameter change
[10:48:51.364] INFO: running: highrate
[10:48:51.365] INFO: ----------------------------------------------------------------------
[10:48:51.365] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[10:48:51.365] INFO: ----------------------------------------------------------------------
[10:48:51.506] INFO: Expecting 768 events.
[10:48:52.640] INFO: 768 events read in total (419ms).
[10:48:52.640] INFO: Test took 1269ms.
[10:48:53.443] INFO: Expecting 41600 events.
[10:48:56.511] INFO: 41600 events read in total (2541ms).
[10:48:56.512] INFO: Test took 3865ms.
[10:48:56.545] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:48:57.272] INFO: Expecting 41600 events.
[10:49:00.448] INFO: 41600 events read in total (2649ms).
[10:49:00.449] INFO: Test took 3888ms.
[10:49:00.482] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:01.206] INFO: Expecting 41600 events.
[10:49:04.422] INFO: 41600 events read in total (2690ms).
[10:49:04.423] INFO: Test took 3924ms.
[10:49:04.458] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:05.181] INFO: Expecting 41600 events.
[10:49:08.419] INFO: 41600 events read in total (2711ms).
[10:49:08.420] INFO: Test took 3943ms.
[10:49:08.454] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:09.178] INFO: Expecting 41600 events.
[10:49:12.428] INFO: 41600 events read in total (2723ms).
[10:49:12.429] INFO: Test took 3957ms.
[10:49:12.463] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:13.186] INFO: Expecting 41600 events.
[10:49:16.412] INFO: 41600 events read in total (2699ms).
[10:49:16.413] INFO: Test took 3931ms.
[10:49:16.447] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:17.171] INFO: Expecting 41600 events.
[10:49:20.411] INFO: 41600 events read in total (2713ms).
[10:49:20.412] INFO: Test took 3946ms.
[10:49:20.451] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:21.172] INFO: Expecting 41600 events.
[10:49:24.417] INFO: 41600 events read in total (2719ms).
[10:49:24.418] INFO: Test took 3948ms.
[10:49:24.452] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:25.177] INFO: Expecting 41600 events.
[10:49:28.413] INFO: 41600 events read in total (2709ms).
[10:49:28.414] INFO: Test took 3945ms.
[10:49:28.447] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:29.172] INFO: Expecting 41600 events.
[10:49:32.396] INFO: 41600 events read in total (2697ms).
[10:49:32.397] INFO: Test took 3931ms.
[10:49:32.431] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:33.157] INFO: Expecting 41600 events.
[10:49:36.405] INFO: 41600 events read in total (2721ms).
[10:49:36.406] INFO: Test took 3956ms.
[10:49:36.441] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:37.166] INFO: Expecting 41600 events.
[10:49:40.411] INFO: 41600 events read in total (2718ms).
[10:49:40.412] INFO: Test took 3952ms.
[10:49:40.445] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:41.167] INFO: Expecting 41600 events.
[10:49:44.417] INFO: 41600 events read in total (2723ms).
[10:49:44.418] INFO: Test took 3955ms.
[10:49:44.461] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:45.172] INFO: Expecting 41600 events.
[10:49:48.414] INFO: 41600 events read in total (2715ms).
[10:49:48.415] INFO: Test took 3934ms.
[10:49:48.449] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:49.170] INFO: Expecting 41600 events.
[10:49:52.414] INFO: 41600 events read in total (2717ms).
[10:49:52.415] INFO: Test took 3947ms.
[10:49:52.450] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:53.175] INFO: Expecting 41600 events.
[10:49:56.419] INFO: 41600 events read in total (2717ms).
[10:49:56.420] INFO: Test took 3952ms.
[10:49:56.454] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:49:57.177] INFO: Expecting 41600 events.
[10:50:00.406] INFO: 41600 events read in total (2702ms).
[10:50:00.407] INFO: Test took 3935ms.
[10:50:00.440] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:01.165] INFO: Expecting 41600 events.
[10:50:04.403] INFO: 41600 events read in total (2711ms).
[10:50:04.404] INFO: Test took 3946ms.
[10:50:04.438] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:05.164] INFO: Expecting 41600 events.
[10:50:08.393] INFO: 41600 events read in total (2702ms).
[10:50:08.394] INFO: Test took 3938ms.
[10:50:08.427] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:09.150] INFO: Expecting 41600 events.
[10:50:12.232] INFO: 41600 events read in total (2555ms).
[10:50:12.233] INFO: Test took 3788ms.
[10:50:12.267] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:12.630] INFO: enter test to run
[10:50:34.515] INFO: test: HighRate no parameter change
[10:50:34.515] INFO: running: highrate
[10:50:34.516] INFO: ----------------------------------------------------------------------
[10:50:34.516] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:50:34.516] INFO: ----------------------------------------------------------------------
[10:50:35.136] INFO: Expecting 208000 events.
[10:50:46.895] INFO: 208000 events read in total (11232ms).
[10:50:46.898] INFO: Test took 12372ms.
[10:50:47.035] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:50:47.285] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 2 0 0 0 0 0 0
[10:50:47.285] INFO: number of red-efficiency pixels: 88 51 79 116 107 131 159 113 107 136 142 140 194 397 407 979
[10:50:47.285] INFO: number of X-ray hits detected: 64314 41580 63869 100169 106002 112535 115048 83936 72341 88312 87171 74348 76350 49500 22667 28837
[10:50:47.285] 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:50:47.286] INFO: number of Vcal hits detected: 207910 207947 207921 207881 207891 207866 207834 207886 207890 207766 207857 207854 207801 207573 207572 206993
[10:50:47.286] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.8 99.5
[10:50:47.286] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.9 99.8 99.8 99.5
[10:50:47.286] INFO: X-ray hit rate [MHz/cm2]: 18.9 12.2 18.7 29.4 31.1 33.0 33.7 24.6 21.2 25.9 25.6 21.8 22.4 14.5 6.6 8.5
[10:50:47.286] INFO: PixTestHighRate::doXPixelAlive() done
[10:50:47.340] INFO: PixTest:: pg_setup set to default.
[10:50:47.353] INFO: enter test to run
[10:51:05.851] INFO: test: HighRate no parameter change
[10:51:05.851] INFO: running: highrate
[10:51:05.852] INFO: ----------------------------------------------------------------------
[10:51:05.852] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:51:05.852] INFO: ----------------------------------------------------------------------
[10:51:06.471] INFO: Expecting 208000 events.
[10:51:20.041] INFO: 208000 events read in total (13043ms).
[10:51:20.046] INFO: Test took 14182ms.
[10:51:20.333] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:20.631] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 2 0 0 0 0 0 0
[10:51:20.631] INFO: number of red-efficiency pixels: 251 121 284 469 399 404 631 376 273 373 400 375 303 391 359 771
[10:51:20.631] INFO: number of X-ray hits detected: 140786 90622 137233 216453 228929 242096 248926 179599 156853 191105 188916 160780 165818 102567 45446 51125
[10:51:20.631] 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:51:20.631] INFO: number of Vcal hits detected: 207728 207875 207690 207490 207576 207565 207277 207596 207721 207502 207570 207590 207686 207584 207626 207205
[10:51:20.631] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.8 99.9 99.8 99.8 99.8 99.9 99.8 99.8 99.6
[10:51:20.631] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.9 99.8 99.8 99.8 99.7 99.8 99.9 99.8 99.8 99.8 99.8 99.8 99.8 99.6
[10:51:20.631] INFO: X-ray hit rate [MHz/cm2]: 41.3 26.6 40.2 63.4 67.1 71.0 73.0 52.6 46.0 56.0 55.4 47.1 48.6 30.1 13.3 15.0
[10:51:20.631] INFO: PixTestHighRate::doXPixelAlive() done
[10:51:20.680] INFO: PixTest:: pg_setup set to default.
[10:51:20.698] INFO: enter test to run
[10:51:38.491] INFO: test: HighRate no parameter change
[10:51:38.491] INFO: running: highrate
[10:51:38.492] INFO: ----------------------------------------------------------------------
[10:51:38.492] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[10:51:38.492] INFO: ----------------------------------------------------------------------
[10:51:39.110] INFO: Expecting 208000 events.
[10:51:54.542] INFO: 208000 events read in total (14905ms).
[10:51:54.550] INFO: Test took 16047ms.
[10:51:54.981] INFO: Fetched DAQ statistics. Counters are being reset now.
[10:51:55.326] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 2 0 0 0 0 0 0
[10:51:55.326] INFO: number of red-efficiency pixels: 539 291 670 1129 871 1054 1480 879 554 857 891 741 618 448 325 612
[10:51:55.326] INFO: number of X-ray hits detected: 212319 137050 209551 328127 347069 368339 377060 273557 238596 288388 287060 244837 251183 155849 67641 75746
[10:51:55.326] 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:51:55.327] INFO: number of Vcal hits detected: 207310 207684 207120 206586 206989 206697 205983 206831 207386 206889 206959 207110 207307 207500 207660 207363
[10:51:55.327] INFO: Vcal hit fiducial efficiency (%): 99.7 99.9 99.6 99.4 99.6 99.4 99.1 99.5 99.7 99.6 99.5 99.6 99.7 99.8 99.8 99.7
[10:51:55.327] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.6 99.3 99.5 99.4 99.0 99.4 99.7 99.5 99.5 99.6 99.7 99.8 99.8 99.7
[10:51:55.327] INFO: X-ray hit rate [MHz/cm2]: 62.2 40.2 61.4 96.2 101.7 108.0 110.5 80.2 69.9 84.5 84.1 71.8 73.6 45.7 19.8 22.2
[10:51:55.327] INFO: PixTestHighRate::doXPixelAlive() done
[10:51:55.382] INFO: PixTest:: pg_setup set to default.
[10:51:55.396] INFO: enter test to run
[10:51:58.794] INFO: test: exit no parameter change
[10:51:59.240] QUIET: Connection to board 33 closed.
[10:51:59.242] INFO: pXar: this is the end, my friend