[09:15:53.966] INFO: *** Welcome to pxar ***
[09:15:53.966] INFO: *** Today: 2016/04/18
[09:15:53.987] INFO: *** Version: v1.9.0-796-gef167-dirty
[09:15:53.987] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//dacParameters35_C15.dat
[09:15:54.015] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//tbmParameters_C0b.dat
[09:15:54.015] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//defaultMaskFile.dat
[09:15:54.016] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C15.dat
[09:15:54.094] INFO: clk: 4
[09:15:54.094] INFO: ctr: 4
[09:15:54.094] INFO: sda: 19
[09:15:54.095] INFO: tin: 9
[09:15:54.095] INFO: level: 15
[09:15:54.095] INFO: triggerdelay: 0
[09:15:54.095] QUIET: Instanciating API for pxar v1.9.0+796~gef16747
[09:15:54.095] INFO: Log level: INFO
[09:15:54.113] QUIET: Connection to board DTB_WREKRL opened.
[09:15:54.117] INFO: DTB startup information
--- DTB info------------------------------------------
Board id: 33
HW version: DTB1.2
FW version: 4.2
SW version: 4.5
USB id: DTB_WREKRL
MAC address: 40D855118021
Hostname: pixelDTB033
Comment:
------------------------------------------------------
[09:15:54.119] INFO: RPC call hashes of host and DTB match: 398089610
[09:15:55.652] INFO: DUT info:
[09:15:55.652] INFO: The DUT currently contains the following objects:
[09:15:55.653] INFO: 2 TBM Cores tbm08c (2 ON)
[09:15:55.653] INFO: TBM Core alpha (0): 7 registers set
[09:15:55.653] INFO: TBM Core beta (1): 7 registers set
[09:15:55.653] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[09:15:55.653] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[09:15:55.653] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[09:15:55.653] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[09:15:55.653] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[09:15:55.653] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[09:15:55.653] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[09:15:55.653] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[09:15:55.653] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[09:15:55.653] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[09:15:55.653] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[09:15:55.654] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[09:15:55.654] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[09:15:55.654] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[09:15:55.654] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[09:15:55.654] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[09:15:55.654] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[09:15:56.057] INFO: enter 'restricted' command line mode
[09:15:56.057] INFO: enter test to run
[09:16:01.928] INFO: test: PixelAlive no parameter change
[09:16:01.928] INFO: running: pixelalive
[09:16:01.938] INFO: ----------------------------------------------------------------------
[09:16:01.938] INFO: PixTestAlive::aliveTest() ntrig = 10, vcal = 200 (ctrlreg = 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0)
[09:16:01.938] INFO: ----------------------------------------------------------------------
[09:16:02.264] INFO: Expecting 41600 events.
[09:16:06.596] INFO: 41600 events read in total (3614ms).
[09:16:06.767] INFO: Test took 4826ms.
[09:16:06.775] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:16:07.042] INFO: PixTestAlive::aliveTest() done
[09:16:07.042] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 0 1 0 0
[09:16:07.071] INFO: enter test to run
[09:16:33.216] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[09:16:33.216] INFO: running: highrate
[09:16:33.216] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[09:16:33.428] INFO: ----------------------------------------------------------------------
[09:16:33.428] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[09:16:33.429] INFO: ----------------------------------------------------------------------
[09:16:33.429] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[09:16:33.429] INFO: edge/corner pixel THR is adjusted
[09:16:33.429] INFO: PixTestHighRate::trimHotPixels: step 0...
[09:16:34.387] INFO: Collecting data for 5 seconds...
[09:16:39.404] INFO: Done with hot pixel readout
[09:16:51.146] INFO: PixTest:: pg_setup set to default.
[09:16:51.147] INFO: 2 hot pixels found in step 0
[09:16:52.160] INFO: Collecting data for 5 seconds...
[09:16:57.176] INFO: Done with hot pixel readout
[09:17:08.931] INFO: PixTest:: pg_setup set to default.
[09:17:08.932] INFO: 3 hot pixels found in step 1
[09:17:09.924] INFO: Collecting data for 5 seconds...
[09:17:14.941] INFO: Done with hot pixel readout
[09:17:26.646] INFO: PixTest:: pg_setup set to default.
[09:17:26.647] INFO: 2 hot pixels found in step 2
[09:17:27.639] INFO: Collecting data for 5 seconds...
[09:17:32.657] INFO: Done with hot pixel readout
[09:17:44.438] INFO: PixTest:: pg_setup set to default.
[09:17:44.438] INFO: 0 hot pixels found in step 3
[09:17:44.476] INFO: 0 hot pixels could not be trimmed and have been masked.
[09:17:44.480] INFO: PixTest::trimHotPixels() done
[09:17:44.480] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C0.dat
[09:17:44.486] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C1.dat
[09:17:44.492] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C2.dat
[09:17:44.497] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C3.dat
[09:17:44.502] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C4.dat
[09:17:44.508] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C5.dat
[09:17:44.513] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C6.dat
[09:17:44.518] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C7.dat
[09:17:44.523] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C8.dat
[09:17:44.528] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C9.dat
[09:17:44.533] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C10.dat
[09:17:44.538] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C11.dat
[09:17:44.543] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C12.dat
[09:17:44.548] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C13.dat
[09:17:44.553] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C14.dat
[09:17:44.559] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C15.dat
[09:17:44.564] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//defaultMaskFile.dat
[09:17:44.573] INFO: enter test to run
[09:18:40.591] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[09:18:40.591] INFO: running: highrate
[09:18:40.595] INFO: ----------------------------------------------------------------------
[09:18:40.595] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[09:18:40.595] INFO: ----------------------------------------------------------------------
[09:18:40.595] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[09:18:40.595] INFO: edge/corner pixel THR is adjusted
[09:18:40.595] INFO: PixTestHighRate::trimHotPixels: step 0...
[09:18:41.553] INFO: Collecting data for 1 seconds...
[09:18:42.557] INFO: Done with hot pixel readout
[09:18:46.591] INFO: PixTest:: pg_setup set to default.
[09:18:46.592] INFO: 0 hot pixels found in step 0
[09:18:46.597] INFO: 0 hot pixels could not be trimmed and have been masked.
[09:18:46.689] INFO: PixTest::trimHotPixels() done
[09:18:46.689] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C0.dat
[09:18:46.699] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C1.dat
[09:18:46.705] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C2.dat
[09:18:46.710] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C3.dat
[09:18:46.716] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C4.dat
[09:18:46.721] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C5.dat
[09:18:46.727] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C6.dat
[09:18:46.732] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C7.dat
[09:18:46.737] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C8.dat
[09:18:46.742] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C9.dat
[09:18:46.748] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C10.dat
[09:18:46.753] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C11.dat
[09:18:46.759] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C12.dat
[09:18:46.764] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C13.dat
[09:18:46.769] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C14.dat
[09:18:46.774] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//trimParameters35_C15.dat
[09:18:46.780] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-M-3-42_FPIXTest-17C-FNAL-160415-1125_2016-04-15_11h26m_1460737568/000_FPIXTest_p17//defaultMaskFile.dat
[09:18:46.790] INFO: enter test to run
[09:19:31.206] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[09:19:31.206] INFO: running: xray
[09:19:31.208] INFO: ----------------------------------------------------------------------
[09:19:31.208] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[09:19:31.208] INFO: ----------------------------------------------------------------------
[09:19:32.170] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[09:19:43.660] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[09:20:12.956] INFO: Resuming triggers.
[09:20:24.443] INFO: run duration 22 seconds, buffer almost full (81%), pausing triggers.
[09:20:53.829] INFO: Resuming triggers.
[09:21:05.318] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[09:21:34.537] INFO: Resuming triggers.
[09:21:46.027] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[09:22:15.508] INFO: Resuming triggers.
[09:22:26.998] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[09:22:56.323] INFO: Resuming triggers.
[09:23:07.812] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[09:23:37.177] INFO: Resuming triggers.
[09:23:48.670] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[09:24:18.036] INFO: Resuming triggers.
[09:24:29.527] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[09:24:58.943] INFO: Resuming triggers.
[09:25:07.360] INFO: data taking finished, elapsed time: 100 seconds.
[09:25:28.983] INFO: PixTest:: pg_setup set to default.
[09:25:28.986] INFO: PixTestXray::doPhRun() done
[09:25:29.123] INFO: enter test to run
[09:27:26.235] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[09:27:26.235] INFO: running: xray
[09:27:26.236] INFO: ----------------------------------------------------------------------
[09:27:26.236] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[09:27:26.236] INFO: ----------------------------------------------------------------------
[09:27:27.200] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[09:27:33.749] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[09:28:04.088] INFO: Resuming triggers.
[09:28:10.637] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[09:28:39.640] INFO: Resuming triggers.
[09:28:46.187] INFO: run duration 19 seconds, buffer almost full (81%), pausing triggers.
[09:29:16.278] INFO: Resuming triggers.
[09:29:22.824] INFO: run duration 26 seconds, buffer almost full (81%), pausing triggers.
[09:29:52.808] INFO: Resuming triggers.
[09:29:59.356] INFO: run duration 32 seconds, buffer almost full (81%), pausing triggers.
[09:30:29.460] INFO: Resuming triggers.
[09:30:36.007] INFO: run duration 39 seconds, buffer almost full (81%), pausing triggers.
[09:31:06.127] INFO: Resuming triggers.
[09:31:12.672] INFO: run duration 45 seconds, buffer almost full (81%), pausing triggers.
[09:31:42.783] INFO: Resuming triggers.
[09:31:49.328] INFO: run duration 52 seconds, buffer almost full (81%), pausing triggers.
[09:32:19.438] INFO: Resuming triggers.
[09:32:25.983] INFO: run duration 58 seconds, buffer almost full (81%), pausing triggers.
[09:32:56.078] INFO: Resuming triggers.
[09:33:02.623] INFO: run duration 65 seconds, buffer almost full (81%), pausing triggers.
[09:33:32.774] INFO: Resuming triggers.
[09:33:39.318] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[09:34:09.433] INFO: Resuming triggers.
[09:34:15.975] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[09:34:46.077] INFO: Resuming triggers.
[09:34:52.617] INFO: run duration 84 seconds, buffer almost full (81%), pausing triggers.
[09:35:22.797] INFO: Resuming triggers.
[09:35:29.337] INFO: run duration 91 seconds, buffer almost full (81%), pausing triggers.
[09:35:59.463] INFO: Resuming triggers.
[09:36:06.006] INFO: run duration 97 seconds, buffer almost full (81%), pausing triggers.
[09:36:36.084] INFO: Resuming triggers.
[09:36:38.236] INFO: data taking finished, elapsed time: 100 seconds.
[09:36:48.461] INFO: PixTest:: pg_setup set to default.
[09:36:48.464] INFO: PixTestXray::doPhRun() done
[09:36:48.612] INFO: enter test to run
[09:38:13.350] INFO: test: HighRate no parameter change
[09:38:13.350] INFO: running: highrate
[09:38:13.351] INFO: ----------------------------------------------------------------------
[09:38:13.351] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[09:38:13.351] INFO: ----------------------------------------------------------------------
[09:38:13.493] INFO: Expecting 768 events.
[09:38:14.623] INFO: 768 events read in total (415ms).
[09:38:14.623] INFO: Test took 1264ms.
[09:38:15.426] INFO: Expecting 41600 events.
[09:38:18.202] INFO: 41600 events read in total (2249ms).
[09:38:18.203] INFO: Test took 3574ms.
[09:38:18.239] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:18.964] INFO: Expecting 41600 events.
[09:38:22.146] INFO: 41600 events read in total (2655ms).
[09:38:22.147] INFO: Test took 3891ms.
[09:38:22.183] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:22.905] INFO: Expecting 41600 events.
[09:38:26.090] INFO: 41600 events read in total (2659ms).
[09:38:26.091] INFO: Test took 3889ms.
[09:38:26.126] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:26.846] INFO: Expecting 41600 events.
[09:38:30.053] INFO: 41600 events read in total (2680ms).
[09:38:30.054] INFO: Test took 3909ms.
[09:38:30.089] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:30.810] INFO: Expecting 41600 events.
[09:38:34.047] INFO: 41600 events read in total (2710ms).
[09:38:34.048] INFO: Test took 3940ms.
[09:38:34.083] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:34.802] INFO: Expecting 41600 events.
[09:38:38.055] INFO: 41600 events read in total (2726ms).
[09:38:38.056] INFO: Test took 3953ms.
[09:38:38.091] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:38.810] INFO: Expecting 41600 events.
[09:38:42.065] INFO: 41600 events read in total (2728ms).
[09:38:42.066] INFO: Test took 3956ms.
[09:38:42.101] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:42.823] INFO: Expecting 41600 events.
[09:38:46.073] INFO: 41600 events read in total (2724ms).
[09:38:46.074] INFO: Test took 3955ms.
[09:38:46.108] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:46.830] INFO: Expecting 41600 events.
[09:38:50.089] INFO: 41600 events read in total (2732ms).
[09:38:50.090] INFO: Test took 3963ms.
[09:38:50.125] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:50.842] INFO: Expecting 41600 events.
[09:38:54.105] INFO: 41600 events read in total (2736ms).
[09:38:54.106] INFO: Test took 3962ms.
[09:38:54.142] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:54.863] INFO: Expecting 41600 events.
[09:38:58.070] INFO: 41600 events read in total (2680ms).
[09:38:58.071] INFO: Test took 3911ms.
[09:38:58.108] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:38:58.829] INFO: Expecting 41600 events.
[09:39:02.093] INFO: 41600 events read in total (2737ms).
[09:39:02.094] INFO: Test took 3966ms.
[09:39:02.129] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:39:02.850] INFO: Expecting 41600 events.
[09:39:06.113] INFO: 41600 events read in total (2736ms).
[09:39:06.114] INFO: Test took 3966ms.
[09:39:06.149] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:39:06.871] INFO: Expecting 41600 events.
[09:39:10.121] INFO: 41600 events read in total (2723ms).
[09:39:10.122] INFO: Test took 3954ms.
[09:39:10.162] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:39:10.877] INFO: Expecting 41600 events.
[09:39:14.141] INFO: 41600 events read in total (2737ms).
[09:39:14.142] INFO: Test took 3961ms.
[09:39:14.176] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:39:14.901] INFO: Expecting 41600 events.
[09:39:18.166] INFO: 41600 events read in total (2738ms).
[09:39:18.167] INFO: Test took 3973ms.
[09:39:18.202] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:39:18.924] INFO: Expecting 41600 events.
[09:39:22.094] INFO: 41600 events read in total (2644ms).
[09:39:22.095] INFO: Test took 3874ms.
[09:39:22.129] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:39:22.849] INFO: Expecting 41600 events.
[09:39:26.050] INFO: 41600 events read in total (2674ms).
[09:39:26.051] INFO: Test took 3902ms.
[09:39:26.087] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:39:26.809] INFO: Expecting 41600 events.
[09:39:29.959] INFO: 41600 events read in total (2623ms).
[09:39:29.960] INFO: Test took 3854ms.
[09:39:29.995] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:39:30.721] INFO: Expecting 41600 events.
[09:39:33.756] INFO: 41600 events read in total (2508ms).
[09:39:33.757] INFO: Test took 3743ms.
[09:39:33.792] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:39:34.154] INFO: enter test to run
[09:40:01.733] INFO: test: HighRate no parameter change
[09:40:01.733] INFO: running: highrate
[09:40:01.734] INFO: ----------------------------------------------------------------------
[09:40:01.734] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:40:01.734] INFO: ----------------------------------------------------------------------
[09:40:02.349] INFO: Expecting 208000 events.
[09:40:14.321] INFO: 208000 events read in total (11445ms).
[09:40:14.324] INFO: Test took 12582ms.
[09:40:14.473] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:40:14.727] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 0 1 0 0
[09:40:14.727] INFO: number of red-efficiency pixels: 76 47 82 155 151 133 148 112 127 149 129 96 100 67 24 35
[09:40:14.727] INFO: number of X-ray hits detected: 63329 42055 68089 108147 114375 113874 118801 88657 87115 104219 104628 88187 90153 52913 22671 24695
[09:40:14.728] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:40:14.728] INFO: number of Vcal hits detected: 207924 207953 207917 207845 207846 207858 207849 207886 207870 207800 207867 207899 207895 207884 207975 207964
[09:40:14.728] 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 100.0 100.0 100.0 100.0
[09:40:14.728] 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 100.0 99.9 99.9 100.0 100.0
[09:40:14.728] INFO: X-ray hit rate [MHz/cm2]: 18.6 12.3 20.0 31.7 33.5 33.4 34.8 26.0 25.5 30.5 30.7 25.8 26.4 15.5 6.6 7.2
[09:40:14.728] INFO: PixTestHighRate::doXPixelAlive() done
[09:40:14.776] INFO: PixTest:: pg_setup set to default.
[09:40:14.791] INFO: enter test to run
[09:40:31.381] INFO: test: HighRate no parameter change
[09:40:31.381] INFO: running: highrate
[09:40:31.382] INFO: ----------------------------------------------------------------------
[09:40:31.382] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:40:31.382] INFO: ----------------------------------------------------------------------
[09:40:31.995] INFO: Expecting 208000 events.
[09:40:45.743] INFO: 208000 events read in total (13221ms).
[09:40:45.749] INFO: Test took 14357ms.
[09:40:46.050] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:40:46.352] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 0 1 0 0
[09:40:46.352] INFO: number of red-efficiency pixels: 215 94 256 476 443 456 595 387 359 462 452 274 313 162 53 87
[09:40:46.352] INFO: number of X-ray hits detected: 131566 86148 139348 222461 235582 233195 243843 184359 179808 213970 215534 181201 186399 108569 46481 51750
[09:40:46.352] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:40:46.352] INFO: number of Vcal hits detected: 207768 207897 207717 207482 207522 207509 207331 207567 207618 207442 207517 207713 207672 207784 207944 207912
[09:40:46.352] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.7 99.8 99.8 99.8 99.8 99.9 99.9 99.9 100.0 100.0
[09:40:46.352] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.8 99.8 99.8 99.7 99.8 99.8 99.7 99.8 99.9 99.8 99.9 100.0 100.0
[09:40:46.352] INFO: X-ray hit rate [MHz/cm2]: 38.6 25.3 40.8 65.2 69.1 68.4 71.5 54.0 52.7 62.7 63.2 53.1 54.6 31.8 13.6 15.2
[09:40:46.352] INFO: PixTestHighRate::doXPixelAlive() done
[09:40:46.398] INFO: PixTest:: pg_setup set to default.
[09:40:46.413] INFO: enter test to run
[09:41:01.853] INFO: test: HighRate no parameter change
[09:41:01.853] INFO: running: highrate
[09:41:01.854] INFO: ----------------------------------------------------------------------
[09:41:01.854] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[09:41:01.854] INFO: ----------------------------------------------------------------------
[09:41:02.473] INFO: Expecting 208000 events.
[09:41:18.320] INFO: 208000 events read in total (15320ms).
[09:41:18.328] INFO: Test took 16464ms.
[09:41:18.788] INFO: Fetched DAQ statistics. Counters are being reset now.
[09:41:19.144] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 1 0 0 0 1 0 0
[09:41:19.144] INFO: number of red-efficiency pixels: 436 226 614 1224 997 1046 1335 899 828 1166 1058 590 728 419 100 142
[09:41:19.144] INFO: number of X-ray hits detected: 203813 133406 215866 345380 363085 361459 376845 283894 278584 331000 333049 279934 287893 168823 71875 80616
[09:41:19.144] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[09:41:19.144] INFO: number of Vcal hits detected: 207523 207758 207186 206441 206758 206705 206248 206806 207000 206415 206666 207327 207145 207464 207895 207845
[09:41:19.144] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.7 99.3 99.5 99.4 99.2 99.5 99.6 99.3 99.4 99.7 99.6 99.8 100.0 99.9
[09:41:19.144] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.6 99.3 99.4 99.4 99.2 99.4 99.5 99.2 99.4 99.7 99.6 99.7 99.9 99.9
[09:41:19.144] INFO: X-ray hit rate [MHz/cm2]: 59.7 39.1 63.3 101.2 106.4 105.9 110.5 83.2 81.7 97.0 97.6 82.1 84.4 49.5 21.1 23.6
[09:41:19.144] INFO: PixTestHighRate::doXPixelAlive() done
[09:41:19.198] INFO: PixTest:: pg_setup set to default.
[09:41:19.218] INFO: enter test to run
[09:42:11.140] INFO: test: exit no parameter change
[09:42:11.446] QUIET: Connection to board 33 closed.
[09:42:11.447] INFO: pXar: this is the end, my friend