[12:06:00.489] INFO: *** Welcome to pxar ***
[12:06:00.489] INFO: *** Today: 2016/06/21
[12:06:00.548] INFO: *** Version: v1.9.0-814-g7497
[12:06:00.548] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//dacParameters35_C15.dat
[12:06:00.589] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:06:00.589] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//defaultMaskFile.dat
[12:06:00.594] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C15.dat
[12:06:00.710] INFO: clk: 4
[12:06:00.710] INFO: ctr: 4
[12:06:00.710] INFO: sda: 19
[12:06:00.710] INFO: tin: 9
[12:06:00.710] INFO: level: 15
[12:06:00.710] INFO: triggerdelay: 0
[12:06:00.710] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[12:06:00.710] INFO: Log level: INFO
[12:06:00.725] QUIET: Connection to board DTB_WREK4U opened.
[12:06:00.729] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 32
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREK4U
MAC address: 40D855118020
Hostname: pixelDTB032
Comment:
------------------------------------------------------
[12:06:00.732] INFO: RPC call hashes of host and DTB match: 398089610
[12:06:02.262] INFO: DUT info:
[12:06:02.263] INFO: The DUT currently contains the following objects:
[12:06:02.263] INFO: 2 TBM Cores tbm08c (2 ON)
[12:06:02.263] INFO: TBM Core alpha (0): 7 registers set
[12:06:02.263] INFO: TBM Core beta (1): 7 registers set
[12:06:02.263] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:06:02.263] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:02.263] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:02.263] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:02.263] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:02.263] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:02.263] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:02.263] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:02.263] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:02.263] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:02.263] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:02.263] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:02.263] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:02.263] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:02.263] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:02.263] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:02.263] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:06:02.667] INFO: enter 'restricted' command line mode
[12:06:02.667] INFO: enter test to run
[12:06:10.379] INFO: test: PixelAlive no parameter change
[12:06:10.379] INFO: running: pixelalive
[12:06:10.388] INFO: ----------------------------------------------------------------------
[12:06:10.388] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[12:06:10.388] INFO: ----------------------------------------------------------------------
[12:06:10.766] INFO: Expecting 41600 events.
[12:06:15.088] INFO: 41600 events read in total (3603ms).
[12:06:15.257] INFO: Test took 4866ms.
[12:06:15.269] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:06:15.537] INFO: PixTestAlive::aliveTest() done
[12:06:15.537] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:06:15.565] INFO: enter test to run
[12:07:13.811] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:07:13.811] INFO: running: highrate
[12:07:13.811] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[12:07:14.049] INFO: ----------------------------------------------------------------------
[12:07:14.049] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:07:14.049] INFO: ----------------------------------------------------------------------
[12:07:14.049] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:07:14.049] INFO: edge/corner pixel THR is adjusted
[12:07:14.049] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:07:15.007] INFO: Collecting data for 5 seconds...
[12:07:20.024] INFO: Done with hot pixel readout
[12:07:32.286] INFO: PixTest:: pg_setup set to default.
[12:07:32.286] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:07:32.287] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:07:32.287] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:07:32.287] INFO: 119 hot pixels found in step 0
[12:07:33.286] INFO: Collecting data for 5 seconds...
[12:07:38.303] INFO: Done with hot pixel readout
[12:07:50.297] INFO: PixTest:: pg_setup set to default.
[12:07:50.297] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:07:50.297] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:07:50.298] INFO: 152 hot pixels found in step 1
[12:07:51.302] INFO: Collecting data for 5 seconds...
[12:07:56.319] INFO: Done with hot pixel readout
[12:08:08.582] INFO: PixTest:: pg_setup set to default.
[12:08:08.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:08:08.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:08:08.582] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:08:08.583] INFO: 92 hot pixels found in step 2
[12:08:09.581] INFO: Collecting data for 5 seconds...
[12:08:14.598] INFO: Done with hot pixel readout
[12:08:26.067] INFO: PixTest:: pg_setup set to default.
[12:08:26.067] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:08:26.067] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:08:26.067] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:08:26.067] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:08:26.067] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:08:26.067] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:08:26.068] INFO: 80 hot pixels found in step 3
[12:08:27.072] INFO: Collecting data for 5 seconds...
[12:08:32.089] INFO: Done with hot pixel readout
[12:08:44.656] INFO: PixTest:: pg_setup set to default.
[12:08:44.656] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:08:44.656] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:08:44.656] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:08:44.656] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:08:44.656] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:08:44.657] INFO: 98 hot pixels found in step 4
[12:08:45.656] INFO: Collecting data for 5 seconds...
[12:08:50.672] INFO: Done with hot pixel readout
[12:09:03.221] INFO: PixTest:: pg_setup set to default.
[12:09:03.221] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:03.221] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:03.221] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:03.221] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:03.222] INFO: 82 hot pixels found in step 5
[12:09:04.220] INFO: Collecting data for 5 seconds...
[12:09:09.237] INFO: Done with hot pixel readout
[12:09:21.964] INFO: PixTest:: pg_setup set to default.
[12:09:21.964] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:21.964] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:21.964] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:21.964] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:21.964] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:21.965] INFO: 75 hot pixels found in step 6
[12:09:22.963] INFO: Collecting data for 5 seconds...
[12:09:27.980] INFO: Done with hot pixel readout
[12:09:40.681] INFO: PixTest:: pg_setup set to default.
[12:09:40.681] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:40.681] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:40.682] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:40.682] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:40.682] INFO: 53 hot pixels found in step 7
[12:09:41.681] INFO: Collecting data for 5 seconds...
[12:09:46.697] INFO: Done with hot pixel readout
[12:09:59.428] INFO: PixTest:: pg_setup set to default.
[12:09:59.429] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:59.429] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:59.429] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:59.429] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:59.429] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:59.429] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:59.429] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:09:59.429] INFO: 65 hot pixels found in step 8
[12:10:00.428] INFO: Collecting data for 5 seconds...
[12:10:05.444] INFO: Done with hot pixel readout
[12:10:18.139] INFO: PixTest:: pg_setup set to default.
[12:10:18.139] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:18.139] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:18.139] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:18.139] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:18.139] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:18.139] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:18.139] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:18.139] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:18.139] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:18.139] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:18.140] INFO: 68 hot pixels found in step 9
[12:10:19.138] INFO: Collecting data for 5 seconds...
[12:10:24.154] INFO: Done with hot pixel readout
[12:10:36.853] INFO: PixTest:: pg_setup set to default.
[12:10:36.853] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:36.853] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:36.853] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:36.853] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:36.853] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:36.853] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:36.854] INFO: 69 hot pixels found in step 10
[12:10:37.853] INFO: Collecting data for 5 seconds...
[12:10:42.870] INFO: Done with hot pixel readout
[12:10:55.610] INFO: PixTest:: pg_setup set to default.
[12:10:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:55.610] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:10:55.611] INFO: 63 hot pixels found in step 11
[12:10:56.610] INFO: Collecting data for 5 seconds...
[12:11:01.626] INFO: Done with hot pixel readout
[12:11:14.317] INFO: PixTest:: pg_setup set to default.
[12:11:14.317] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:14.317] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:14.317] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:14.317] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:14.317] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:14.317] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:14.317] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:14.317] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:14.318] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:14.318] INFO: 63 hot pixels found in step 12
[12:11:15.318] INFO: Collecting data for 5 seconds...
[12:11:20.334] INFO: Done with hot pixel readout
[12:11:33.086] INFO: PixTest:: pg_setup set to default.
[12:11:33.087] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:33.087] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:33.087] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:33.087] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:33.087] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:33.087] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:33.087] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:33.087] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:33.087] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:33.088] INFO: 57 hot pixels found in step 13
[12:11:34.086] INFO: Collecting data for 5 seconds...
[12:11:39.103] INFO: Done with hot pixel readout
[12:11:51.809] INFO: PixTest:: pg_setup set to default.
[12:11:51.809] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:51.810] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:51.810] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:51.810] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:51.810] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:51.810] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:51.810] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:51.810] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:51.810] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:51.810] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:51.810] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:51.810] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:51.810] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:51.810] WARNING: => trimBits already at highest possible threshold, 'real' hot pixel found
[12:11:51.810] INFO: 54 hot pixels found in step 14
[12:11:51.853] INFO: 54 hot pixels could not be trimmed and have been masked.
[12:11:51.857] INFO: PixTest::trimHotPixels() done
[12:11:51.857] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C0.dat
[12:11:51.863] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C1.dat
[12:11:51.868] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C2.dat
[12:11:51.873] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C3.dat
[12:11:51.879] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C4.dat
[12:11:51.884] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C5.dat
[12:11:51.889] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C6.dat
[12:11:51.894] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C7.dat
[12:11:51.899] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C8.dat
[12:11:51.904] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C9.dat
[12:11:51.910] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C10.dat
[12:11:51.915] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C11.dat
[12:11:51.920] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C12.dat
[12:11:51.925] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C13.dat
[12:11:51.930] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C14.dat
[12:11:51.935] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C15.dat
[12:11:51.940] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//defaultMaskFile.dat
[12:11:51.950] INFO: enter test to run
[12:13:13.280] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[12:13:13.280] INFO: running: highrate
[12:13:13.285] INFO: ----------------------------------------------------------------------
[12:13:13.285] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:13:13.285] INFO: ----------------------------------------------------------------------
[12:13:13.285] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[12:13:13.285] INFO: edge/corner pixel THR is adjusted
[12:13:13.285] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:13:14.243] INFO: Collecting data for 1 seconds...
[12:13:15.246] INFO: Done with hot pixel readout
[12:13:19.762] INFO: PixTest:: pg_setup set to default.
[12:13:19.763] INFO: 0 hot pixels found in step 0
[12:13:19.769] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:13:19.841] INFO: PixTest::trimHotPixels() done
[12:13:19.841] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C0.dat
[12:13:19.853] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C1.dat
[12:13:19.863] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C2.dat
[12:13:19.868] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C3.dat
[12:13:19.873] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C4.dat
[12:13:19.879] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C5.dat
[12:13:19.884] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C6.dat
[12:13:19.889] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C7.dat
[12:13:19.894] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C8.dat
[12:13:19.899] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C9.dat
[12:13:19.905] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C10.dat
[12:13:19.910] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C11.dat
[12:13:19.915] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C12.dat
[12:13:19.920] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C13.dat
[12:13:19.925] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C14.dat
[12:13:19.931] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//trimParameters35_C15.dat
[12:13:19.936] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Y-T-NM_FPIXTest-17C-FNAL-160610-1447_2016-06-10_14h47m_1465588044/000_FPIXTest_p17//defaultMaskFile.dat
[12:13:19.945] INFO: enter test to run
[12:14:09.600] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[12:14:09.600] INFO: running: xray
[12:14:09.601] INFO: ----------------------------------------------------------------------
[12:14:09.601] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:14:09.601] INFO: ----------------------------------------------------------------------
[12:14:10.565] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:14:20.995] INFO: run duration 10 seconds, buffer almost full (81%), pausing triggers.
[12:14:50.394] INFO: Resuming triggers.
[12:15:00.829] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[12:15:30.044] INFO: Resuming triggers.
[12:15:40.479] INFO: run duration 31 seconds, buffer almost full (81%), pausing triggers.
[12:16:10.510] INFO: Resuming triggers.
[12:16:20.946] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[12:16:51.332] INFO: Resuming triggers.
[12:17:01.765] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[12:17:31.762] INFO: Resuming triggers.
[12:17:42.195] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[12:18:11.700] INFO: Resuming triggers.
[12:18:22.136] INFO: run duration 72 seconds, buffer almost full (81%), pausing triggers.
[12:18:51.687] INFO: Resuming triggers.
[12:19:02.123] INFO: run duration 83 seconds, buffer almost full (81%), pausing triggers.
[12:19:31.731] INFO: Resuming triggers.
[12:19:42.167] INFO: run duration 93 seconds, buffer almost full (81%), pausing triggers.
[12:20:11.657] INFO: Resuming triggers.
[12:20:18.097] INFO: data taking finished, elapsed time: 100 seconds.
[12:20:36.497] INFO: PixTest:: pg_setup set to default.
[12:20:36.500] INFO: PixTestXray::doPhRun() done
[12:20:36.661] INFO: enter test to run
[12:21:35.413] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[12:21:35.413] INFO: running: xray
[12:21:35.414] INFO: ----------------------------------------------------------------------
[12:21:35.414] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:21:35.414] INFO: ----------------------------------------------------------------------
[12:21:36.379] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:21:42.140] INFO: run duration 5 seconds, buffer almost full (81%), pausing triggers.
[12:22:12.295] INFO: Resuming triggers.
[12:22:18.061] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[12:22:48.220] INFO: Resuming triggers.
[12:22:53.984] INFO: run duration 17 seconds, buffer almost full (81%), pausing triggers.
[12:23:24.215] INFO: Resuming triggers.
[12:23:29.980] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[12:24:00.212] INFO: Resuming triggers.
[12:24:05.972] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[12:24:36.122] INFO: Resuming triggers.
[12:24:41.884] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[12:25:12.073] INFO: Resuming triggers.
[12:25:17.836] INFO: run duration 40 seconds, buffer almost full (81%), pausing triggers.
[12:25:48.034] INFO: Resuming triggers.
[12:25:53.793] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[12:26:24.834] INFO: Resuming triggers.
[12:26:30.594] INFO: run duration 51 seconds, buffer almost full (81%), pausing triggers.
[12:27:01.116] INFO: Resuming triggers.
[12:27:06.879] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[12:27:37.491] INFO: Resuming triggers.
[12:27:43.252] INFO: run duration 63 seconds, buffer almost full (81%), pausing triggers.
[12:28:14.892] INFO: Resuming triggers.
[12:28:20.655] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[12:28:52.313] INFO: Resuming triggers.
[12:28:58.079] INFO: run duration 74 seconds, buffer almost full (81%), pausing triggers.
[12:29:28.244] INFO: Resuming triggers.
[12:29:34.005] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[12:30:04.291] INFO: Resuming triggers.
[12:30:10.053] INFO: run duration 86 seconds, buffer almost full (81%), pausing triggers.
[12:30:40.143] INFO: Resuming triggers.
[12:30:45.906] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[12:31:16.098] INFO: Resuming triggers.
[12:31:21.862] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[12:31:52.023] INFO: Resuming triggers.
[12:31:54.394] INFO: data taking finished, elapsed time: 100 seconds.
[12:32:07.081] INFO: PixTest:: pg_setup set to default.
[12:32:07.084] INFO: PixTestXray::doPhRun() done
[12:32:07.233] INFO: enter test to run
[12:32:46.304] INFO: test: HighRate no parameter change
[12:32:46.304] INFO: running: highrate
[12:32:46.323] INFO: ----------------------------------------------------------------------
[12:32:46.323] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:32:46.323] INFO: ----------------------------------------------------------------------
[12:32:46.482] INFO: Expecting 768 events.
[12:32:47.624] INFO: 768 events read in total (419ms).
[12:32:47.624] INFO: Test took 1295ms.
[12:32:48.427] INFO: Expecting 41600 events.
[12:32:51.614] INFO: 41600 events read in total (2660ms).
[12:32:51.615] INFO: Test took 3983ms.
[12:32:51.657] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:32:52.367] INFO: Expecting 41600 events.
[12:32:55.672] INFO: 41600 events read in total (2778ms).
[12:32:55.673] INFO: Test took 3995ms.
[12:32:55.715] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:32:56.423] INFO: Expecting 41600 events.
[12:32:59.756] INFO: 41600 events read in total (2806ms).
[12:32:59.757] INFO: Test took 4022ms.
[12:32:59.799] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:00.511] INFO: Expecting 41600 events.
[12:33:03.837] INFO: 41600 events read in total (2799ms).
[12:33:03.838] INFO: Test took 4018ms.
[12:33:03.880] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:04.592] INFO: Expecting 41600 events.
[12:33:07.934] INFO: 41600 events read in total (2815ms).
[12:33:07.936] INFO: Test took 4036ms.
[12:33:07.977] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:08.682] INFO: Expecting 41600 events.
[12:33:12.017] INFO: 41600 events read in total (2809ms).
[12:33:12.018] INFO: Test took 4020ms.
[12:33:12.060] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:12.766] INFO: Expecting 41600 events.
[12:33:16.097] INFO: 41600 events read in total (2805ms).
[12:33:16.098] INFO: Test took 4018ms.
[12:33:16.140] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:16.847] INFO: Expecting 41600 events.
[12:33:20.189] INFO: 41600 events read in total (2815ms).
[12:33:20.190] INFO: Test took 4030ms.
[12:33:20.232] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:20.939] INFO: Expecting 41600 events.
[12:33:24.280] INFO: 41600 events read in total (2814ms).
[12:33:24.281] INFO: Test took 4028ms.
[12:33:24.322] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:25.029] INFO: Expecting 41600 events.
[12:33:28.374] INFO: 41600 events read in total (2818ms).
[12:33:28.375] INFO: Test took 4032ms.
[12:33:28.417] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:29.126] INFO: Expecting 41600 events.
[12:33:32.457] INFO: 41600 events read in total (2804ms).
[12:33:32.458] INFO: Test took 4020ms.
[12:33:32.501] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:33.211] INFO: Expecting 41600 events.
[12:33:36.549] INFO: 41600 events read in total (2811ms).
[12:33:36.550] INFO: Test took 4027ms.
[12:33:36.591] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:37.303] INFO: Expecting 41600 events.
[12:33:40.638] INFO: 41600 events read in total (2808ms).
[12:33:40.639] INFO: Test took 4026ms.
[12:33:40.681] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:41.389] INFO: Expecting 41600 events.
[12:33:44.730] INFO: 41600 events read in total (2814ms).
[12:33:44.731] INFO: Test took 4028ms.
[12:33:44.773] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:45.480] INFO: Expecting 41600 events.
[12:33:48.822] INFO: 41600 events read in total (2815ms).
[12:33:48.823] INFO: Test took 4029ms.
[12:33:48.865] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:49.573] INFO: Expecting 41600 events.
[12:33:52.913] INFO: 41600 events read in total (2813ms).
[12:33:52.915] INFO: Test took 4029ms.
[12:33:52.957] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:53.666] INFO: Expecting 41600 events.
[12:33:57.006] INFO: 41600 events read in total (2813ms).
[12:33:57.007] INFO: Test took 4028ms.
[12:33:57.049] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:33:57.758] INFO: Expecting 41600 events.
[12:34:01.108] INFO: 41600 events read in total (2823ms).
[12:34:01.109] INFO: Test took 4038ms.
[12:34:01.150] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:01.857] INFO: Expecting 41600 events.
[12:34:05.178] INFO: 41600 events read in total (2794ms).
[12:34:05.179] INFO: Test took 4007ms.
[12:34:05.221] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:05.929] INFO: Expecting 41600 events.
[12:34:09.147] INFO: 41600 events read in total (2691ms).
[12:34:09.148] INFO: Test took 3907ms.
[12:34:09.190] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:09.538] INFO: enter test to run
[12:34:29.384] INFO: test: HighRate no parameter change
[12:34:29.384] INFO: running: highrate
[12:34:29.385] INFO: ----------------------------------------------------------------------
[12:34:29.385] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:34:29.385] INFO: ----------------------------------------------------------------------
[12:34:30.003] INFO: Expecting 208000 events.
[12:34:42.299] INFO: 208000 events read in total (11769ms).
[12:34:42.303] INFO: Test took 12908ms.
[12:34:42.490] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:34:42.756] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:34:42.756] INFO: number of red-efficiency pixels: 99 63 120 158 165 191 185 123 133 160 156 132 139 63 36 31
[12:34:42.756] INFO: number of X-ray hits detected: 77206 58289 94367 138709 147712 152815 148804 109001 99526 129201 120322 106895 107091 64357 29086 29536
[12:34:42.756] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:34:42.756] INFO: number of Vcal hits detected: 207900 207937 207821 207840 207834 207803 207802 207872 207861 207831 207841 207862 207855 207933 207964 207969
[12:34:42.756] INFO: Vcal hit fiducial efficiency (%): 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.9 100.0 100.0 100.0
[12:34:42.756] INFO: Vcal hit overall efficiency (%): 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.9 100.0 100.0 100.0
[12:34:42.756] INFO: X-ray hit rate [MHz/cm2]: 22.6 17.1 27.7 40.7 43.3 44.8 43.6 31.9 29.2 37.9 35.3 31.3 31.4 18.9 8.5 8.7
[12:34:42.756] INFO: PixTestHighRate::doXPixelAlive() done
[12:34:42.805] INFO: PixTest:: pg_setup set to default.
[12:34:42.820] INFO: enter test to run
[12:35:08.167] INFO: test: HighRate no parameter change
[12:35:08.167] INFO: running: highrate
[12:35:08.168] INFO: ----------------------------------------------------------------------
[12:35:08.169] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:35:08.169] INFO: ----------------------------------------------------------------------
[12:35:08.788] INFO: Expecting 208000 events.
[12:35:23.346] INFO: 208000 events read in total (14031ms).
[12:35:23.352] INFO: Test took 15174ms.
[12:35:23.722] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:35:24.053] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:35:24.053] INFO: number of red-efficiency pixels: 270 199 412 541 600 661 658 508 396 492 512 436 473 218 70 57
[12:35:24.053] INFO: number of X-ray hits detected: 157721 118610 191145 282978 300364 309814 302277 220208 203407 264337 246568 216710 218502 130490 60084 59622
[12:35:24.053] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:35:24.053] INFO: number of Vcal hits detected: 207708 207778 207481 207420 207312 207270 207262 207424 207573 207467 207422 207525 207489 207763 207930 207943
[12:35:24.053] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.7 99.7 99.7 99.8 99.8 99.7 99.8 99.8 99.9 100.0 100.0
[12:35:24.053] INFO: Vcal hit overall efficiency (%): 99.9 99.9 99.8 99.7 99.7 99.6 99.6 99.7 99.8 99.7 99.7 99.8 99.8 99.9 100.0 100.0
[12:35:24.053] INFO: X-ray hit rate [MHz/cm2]: 46.2 34.8 56.0 82.9 88.0 90.8 88.6 64.5 59.6 77.5 72.3 63.5 64.0 38.2 17.6 17.5
[12:35:24.053] INFO: PixTestHighRate::doXPixelAlive() done
[12:35:24.104] INFO: PixTest:: pg_setup set to default.
[12:35:24.116] INFO: enter test to run
[12:35:48.599] INFO: test: HighRate no parameter change
[12:35:48.599] INFO: running: highrate
[12:35:48.600] INFO: ----------------------------------------------------------------------
[12:35:48.600] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:35:48.600] INFO: ----------------------------------------------------------------------
[12:35:49.217] INFO: Expecting 208000 events.
[12:36:06.331] INFO: 208000 events read in total (16587ms).
[12:36:06.340] INFO: Test took 17731ms.
[12:36:06.908] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:36:07.294] INFO: number of dead pixels (per ROC): 0 0 1 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:36:07.294] INFO: number of red-efficiency pixels: 599 418 835 1251 1478 1616 1537 1094 881 1179 1177 1097 1132 448 142 89
[12:36:07.294] INFO: number of X-ray hits detected: 235562 177806 288164 422422 449759 462827 452552 332351 305199 397228 367926 324814 327233 196314 89907 90240
[12:36:07.294] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[12:36:07.294] INFO: number of Vcal hits detected: 207301 207501 206721 206400 205976 205675 205852 206493 206909 206519 206472 206577 206601 207473 207853 207909
[12:36:07.294] INFO: Vcal hit fiducial efficiency (%): 99.7 99.8 99.5 99.3 99.1 99.0 99.1 99.4 99.5 99.4 99.3 99.4 99.4 99.8 99.9 100.0
[12:36:07.294] INFO: Vcal hit overall efficiency (%): 99.7 99.8 99.4 99.2 99.0 98.9 99.0 99.3 99.5 99.3 99.3 99.3 99.3 99.7 99.9 100.0
[12:36:07.294] INFO: X-ray hit rate [MHz/cm2]: 69.0 52.1 84.5 123.8 131.8 135.7 132.6 97.4 89.5 116.4 107.8 95.2 95.9 57.5 26.4 26.5
[12:36:07.294] INFO: PixTestHighRate::doXPixelAlive() done
[12:36:07.343] INFO: PixTest:: pg_setup set to default.
[12:36:07.361] INFO: enter test to run
[12:36:19.111] INFO: test: exit no parameter change
[12:36:19.444] QUIET: Connection to board 32 closed.
[12:36:19.446] INFO: pXar: this is the end, my friend