[12:58:15.445] INFO: *** Welcome to pxar ***
[12:58:15.445] INFO: *** Today: 2016/07/22
[12:58:16.287] INFO: *** Version: v1.9.0-814-g7497
[12:58:16.287] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//dacParameters35_C15.dat
[12:58:16.376] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:58:16.376] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//defaultMaskFile.dat
[12:58:16.387] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C15.dat
[12:58:16.567] INFO: clk: 4
[12:58:16.567] INFO: ctr: 4
[12:58:16.567] INFO: sda: 19
[12:58:16.567] INFO: tin: 9
[12:58:16.567] INFO: level: 15
[12:58:16.567] INFO: triggerdelay: 0
[12:58:16.567] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[12:58:16.567] INFO: Log level: INFO
[12:58:16.585] QUIET: Connection to board DTB_WREKRL opened.
[12:58:16.589] 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:
------------------------------------------------------
[12:58:16.592] INFO: RPC call hashes of host and DTB match: 398089610
[12:58:18.143] INFO: DUT info:
[12:58:18.143] INFO: The DUT currently contains the following objects:
[12:58:18.143] INFO: 2 TBM Cores tbm08c (2 ON)
[12:58:18.143] INFO: TBM Core alpha (0): 7 registers set
[12:58:18.144] INFO: TBM Core beta (1): 7 registers set
[12:58:18.144] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:58:18.144] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:58:18.144] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:58:18.144] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:58:18.144] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:58:18.144] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:58:18.144] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:58:18.144] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:58:18.144] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:58:18.144] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:58:18.144] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:58:18.144] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:58:18.144] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:58:18.144] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:58:18.144] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:58:18.144] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:58:18.144] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:58:18.584] INFO: enter 'restricted' command line mode
[12:58:18.584] INFO: enter test to run
[12:58:29.729] INFO: test: PixelAlive no parameter change
[12:58:29.729] INFO: running: pixelalive
[12:58:29.796] INFO: ----------------------------------------------------------------------
[12:58:29.796] 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:58:29.796] INFO: ----------------------------------------------------------------------
[12:58:30.116] INFO: Expecting 41600 events.
[12:58:34.574] INFO: 41600 events read in total (3740ms).
[12:58:34.739] INFO: Test took 4940ms.
[12:58:34.754] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:58:35.273] INFO: PixTestAlive::aliveTest() done
[12:58:35.273] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2 0
[12:58:35.447] INFO: enter test to run
[12:59:10.328] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:59:10.328] INFO: running: highrate
[12:59:10.341] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[12:59:10.613] INFO: ----------------------------------------------------------------------
[12:59:10.613] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:59:10.613] INFO: ----------------------------------------------------------------------
[12:59:10.613] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:59:10.613] INFO: edge/corner pixel THR is adjusted
[12:59:10.613] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:59:11.571] INFO: Collecting data for 5 seconds...
[12:59:16.588] INFO: Done with hot pixel readout
[12:59:28.518] INFO: PixTest:: pg_setup set to default.
[12:59:28.519] INFO: 0 hot pixels found in step 0
[12:59:28.532] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:59:28.549] INFO: PixTest::trimHotPixels() done
[12:59:28.570] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C0.dat
[12:59:28.576] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C1.dat
[12:59:28.582] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C2.dat
[12:59:28.587] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C3.dat
[12:59:28.593] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C4.dat
[12:59:28.598] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C5.dat
[12:59:28.603] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C6.dat
[12:59:28.609] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C7.dat
[12:59:28.614] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C8.dat
[12:59:28.619] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C9.dat
[12:59:28.624] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C10.dat
[12:59:28.630] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C11.dat
[12:59:28.635] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C12.dat
[12:59:28.652] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C13.dat
[12:59:28.658] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C14.dat
[12:59:28.664] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C15.dat
[12:59:28.669] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//defaultMaskFile.dat
[12:59:28.681] INFO: enter test to run
[13:00:55.288] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[13:00:55.288] INFO: running: highrate
[13:00:55.292] INFO: ----------------------------------------------------------------------
[13:00:55.292] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[13:00:55.292] INFO: ----------------------------------------------------------------------
[13:00:55.292] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[13:00:55.292] INFO: edge/corner pixel THR is adjusted
[13:00:55.292] INFO: PixTestHighRate::trimHotPixels: step 0...
[13:00:56.250] INFO: Collecting data for 1 seconds...
[13:00:57.254] INFO: Done with hot pixel readout
[13:01:01.269] INFO: PixTest:: pg_setup set to default.
[13:01:01.270] INFO: 0 hot pixels found in step 0
[13:01:01.276] INFO: 0 hot pixels could not be trimmed and have been masked.
[13:01:01.361] INFO: PixTest::trimHotPixels() done
[13:01:01.361] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C0.dat
[13:01:01.389] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C1.dat
[13:01:01.400] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C2.dat
[13:01:01.409] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C3.dat
[13:01:01.414] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C4.dat
[13:01:01.420] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C5.dat
[13:01:01.425] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C6.dat
[13:01:01.431] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C7.dat
[13:01:01.436] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C8.dat
[13:01:01.441] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C9.dat
[13:01:01.447] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C10.dat
[13:01:01.452] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C11.dat
[13:01:01.458] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C12.dat
[13:01:01.463] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C13.dat
[13:01:01.469] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C14.dat
[13:01:01.474] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//trimParameters35_C15.dat
[13:01:01.479] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-P-2-06_FPIXTest-17C-FNAL-160630-1519_2016-06-30_15h19m_1467317990/000_FPIXTest_p17//defaultMaskFile.dat
[13:01:01.489] INFO: enter test to run
[13:02:00.015] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[13:02:00.015] INFO: running: xray
[13:02:00.017] INFO: ----------------------------------------------------------------------
[13:02:00.017] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:02:00.017] INFO: ----------------------------------------------------------------------
[13:02:00.999] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:02:12.592] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[13:02:42.165] INFO: Resuming triggers.
[13:02:53.761] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[13:03:23.329] INFO: Resuming triggers.
[13:03:34.920] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[13:04:04.503] INFO: Resuming triggers.
[13:04:16.098] INFO: run duration 46 seconds, buffer almost full (81%), pausing triggers.
[13:04:45.689] INFO: Resuming triggers.
[13:04:57.278] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[13:05:26.924] INFO: Resuming triggers.
[13:05:38.516] INFO: run duration 69 seconds, buffer almost full (81%), pausing triggers.
[13:06:08.209] INFO: Resuming triggers.
[13:06:19.800] INFO: run duration 80 seconds, buffer almost full (81%), pausing triggers.
[13:06:49.519] INFO: Resuming triggers.
[13:07:01.112] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[13:07:30.637] INFO: Resuming triggers.
[13:07:38.215] INFO: data taking finished, elapsed time: 100 seconds.
[13:07:57.789] INFO: PixTest:: pg_setup set to default.
[13:07:57.792] INFO: PixTestXray::doPhRun() done
[13:07:57.962] INFO: enter test to run
[13:09:01.340] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[13:09:01.340] INFO: running: xray
[13:09:01.342] INFO: ----------------------------------------------------------------------
[13:09:01.342] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[13:09:01.342] INFO: ----------------------------------------------------------------------
[13:09:02.306] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[13:09:09.223] INFO: run duration 6 seconds, buffer almost full (81%), pausing triggers.
[13:09:39.491] INFO: Resuming triggers.
[13:09:46.410] INFO: run duration 13 seconds, buffer almost full (81%), pausing triggers.
[13:10:16.651] INFO: Resuming triggers.
[13:10:23.567] INFO: run duration 20 seconds, buffer almost full (81%), pausing triggers.
[13:10:54.404] INFO: Resuming triggers.
[13:11:01.321] INFO: run duration 27 seconds, buffer almost full (81%), pausing triggers.
[13:11:31.614] INFO: Resuming triggers.
[13:11:38.529] INFO: run duration 34 seconds, buffer almost full (81%), pausing triggers.
[13:12:09.306] INFO: Resuming triggers.
[13:12:16.227] INFO: run duration 41 seconds, buffer almost full (81%), pausing triggers.
[13:12:46.671] INFO: Resuming triggers.
[13:12:53.590] INFO: run duration 48 seconds, buffer almost full (81%), pausing triggers.
[13:13:24.925] INFO: Resuming triggers.
[13:13:31.843] INFO: run duration 55 seconds, buffer almost full (81%), pausing triggers.
[13:14:02.914] INFO: Resuming triggers.
[13:14:09.831] INFO: run duration 62 seconds, buffer almost full (81%), pausing triggers.
[13:14:40.421] INFO: Resuming triggers.
[13:14:47.335] INFO: run duration 68 seconds, buffer almost full (81%), pausing triggers.
[13:15:17.564] INFO: Resuming triggers.
[13:15:24.476] INFO: run duration 75 seconds, buffer almost full (81%), pausing triggers.
[13:15:54.982] INFO: Resuming triggers.
[13:16:01.899] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[13:16:32.133] INFO: Resuming triggers.
[13:16:39.049] INFO: run duration 89 seconds, buffer almost full (81%), pausing triggers.
[13:17:09.350] INFO: Resuming triggers.
[13:17:16.269] INFO: run duration 96 seconds, buffer almost full (81%), pausing triggers.
[13:17:46.707] INFO: Resuming triggers.
[13:17:50.208] INFO: data taking finished, elapsed time: 100 seconds.
[13:18:05.843] INFO: PixTest:: pg_setup set to default.
[13:18:05.846] INFO: PixTestXray::doPhRun() done
[13:18:05.993] INFO: enter test to run
[13:18:34.056] INFO: test: HighRate no parameter change
[13:18:34.057] INFO: running: highrate
[13:18:34.073] INFO: ----------------------------------------------------------------------
[13:18:34.073] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[13:18:34.073] INFO: ----------------------------------------------------------------------
[13:18:34.230] INFO: Expecting 768 events.
[13:18:35.364] INFO: 768 events read in total (419ms).
[13:18:35.364] INFO: Test took 1269ms.
[13:18:36.167] INFO: Expecting 41600 events.
[13:18:39.259] INFO: 41600 events read in total (2565ms).
[13:18:39.260] INFO: Test took 3803ms.
[13:18:39.293] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:40.020] INFO: Expecting 41600 events.
[13:18:43.200] INFO: 41600 events read in total (2653ms).
[13:18:43.201] INFO: Test took 3890ms.
[13:18:43.235] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:43.960] INFO: Expecting 41600 events.
[13:18:47.186] INFO: 41600 events read in total (2699ms).
[13:18:47.187] INFO: Test took 3934ms.
[13:18:47.220] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:47.947] INFO: Expecting 41600 events.
[13:18:51.179] INFO: 41600 events read in total (2705ms).
[13:18:51.180] INFO: Test took 3942ms.
[13:18:51.213] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:51.939] INFO: Expecting 41600 events.
[13:18:55.158] INFO: 41600 events read in total (2692ms).
[13:18:55.159] INFO: Test took 3929ms.
[13:18:55.192] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:55.921] INFO: Expecting 41600 events.
[13:18:59.160] INFO: 41600 events read in total (2712ms).
[13:18:59.161] INFO: Test took 3951ms.
[13:18:59.195] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:18:59.921] INFO: Expecting 41600 events.
[13:19:03.157] INFO: 41600 events read in total (2709ms).
[13:19:03.158] INFO: Test took 3944ms.
[13:19:03.191] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:03.918] INFO: Expecting 41600 events.
[13:19:07.151] INFO: 41600 events read in total (2706ms).
[13:19:07.152] INFO: Test took 3943ms.
[13:19:07.186] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:07.912] INFO: Expecting 41600 events.
[13:19:11.137] INFO: 41600 events read in total (2698ms).
[13:19:11.138] INFO: Test took 3936ms.
[13:19:11.172] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:11.898] INFO: Expecting 41600 events.
[13:19:15.133] INFO: 41600 events read in total (2708ms).
[13:19:15.134] INFO: Test took 3944ms.
[13:19:15.167] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:15.894] INFO: Expecting 41600 events.
[13:19:19.149] INFO: 41600 events read in total (2728ms).
[13:19:19.150] INFO: Test took 3963ms.
[13:19:19.184] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:19.906] INFO: Expecting 41600 events.
[13:19:23.145] INFO: 41600 events read in total (2712ms).
[13:19:23.146] INFO: Test took 3943ms.
[13:19:23.179] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:23.902] INFO: Expecting 41600 events.
[13:19:27.133] INFO: 41600 events read in total (2704ms).
[13:19:27.134] INFO: Test took 3937ms.
[13:19:27.167] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:27.896] INFO: Expecting 41600 events.
[13:19:31.141] INFO: 41600 events read in total (2718ms).
[13:19:31.142] INFO: Test took 3957ms.
[13:19:31.176] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:31.902] INFO: Expecting 41600 events.
[13:19:35.145] INFO: 41600 events read in total (2716ms).
[13:19:35.146] INFO: Test took 3951ms.
[13:19:35.179] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:35.902] INFO: Expecting 41600 events.
[13:19:39.132] INFO: 41600 events read in total (2703ms).
[13:19:39.133] INFO: Test took 3936ms.
[13:19:39.166] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:39.892] INFO: Expecting 41600 events.
[13:19:43.137] INFO: 41600 events read in total (2718ms).
[13:19:43.138] INFO: Test took 3954ms.
[13:19:43.171] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:43.893] INFO: Expecting 41600 events.
[13:19:47.112] INFO: 41600 events read in total (2692ms).
[13:19:47.113] INFO: Test took 3924ms.
[13:19:47.146] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:47.872] INFO: Expecting 41600 events.
[13:19:51.083] INFO: 41600 events read in total (2685ms).
[13:19:51.084] INFO: Test took 3920ms.
[13:19:51.117] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:51.845] INFO: Expecting 41600 events.
[13:19:54.930] INFO: 41600 events read in total (2558ms).
[13:19:54.931] INFO: Test took 3795ms.
[13:19:54.963] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:19:55.330] INFO: enter test to run
[13:20:23.120] INFO: test: HighRate no parameter change
[13:20:23.120] INFO: running: highrate
[13:20:23.121] INFO: ----------------------------------------------------------------------
[13:20:23.121] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:20:23.121] INFO: ----------------------------------------------------------------------
[13:20:23.739] INFO: Expecting 208000 events.
[13:20:35.556] INFO: 208000 events read in total (11290ms).
[13:20:35.559] INFO: Test took 12428ms.
[13:20:35.696] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:20:35.947] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 1 2 0
[13:20:35.947] INFO: number of red-efficiency pixels: 63 36 51 120 111 156 133 88 83 120 109 94 81 59 18 22
[13:20:35.947] INFO: number of X-ray hits detected: 53517 37042 61686 96826 100944 108482 107647 76680 80866 106194 106653 83961 87860 54572 20083 21840
[13:20:35.947] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:20:35.947] INFO: number of Vcal hits detected: 207936 207963 207949 207877 207884 207837 207864 207910 207917 207879 207888 207903 207916 207892 207884 207978
[13:20:35.947] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 100.0
[13:20:35.947] INFO: Vcal hit overall efficiency (%): 100.0 100.0 100.0 99.9 99.9 99.9 99.9 100.0 100.0 99.9 99.9 100.0 100.0 99.9 99.9 100.0
[13:20:35.947] INFO: X-ray hit rate [MHz/cm2]: 15.7 10.9 18.1 28.4 29.6 31.8 31.6 22.5 23.7 31.1 31.3 24.6 25.8 16.0 5.9 6.4
[13:20:35.947] INFO: PixTestHighRate::doXPixelAlive() done
[13:20:35.996] INFO: PixTest:: pg_setup set to default.
[13:20:36.007] INFO: enter test to run
[13:21:04.015] INFO: test: HighRate no parameter change
[13:21:04.015] INFO: running: highrate
[13:21:04.017] INFO: ----------------------------------------------------------------------
[13:21:04.017] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:21:04.017] INFO: ----------------------------------------------------------------------
[13:21:04.630] INFO: Expecting 208000 events.
[13:21:18.079] INFO: 208000 events read in total (12922ms).
[13:21:18.084] INFO: Test took 14057ms.
[13:21:18.367] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:21:18.663] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 1 2 0
[13:21:18.663] INFO: number of red-efficiency pixels: 157 79 164 272 340 459 483 250 250 477 342 308 235 183 46 51
[13:21:18.663] INFO: number of X-ray hits detected: 108420 75820 125548 198091 204696 222258 218046 155644 165360 217857 217694 171501 179626 110825 41119 44254
[13:21:18.663] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:21:18.663] INFO: number of Vcal hits detected: 207835 207920 207827 207715 207633 207503 207466 207731 207739 207469 207636 207659 207752 207758 207856 207949
[13:21:18.663] INFO: Vcal hit fiducial efficiency (%): 99.9 100.0 99.9 99.9 99.8 99.8 99.8 99.9 99.9 99.8 99.8 99.8 99.9 99.9 100.0 100.0
[13:21:18.663] INFO: Vcal hit overall efficiency (%): 99.9 100.0 99.9 99.9 99.8 99.8 99.7 99.9 99.9 99.7 99.8 99.8 99.9 99.9 99.9 100.0
[13:21:18.663] INFO: X-ray hit rate [MHz/cm2]: 31.8 22.2 36.8 58.1 60.0 65.1 63.9 45.6 48.5 63.9 63.8 50.3 52.6 32.5 12.1 13.0
[13:21:18.663] INFO: PixTestHighRate::doXPixelAlive() done
[13:21:18.714] INFO: PixTest:: pg_setup set to default.
[13:21:18.727] INFO: enter test to run
[13:22:18.383] INFO: test: HighRate no parameter change
[13:22:18.383] INFO: running: highrate
[13:22:18.384] INFO: ----------------------------------------------------------------------
[13:22:18.384] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[13:22:18.384] INFO: ----------------------------------------------------------------------
[13:22:19.004] INFO: Expecting 208000 events.
[13:22:34.227] INFO: 208000 events read in total (14696ms).
[13:22:34.234] INFO: Test took 15839ms.
[13:22:34.647] INFO: Fetched DAQ statistics. Counters are being reset now.
[13:22:34.987] INFO: number of dead pixels (per ROC): 0 0 0 0 0 0 0 0 0 0 0 0 0 1 2 0
[13:22:34.987] INFO: number of red-efficiency pixels: 315 150 420 676 788 1099 1075 620 541 1138 817 708 560 443 98 98
[13:22:34.987] INFO: number of X-ray hits detected: 163546 114208 189246 296712 307951 333505 327282 233607 248921 327030 326765 257538 269400 166948 62148 67613
[13:22:34.987] INFO: number of triggers sent (total per ROC): 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000 208000
[13:22:34.987] INFO: number of Vcal hits detected: 207650 207844 207505 207226 207084 206582 206637 207236 207374 206588 207060 207148 207378 207439 207801 207896
[13:22:34.987] INFO: Vcal hit fiducial efficiency (%): 99.9 99.9 99.8 99.7 99.6 99.4 99.4 99.7 99.7 99.4 99.6 99.6 99.7 99.8 100.0 99.9
[13:22:34.987] INFO: Vcal hit overall efficiency (%): 99.8 99.9 99.8 99.6 99.6 99.3 99.3 99.6 99.7 99.3 99.5 99.6 99.7 99.7 99.9 100.0
[13:22:34.987] INFO: X-ray hit rate [MHz/cm2]: 47.9 33.5 55.5 87.0 90.3 97.8 95.9 68.5 73.0 95.9 95.8 75.5 79.0 48.9 18.2 19.8
[13:22:34.987] INFO: PixTestHighRate::doXPixelAlive() done
[13:22:35.039] INFO: PixTest:: pg_setup set to default.
[13:22:35.057] INFO: enter test to run
[13:23:34.687] INFO: test: exit no parameter change
[13:23:35.070] QUIET: Connection to board 33 closed.
[13:23:35.085] INFO: pXar: this is the end, my friend