[12:22:54.938] INFO: *** Welcome to pxar ***
[12:22:54.938] INFO: *** Today: 2016/09/16
[12:22:56.128] INFO: *** Version: v1.9.0-818-g96727
[12:22:56.128] INFO: readRocDacs: /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//dacParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//dacParameters35_C15.dat
[12:22:56.152] INFO: readTbmDacs: /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//tbmParameters_C0a.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//tbmParameters_C0b.dat
[12:22:56.152] INFO: readMaskFile: /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//defaultMaskFile.dat
[12:22:56.154] INFO: readTrimFile: /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C15.dat
[12:22:56.294] INFO: clk: 4
[12:22:56.294] INFO: ctr: 4
[12:22:56.294] INFO: sda: 19
[12:22:56.294] INFO: tin: 9
[12:22:56.294] INFO: level: 15
[12:22:56.294] INFO: triggerdelay: 0
[12:22:56.312] QUIET: Instanciating API for pxar v1.9.0+814~g74979a6
[12:22:56.312] INFO: Log level: INFO
[12:22:56.337] QUIET: Connection to board DTB_WREKRL opened.
[12:22:56.341] 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:22:56.344] INFO: RPC call hashes of host and DTB match: 398089610
[12:22:57.880] INFO: DUT info:
[12:22:57.880] INFO: The DUT currently contains the following objects:
[12:22:57.880] INFO: 2 TBM Cores tbm08c (2 ON)
[12:22:57.892] INFO: TBM Core alpha (0): 7 registers set
[12:22:57.892] INFO: TBM Core beta (1): 7 registers set
[12:22:57.892] INFO: 16 ROCs psi46digv21respin (16 ON) with 4160 pixelConfigs
[12:22:57.892] INFO: ROC 0: 19 DACs set, Pixels: 0 masked, 0 active.
[12:22:57.892] INFO: ROC 1: 19 DACs set, Pixels: 0 masked, 0 active.
[12:22:57.892] INFO: ROC 2: 19 DACs set, Pixels: 0 masked, 0 active.
[12:22:57.892] INFO: ROC 3: 19 DACs set, Pixels: 0 masked, 0 active.
[12:22:57.892] INFO: ROC 4: 19 DACs set, Pixels: 0 masked, 0 active.
[12:22:57.892] INFO: ROC 5: 19 DACs set, Pixels: 0 masked, 0 active.
[12:22:57.892] INFO: ROC 6: 19 DACs set, Pixels: 0 masked, 0 active.
[12:22:57.892] INFO: ROC 7: 19 DACs set, Pixels: 0 masked, 0 active.
[12:22:57.892] INFO: ROC 8: 19 DACs set, Pixels: 0 masked, 0 active.
[12:22:57.892] INFO: ROC 9: 19 DACs set, Pixels: 0 masked, 0 active.
[12:22:57.892] INFO: ROC 10: 19 DACs set, Pixels: 0 masked, 0 active.
[12:22:57.892] INFO: ROC 11: 19 DACs set, Pixels: 0 masked, 0 active.
[12:22:57.892] INFO: ROC 12: 19 DACs set, Pixels: 0 masked, 0 active.
[12:22:57.893] INFO: ROC 13: 19 DACs set, Pixels: 0 masked, 0 active.
[12:22:57.893] INFO: ROC 14: 19 DACs set, Pixels: 0 masked, 0 active.
[12:22:57.893] INFO: ROC 15: 19 DACs set, Pixels: 0 masked, 0 active.
[12:22:58.319] INFO: enter 'restricted' command line mode
[12:22:58.319] INFO: enter test to run
[12:23:43.243] INFO: test: PixelAlive no parameter change
[12:23:43.243] INFO: running: pixelalive
[12:23:43.266] INFO: ----------------------------------------------------------------------
[12:23:43.266] 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:23:43.266] INFO: ----------------------------------------------------------------------
[12:23:43.580] INFO: Expecting 41600 events.
[12:23:47.909] INFO: 41600 events read in total (3611ms).
[12:23:48.079] INFO: Test took 4811ms.
[12:23:48.093] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:23:48.414] INFO: PixTestAlive::aliveTest() done
[12:23:48.414] INFO: number of dead pixels (per ROC): 25 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:23:48.443] INFO: enter test to run
[12:24:09.595] INFO: test: HighRate setting parameters: ->runsecondshotpixels=5;trimhotpixelthr=200<-
[12:24:09.595] INFO: running: highrate
[12:24:09.609] INFO: readGainPedestalParameters /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//phCalibrationFitErr35_C0.dat .. /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//phCalibrationFitErr35_C15.dat
[12:24:09.875] INFO: ----------------------------------------------------------------------
[12:24:09.875] INFO: PixTest::trimHotPixels() running for 5 seconds with 100 kHz trigger rate
[12:24:09.875] INFO: ----------------------------------------------------------------------
[12:24:09.875] INFO: THR = 200, corresponding to ~ 106.667 MHz/cm2
[12:24:09.875] INFO: edge/corner pixel THR is adjusted
[12:24:09.875] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:24:10.864] INFO: Collecting data for 5 seconds...
[12:24:15.880] INFO: Done with hot pixel readout
[12:24:27.344] INFO: PixTest:: pg_setup set to default.
[12:24:27.345] INFO: 2 hot pixels found in step 0
[12:24:28.353] INFO: Collecting data for 5 seconds...
[12:24:33.370] INFO: Done with hot pixel readout
[12:24:44.867] INFO: PixTest:: pg_setup set to default.
[12:24:44.868] INFO: 1 hot pixels found in step 1
[12:24:45.859] INFO: Collecting data for 5 seconds...
[12:24:50.875] INFO: Done with hot pixel readout
[12:25:02.315] INFO: PixTest:: pg_setup set to default.
[12:25:02.316] INFO: 1 hot pixels found in step 2
[12:25:03.306] INFO: Collecting data for 5 seconds...
[12:25:08.323] INFO: Done with hot pixel readout
[12:25:20.146] INFO: PixTest:: pg_setup set to default.
[12:25:20.147] INFO: 0 hot pixels found in step 3
[12:25:20.183] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:25:20.186] INFO: PixTest::trimHotPixels() done
[12:25:20.186] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C0.dat
[12:25:20.195] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C1.dat
[12:25:20.202] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C2.dat
[12:25:20.208] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C3.dat
[12:25:20.213] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C4.dat
[12:25:20.218] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C5.dat
[12:25:20.224] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C6.dat
[12:25:20.229] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C7.dat
[12:25:20.235] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C8.dat
[12:25:20.240] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C9.dat
[12:25:20.246] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C10.dat
[12:25:20.251] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C11.dat
[12:25:20.257] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C12.dat
[12:25:20.262] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C13.dat
[12:25:20.267] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C14.dat
[12:25:20.273] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C15.dat
[12:25:20.278] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//defaultMaskFile.dat
[12:25:20.289] INFO: enter test to run
[12:26:05.954] INFO: test: HighRate setting parameters: ->runsecondshotpixels=1;trimhotpixelthr=200<-
[12:26:05.954] INFO: running: highrate
[12:26:05.958] INFO: ----------------------------------------------------------------------
[12:26:05.958] INFO: PixTest::trimHotPixels() running for 1 seconds with 100 kHz trigger rate
[12:26:05.958] INFO: ----------------------------------------------------------------------
[12:26:05.958] INFO: THR = 200, corresponding to ~ 533.333 MHz/cm2
[12:26:05.958] INFO: edge/corner pixel THR is adjusted
[12:26:05.958] INFO: PixTestHighRate::trimHotPixels: step 0...
[12:26:06.915] INFO: Collecting data for 1 seconds...
[12:26:07.919] INFO: Done with hot pixel readout
[12:26:11.770] INFO: PixTest:: pg_setup set to default.
[12:26:11.771] INFO: 0 hot pixels found in step 0
[12:26:11.777] INFO: 0 hot pixels could not be trimmed and have been masked.
[12:26:11.875] INFO: PixTest::trimHotPixels() done
[12:26:11.875] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C0.dat
[12:26:11.883] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C1.dat
[12:26:11.888] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C2.dat
[12:26:11.893] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C3.dat
[12:26:11.899] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C4.dat
[12:26:11.904] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C5.dat
[12:26:11.910] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C6.dat
[12:26:11.915] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C7.dat
[12:26:11.921] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C8.dat
[12:26:11.926] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C9.dat
[12:26:11.931] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C10.dat
[12:26:11.937] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C11.dat
[12:26:11.942] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C12.dat
[12:26:11.948] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C13.dat
[12:26:11.953] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C14.dat
[12:26:11.958] INFO: write trim parameters into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//trimParameters35_C15.dat
[12:26:11.964] INFO: write masked pixels into /home/uicpirepix2/ProductionTestResults/M-Q-4-11_FPIXTest-17C-FNAL-160909-0931-300V_2016-09-09_09h31m_1473431488/000_FPIXTest_p17//defaultMaskFile.dat
[12:26:11.996] INFO: enter test to run
[12:26:46.738] INFO: test: Xray setting parameters: ->source=DCLowRate<-
[12:26:46.738] INFO: running: xray
[12:26:46.739] INFO: ----------------------------------------------------------------------
[12:26:46.739] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:26:46.739] INFO: ----------------------------------------------------------------------
[12:26:47.702] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:26:59.548] INFO: run duration 11 seconds, buffer almost full (81%), pausing triggers.
[12:27:29.331] INFO: Resuming triggers.
[12:27:41.178] INFO: run duration 23 seconds, buffer almost full (81%), pausing triggers.
[12:28:10.989] INFO: Resuming triggers.
[12:28:22.846] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[12:28:52.671] INFO: Resuming triggers.
[12:29:04.518] INFO: run duration 47 seconds, buffer almost full (81%), pausing triggers.
[12:29:33.791] INFO: Resuming triggers.
[12:29:45.637] INFO: run duration 59 seconds, buffer almost full (81%), pausing triggers.
[12:30:16.180] INFO: Resuming triggers.
[12:30:28.026] INFO: run duration 70 seconds, buffer almost full (81%), pausing triggers.
[12:30:58.109] INFO: Resuming triggers.
[12:31:09.954] INFO: run duration 82 seconds, buffer almost full (81%), pausing triggers.
[12:31:40.101] INFO: Resuming triggers.
[12:31:51.948] INFO: run duration 94 seconds, buffer almost full (81%), pausing triggers.
[12:32:22.431] INFO: Resuming triggers.
[12:32:28.018] INFO: data taking finished, elapsed time: 100 seconds.
[12:32:42.569] INFO: PixTest:: pg_setup set to default.
[12:32:42.572] INFO: PixTestXray::doPhRun() done
[12:32:42.801] INFO: enter test to run
[12:34:02.407] INFO: test: Xray setting parameters: ->source=DCHighRate<-
[12:34:02.407] INFO: running: xray
[12:34:02.408] INFO: ----------------------------------------------------------------------
[12:34:02.408] INFO: PixTestXray::doPhRun() fParRunSeconds = 100
[12:34:02.408] INFO: ----------------------------------------------------------------------
[12:34:03.372] INFO: PixTestXray::doPhRun start TriggerLoop with trigger frequency 100 kHz, period 405 and duration 100 seconds, fEventsMax = 10000000
[12:34:10.538] INFO: run duration 7 seconds, buffer almost full (81%), pausing triggers.
[12:34:40.774] INFO: Resuming triggers.
[12:34:47.940] INFO: run duration 14 seconds, buffer almost full (81%), pausing triggers.
[12:35:18.051] INFO: Resuming triggers.
[12:35:25.219] INFO: run duration 21 seconds, buffer almost full (81%), pausing triggers.
[12:35:55.406] INFO: Resuming triggers.
[12:36:02.572] INFO: run duration 28 seconds, buffer almost full (81%), pausing triggers.
[12:36:32.771] INFO: Resuming triggers.
[12:36:39.939] INFO: run duration 35 seconds, buffer almost full (81%), pausing triggers.
[12:37:10.114] INFO: Resuming triggers.
[12:37:17.284] INFO: run duration 42 seconds, buffer almost full (81%), pausing triggers.
[12:37:47.506] INFO: Resuming triggers.
[12:37:54.672] INFO: run duration 50 seconds, buffer almost full (81%), pausing triggers.
[12:38:24.832] INFO: Resuming triggers.
[12:38:31.003] INFO: run duration 57 seconds, buffer almost full (81%), pausing triggers.
[12:39:02.240] INFO: Resuming triggers.
[12:39:09.408] INFO: run duration 64 seconds, buffer almost full (81%), pausing triggers.
[12:39:40.260] INFO: Resuming triggers.
[12:39:47.430] INFO: run duration 71 seconds, buffer almost full (81%), pausing triggers.
[12:40:18.192] INFO: Resuming triggers.
[12:40:25.361] INFO: run duration 78 seconds, buffer almost full (81%), pausing triggers.
[12:40:56.339] INFO: Resuming triggers.
[12:41:03.509] INFO: run duration 85 seconds, buffer almost full (81%), pausing triggers.
[12:41:34.570] INFO: Resuming triggers.
[12:41:41.738] INFO: run duration 92 seconds, buffer almost full (81%), pausing triggers.
[12:42:12.591] INFO: Resuming triggers.
[12:42:19.745] INFO: data taking finished, elapsed time: 100 seconds.
[12:42:50.638] INFO: PixTest:: pg_setup set to default.
[12:42:50.642] INFO: PixTestXray::doPhRun() done
[12:42:50.794] INFO: enter test to run
[12:43:43.555] INFO: test: HighRate no parameter change
[12:43:43.555] INFO: running: highrate
[12:43:43.567] INFO: ----------------------------------------------------------------------
[12:43:43.567] INFO: PixTestHighRate::calDelScan() ntrig = 10, vcal = 200
[12:43:43.567] INFO: ----------------------------------------------------------------------
[12:43:43.734] INFO: Expecting 768 events.
[12:43:44.878] INFO: 768 events read in total (429ms).
[12:43:44.878] INFO: Test took 1290ms.
[12:43:45.681] INFO: Expecting 41600 events.
[12:43:48.827] INFO: 41600 events read in total (2619ms).
[12:43:48.828] INFO: Test took 3929ms.
[12:43:48.864] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:49.587] INFO: Expecting 41600 events.
[12:43:52.760] INFO: 41600 events read in total (2646ms).
[12:43:52.761] INFO: Test took 3878ms.
[12:43:52.793] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:53.519] INFO: Expecting 41600 events.
[12:43:56.845] INFO: 41600 events read in total (2799ms).
[12:43:56.845] INFO: Test took 4035ms.
[12:43:56.879] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:43:57.601] INFO: Expecting 41600 events.
[12:44:00.850] INFO: 41600 events read in total (2722ms).
[12:44:00.851] INFO: Test took 3954ms.
[12:44:00.883] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:01.609] INFO: Expecting 41600 events.
[12:44:04.943] INFO: 41600 events read in total (2808ms).
[12:44:04.943] INFO: Test took 4043ms.
[12:44:04.976] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:05.701] INFO: Expecting 41600 events.
[12:44:08.976] INFO: 41600 events read in total (2748ms).
[12:44:08.978] INFO: Test took 3985ms.
[12:44:09.011] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:09.723] INFO: Expecting 41600 events.
[12:44:13.065] INFO: 41600 events read in total (2815ms).
[12:44:13.066] INFO: Test took 4038ms.
[12:44:13.099] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:13.823] INFO: Expecting 41600 events.
[12:44:17.114] INFO: 41600 events read in total (2764ms).
[12:44:17.115] INFO: Test took 3999ms.
[12:44:17.147] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:17.875] INFO: Expecting 41600 events.
[12:44:21.188] INFO: 41600 events read in total (2786ms).
[12:44:21.189] INFO: Test took 4024ms.
[12:44:21.221] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:21.943] INFO: Expecting 41600 events.
[12:44:25.255] INFO: 41600 events read in total (2785ms).
[12:44:25.256] INFO: Test took 4017ms.
[12:44:25.289] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:25.003] INFO: Expecting 41600 events.
[12:44:29.216] INFO: 41600 events read in total (2686ms).
[12:44:29.217] INFO: Test took 3911ms.
[12:44:29.250] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:29.974] INFO: Expecting 41600 events.
[12:44:33.213] INFO: 41600 events read in total (2712ms).
[12:44:33.214] INFO: Test took 3947ms.
[12:44:33.246] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:33.970] INFO: Expecting 41600 events.
[12:44:37.264] INFO: 41600 events read in total (2767ms).
[12:44:37.265] INFO: Test took 4002ms.
[12:44:37.300] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:38.022] INFO: Expecting 41600 events.
[12:44:41.256] INFO: 41600 events read in total (2707ms).
[12:44:41.258] INFO: Test took 3940ms.
[12:44:41.292] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:42.010] INFO: Expecting 41600 events.
[12:44:45.240] INFO: 41600 events read in total (2704ms).
[12:44:45.241] INFO: Test took 3930ms.
[12:44:45.272] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:45.000] INFO: Expecting 41600 events.
[12:44:49.214] INFO: 41600 events read in total (2687ms).
[12:44:49.215] INFO: Test took 3925ms.
[12:44:49.247] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:49.976] INFO: Expecting 41600 events.
[12:44:53.259] INFO: 41600 events read in total (2756ms).
[12:44:53.260] INFO: Test took 3996ms.
[12:44:53.294] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:54.008] INFO: Expecting 41600 events.
[12:44:57.269] INFO: 41600 events read in total (2735ms).
[12:44:57.270] INFO: Test took 3958ms.
[12:44:57.303] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:44:58.023] INFO: Expecting 41600 events.
[12:45:01.232] INFO: 41600 events read in total (2682ms).
[12:45:01.233] INFO: Test took 3913ms.
[12:45:01.266] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:01.990] INFO: Expecting 41600 events.
[12:45:05.108] INFO: 41600 events read in total (2592ms).
[12:45:05.109] INFO: Test took 3826ms.
[12:45:05.140] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:05.507] INFO: enter test to run
[12:45:32.210] INFO: test: HighRate no parameter change
[12:45:32.210] INFO: running: highrate
[12:45:32.211] INFO: ----------------------------------------------------------------------
[12:45:32.211] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:45:32.211] INFO: ----------------------------------------------------------------------
[12:45:32.831] INFO: Expecting 208000 events.
[12:45:44.887] INFO: 208000 events read in total (11529ms).
[12:45:44.890] INFO: Test took 12670ms.
[12:45:45.028] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:45:45.279] INFO: number of dead pixels (per ROC): 25 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:45:45.279] INFO: number of red-efficiency pixels: 102 46 63 98 103 110 124 76 64 109 97 105 98 75 27 14
[12:45:45.279] INFO: number of X-ray hits detected: 60645 36738 58076 90834 98306 103146 102156 68687 71568 96758 94092 81698 84075 51156 19154 21369
[12:45:45.279] 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:45:45.279] INFO: number of Vcal hits detected: 206671 207953 207936 207899 207896 207887 207873 207923 207935 207888 207901 207894 207901 207924 207973 207986
[12:45:45.279] INFO: Vcal hit fiducial efficiency (%): 100.0 100.0 100.0 100.0 100.0 99.9 99.9 100.0 100.0 100.0 100.0 99.9 100.0 100.0 100.0 100.0
[12:45:45.279] INFO: Vcal hit overall efficiency (%): 99.4 100.0 100.0 100.0 100.0 99.9 99.9 100.0 100.0 99.9 100.0 99.9 100.0 100.0 100.0 100.0
[12:45:45.279] INFO: X-ray hit rate [MHz/cm2]: 17.8 10.8 17.0 26.6 28.8 30.2 29.9 20.1 21.0 28.4 27.6 23.9 24.6 15.0 5.6 6.3
[12:45:45.279] INFO: PixTestHighRate::doXPixelAlive() done
[12:45:45.326] INFO: PixTest:: pg_setup set to default.
[12:45:45.340] INFO: enter test to run
[12:46:11.506] INFO: test: HighRate no parameter change
[12:46:11.506] INFO: running: highrate
[12:46:11.507] INFO: ----------------------------------------------------------------------
[12:46:11.507] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:46:11.507] INFO: ----------------------------------------------------------------------
[12:46:12.132] INFO: Expecting 208000 events.
[12:46:25.646] INFO: 208000 events read in total (12987ms).
[12:46:25.650] INFO: Test took 14134ms.
[12:46:25.923] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:46:26.214] INFO: number of dead pixels (per ROC): 25 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:46:26.214] INFO: number of red-efficiency pixels: 214 100 198 268 407 363 465 196 209 374 367 313 258 167 37 55
[12:46:26.214] INFO: number of X-ray hits detected: 123553 74950 118766 184626 200054 210381 206956 140542 146984 198160 193365 165729 171965 104967 39047 43617
[12:46:26.214] 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:46:26.214] INFO: number of Vcal hits detected: 206550 207893 207794 207720 207568 207614 207484 207790 207779 207599 207610 207663 207732 207825 207962 207944
[12:46:26.214] 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.9 99.9 99.9 100.0 100.0
[12:46:26.214] INFO: Vcal hit overall efficiency (%): 99.3 99.9 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
[12:46:26.214] INFO: X-ray hit rate [MHz/cm2]: 36.2 22.0 34.8 54.1 58.6 61.7 60.7 41.2 43.1 58.1 56.7 48.6 50.4 30.8 11.4 12.8
[12:46:26.214] INFO: PixTestHighRate::doXPixelAlive() done
[12:46:26.266] INFO: PixTest:: pg_setup set to default.
[12:46:26.277] INFO: enter test to run
[12:47:13.833] INFO: test: HighRate no parameter change
[12:47:13.833] INFO: running: highrate
[12:47:13.835] INFO: ----------------------------------------------------------------------
[12:47:13.835] INFO: PixTestHighRate::xPixelAlive() ntrig = 50, vcal = 200
[12:47:13.835] INFO: ----------------------------------------------------------------------
[12:47:14.450] INFO: Expecting 208000 events.
[12:47:29.358] INFO: 208000 events read in total (14382ms).
[12:47:29.364] INFO: Test took 15519ms.
[12:47:29.748] INFO: Fetched DAQ statistics. Counters are being reset now.
[12:47:30.084] INFO: number of dead pixels (per ROC): 25 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0
[12:47:30.085] INFO: number of red-efficiency pixels: 387 208 383 644 958 800 1060 429 434 891 861 681 559 330 83 74
[12:47:30.085] INFO: number of X-ray hits detected: 186247 112982 180147 279354 303997 318571 314145 212557 221771 300239 292638 250732 259916 159537 59698 66301
[12:47:30.085] 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:47:30.085] INFO: number of Vcal hits detected: 206363 207786 207547 207267 206836 207059 206695 207506 207516 206938 206987 207209 207394 207623 207916 207926
[12:47:30.085] INFO: Vcal hit fiducial efficiency (%): 99.8 99.9 99.8 99.7 99.5 99.6 99.4 99.8 99.8 99.5 99.6 99.7 99.7 99.8 100.0 100.0
[12:47:30.085] INFO: Vcal hit overall efficiency (%): 99.2 99.9 99.8 99.6 99.4 99.5 99.4 99.8 99.8 99.5 99.5 99.6 99.7 99.8 100.0 100.0
[12:47:30.085] INFO: X-ray hit rate [MHz/cm2]: 54.6 33.1 52.8 81.9 89.1 93.4 92.1 62.3 65.0 88.0 85.8 73.5 76.2 46.8 17.5 19.4
[12:47:30.085] INFO: PixTestHighRate::doXPixelAlive() done
[12:47:30.136] INFO: PixTest:: pg_setup set to default.
[12:47:30.151] INFO: enter test to run
[12:47:52.273] INFO: test: exit no parameter change
[12:47:52.671] QUIET: Connection to board 33 closed.
[12:47:52.687] INFO: pXar: this is the end, my friend