Basic Optimization Setup
Setting up and running optimization in Jupyter¶
In [1]:
Copied!
from geecs_scanner.logging_setup import ensure_logging
import logging
from geecs_scanner.data_acquisition.types import ScanConfig, ScanMode
# from geecs_data_utils.types import ScanConfig, ScanMode
from geecs_scanner.data_acquisition.default_scan_manager import get_default_scan_manager
from geecs_scanner.utils import ApplicationPaths
from pathlib import Path
from geecs_data_utils.scan_data import ScanPaths
from image_analysis.config_loader import set_config_base_dir
ensure_logging(
level=logging.INFO, # or INFO
console=True,
log_dir=None, # or a path if you want a file
max_bytes=10_000_000,
backup_count=5,
)
set_config_base_dir(ScanPaths.paths_config.image_analysis_configs_path)
logging.getLogger("data_logger").setLevel(logging.WARNING)
logging.getLogger("geecs_scanner").setLevel(logging.INFO)
logging.getLogger("geecs_python_api").setLevel(logging.WARNING)
logging.getLogger("image_analysis").setLevel(logging.WARNING)
logging.getLogger("scan_analysis").setLevel(logging.WARNING)
# load the default experiment configuration for Undulator (shot control, etc.)
manager = get_default_scan_manager("Undulator")
manager.shot_control_variables["Trigger.Source"]["OFF"] = "Single shot"
manager.shot_control_variables["Trigger.Source"]["SCAN"] = "Internal"
app_paths = ApplicationPaths(experiment="Undulator")
# Define path to the optimizer config. See documentation for optimizer config structure
optimizer_config_path = (
app_paths.exp_optimization_routines / "bax_alignment_example.yaml"
)
from geecs_scanner.logging_setup import ensure_logging
import logging
from geecs_scanner.data_acquisition.types import ScanConfig, ScanMode
# from geecs_data_utils.types import ScanConfig, ScanMode
from geecs_scanner.data_acquisition.default_scan_manager import get_default_scan_manager
from geecs_scanner.utils import ApplicationPaths
from pathlib import Path
from geecs_data_utils.scan_data import ScanPaths
from image_analysis.config_loader import set_config_base_dir
ensure_logging(
level=logging.INFO, # or INFO
console=True,
log_dir=None, # or a path if you want a file
max_bytes=10_000_000,
backup_count=5,
)
set_config_base_dir(ScanPaths.paths_config.image_analysis_configs_path)
logging.getLogger("data_logger").setLevel(logging.WARNING)
logging.getLogger("geecs_scanner").setLevel(logging.INFO)
logging.getLogger("geecs_python_api").setLevel(logging.WARNING)
logging.getLogger("image_analysis").setLevel(logging.WARNING)
logging.getLogger("scan_analysis").setLevel(logging.WARNING)
# load the default experiment configuration for Undulator (shot control, etc.)
manager = get_default_scan_manager("Undulator")
manager.shot_control_variables["Trigger.Source"]["OFF"] = "Single shot"
manager.shot_control_variables["Trigger.Source"]["SCAN"] = "Internal"
app_paths = ApplicationPaths(experiment="Undulator")
# Define path to the optimizer config. See documentation for optimizer config structure
optimizer_config_path = (
app_paths.exp_optimization_routines / "bax_alignment_example.yaml"
)
23:15:51 INFO numexpr.utils - NumExpr defaulting to 8 threads.
23:15:52 INFO geecs_python_api.controls.interface.geecs_database - database config loaded for loasis@192.168.6.14
C:\Users\loasis.LOASIS\AppData\Local\Temp\ipykernel_6068\792359383.py:12: DeprecationWarning: geecs_scanner.data_acquisition.types.ScanConfig/ScanMode have moved to geecs_data_utils.types; please update your imports.
from geecs_scanner.data_acquisition.types import ScanConfig, ScanMode
23:15:59 INFO image_analysis.config_loader - Config cache cleared
23:15:59 INFO image_analysis.config_loader - Config base dir set to C:\GEECS\Developers Version\source\GEECS-Plugins-Configs\image_analysis_configs
23:16:00 INFO geecs_scanner.data_acquisition.device_manager - Loaded composite variables from C:\GEECS\Developers Version\source\GEECS-Plugins-Configs\scanner_configs\experiments\Undulator\scan_devices\composite_variables.yaml
23:16:00 INFO geecs_scanner.data_acquisition.action_manager - Successfully loaded master actions from C:\GEECS\Developers Version\source\GEECS-Plugins-Configs\scanner_configs\experiments\Undulator\action_library\actions.yaml
23:16:00 INFO geecs_scanner.data_acquisition.scan_executor - Constructing the scan step executor
23:16:00 INFO geecs_scanner.data_acquisition.device_manager - synchronous variables after reset: []
23:16:00 INFO geecs_scanner.data_acquisition.device_manager - asynchronous variables after reset: []
23:16:00 INFO geecs_scanner.data_acquisition.device_manager - non_scalar_saving_devices devices after reset: []
23:16:00 INFO geecs_scanner.data_acquisition.device_manager - devices devices after reset: {}
23:16:00 INFO geecs_scanner.data_acquisition.device_manager - DeviceManager instance has been reset and is ready for reinitialization.
23:16:00 INFO geecs_scanner.data_acquisition.device_manager - DeviceManager instance has been reinitialized.
23:16:00 INFO geecs_scanner.data_acquisition.scan_manager - config dictionary in reinitialize: None
23:16:00 INFO geecs_scanner.data_acquisition.scan_manager - Scan config: <class 'geecs_data_utils.type_defs.ScanConfig'>
23:16:00 INFO geecs_scanner.data_acquisition.scan_manager - scan config getting sent to pre logging is this: <class 'geecs_data_utils.type_defs.ScanConfig'>
23:16:00 INFO geecs_scanner.data_acquisition.scan_manager - Turning off the trigger.
23:16:00 INFO geecs_scanner.data_acquisition.scan_manager - Scan thread started.
23:16:00 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:16:00 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:16:00 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:16:00 INFO geecs_data_utils.scan_paths - Created folder: Z:\data\Undulator\Y2025\10-Oct\25_1028\scans\Scan020
23:16:00 INFO geecs_scanner.data_acquisition.scan_data_manager - TDMS writer initialized with path: Z:\data\Undulator\Y2025\10-Oct\25_1028\scans\Scan020\Scan020.tdms
23:16:01 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Device requirements parsed from config: {'Devices': {'UC_ALineEBeam3': {'add_all_variables': False, 'save_nonscalar_data': True, 'synchronous': True, 'variable_list': ['acq_timestamp']}}}
23:16:01 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Created Array2DScanAnalyzer for device 'UC_ALineEBeam3' with BeamAnalyzer
23:16:01 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Initialized BeamPositionEvaluator with 1 analyzer(s)
23:16:01 INFO xopt.generator - Initialized generator BAX
23:16:01 INFO geecs_scanner.data_acquisition.device_manager - config dict is {'Devices': {'UC_ALineEBeam3': {'add_all_variables': False, 'save_nonscalar_data': True, 'synchronous': True, 'variable_list': ['acq_timestamp']}}}
23:16:01 INFO geecs_scanner.data_acquisition.device_manager - validated SaveDeviceConfig is Devices={'UC_ALineEBeam3': DeviceConfig(synchronous=True, save_nonscalar_data=True, variable_list=['acq_timestamp'], add_all_variables=False, post_analysis_class=None, scan_setup=None)} scan_info=None setup_action=None closeout_action=None
23:16:01 INFO geecs_scanner.data_acquisition.device_manager - UC_ALineEBeam3: Synchronous = True, Save_Non_Scalar = True
23:16:01 INFO geecs_scanner.data_acquisition.device_manager - Subscribing UC_ALineEBeam3 to variables: ['acq_timestamp']
23:16:01 INFO geecs_scanner.data_acquisition.device_manager - Devices loaded: ['UC_ALineEBeam3']
23:16:01 INFO geecs_scanner.data_acquisition.device_manager - Loaded scan info:
23:16:01 INFO geecs_scanner.data_acquisition.device_manager - Adding new scan device: U_S1H with default settings.
23:16:01 INFO geecs_scanner.data_acquisition.device_manager - Subscribing U_S1H to variables: ['Current']
23:16:01 INFO geecs_scanner.data_acquisition.device_manager - Scan device U_S1H added to async_observables.
23:16:01 INFO geecs_scanner.data_acquisition.device_manager - Adding new scan device: U_EMQTripletBipolar with default settings.
23:16:01 INFO geecs_scanner.data_acquisition.device_manager - Subscribing U_EMQTripletBipolar to variables: ['Current_Limit.Ch1']
23:16:01 INFO geecs_scanner.data_acquisition.device_manager - Scan device U_EMQTripletBipolar added to async_observables.
23:16:01 INFO geecs_scanner.data_acquisition.scan_manager - scan steps generate: [{'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}]
23:16:01 INFO geecs_scanner.data_acquisition.scan_manager - steps for the scan are : [{'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}]
23:16:01 INFO geecs_scanner.data_acquisition.scan_data_manager - Configuring save paths for device: UC_ALineEBeam3
23:16:02 INFO geecs_scanner.data_acquisition.scan_data_manager - Attempting to write to Z:\data\Undulator\Y2025\10-Oct\25_1028\scans\Scan020\ScanInfoScan020.ini
23:16:02 INFO geecs_scanner.data_acquisition.scan_data_manager - Scan info written to Z:\data\Undulator\Y2025\10-Oct\25_1028\scans\Scan020\ScanInfoScan020.ini
23:16:02 INFO geecs_scanner.data_acquisition.scan_manager - scan config in pre logging is this: <class 'geecs_data_utils.type_defs.ScanConfig'>
23:16:02 INFO geecs_scanner.data_acquisition.device_manager - Handling scan variables with mode: ScanMode.OPTIMIZATION
23:16:02 INFO geecs_scanner.data_acquisition.device_manager - OPTIMIZATION mode: assume devices will be set dynamically.
23:16:03 INFO geecs_scanner.data_acquisition.scan_manager - Attempting to execute pre-scan actions.
23:16:03 INFO geecs_scanner.data_acquisition.scan_manager - Action list steps=[]
23:16:03 INFO geecs_scanner.data_acquisition.action_manager - Added action sequence: setup_action
23:16:03 INFO geecs_scanner.data_acquisition.action_manager - Starting execution of action sequence: setup_action
23:16:03 INFO geecs_scanner.data_acquisition.action_manager - Checking connectivity for 0 devices
23:16:03 INFO geecs_scanner.data_acquisition.action_manager - Successfully completed action sequence: setup_action
23:16:03 INFO geecs_scanner.data_acquisition.scan_manager - attempting to generate ECS live dump using
23:16:03 INFO geecs_scanner.data_acquisition.scan_manager - attempting to generate ECS live dump using
23:16:03 INFO geecs_scanner.data_acquisition.scan_manager - sending comands to MC to generate ECS live dump
23:16:03 ERROR geecs_python_api.controls.interface.udp_handler - failed to send/ack scan command
Traceback (most recent call last):
File "C:\GEECS\Developers Version\source\GEECS-Plugins\GEECS-PythonAPI\geecs_python_api\controls\interface\udp_handler.py", line 122, in send_scan_cmd
sock_mc.sendto(cmd.encode("ascii"), (client_ip, self.mc_port + 2))
OSError: [WinError 10049] The requested address is not valid in its context
23:16:04 WARNING geecs_scanner.data_acquisition.scan_manager - Failed to generate an ECS live dump
23:16:04 INFO geecs_scanner.data_acquisition.scan_manager - Pre-logging setup completed.
23:16:04 INFO geecs_scanner.data_acquisition.scan_manager - scan Scan020: starting (dir=Z:\data\Undulator\Y2025\10-Oct\25_1028\scans\Scan020)
23:16:04 INFO geecs_scanner.data_acquisition.scan_manager - Estimated scan time: 60.0
23:16:04 INFO geecs_scanner.data_acquisition.scan_manager - Estimated acquisition time based on scan config: 60.0 seconds.
23:16:04 INFO geecs_scanner.data_acquisition.scan_manager - options dict: {'rep_rate_hz': 1, 'Save Hiatus Period (s)': '', 'On-Shot TDMS': False, 'Master Control IP': '192.168.7.203'}
23:16:04 INFO geecs_scanner.data_acquisition.scan_manager - add data saving here
23:16:04 INFO geecs_scanner.data_acquisition.data_logger - FileMover worker started.
23:16:04 INFO geecs_scanner.data_acquisition.data_logger - Registering logging for event-driven observable: UC_ALineEBeam3:acq_timestamp
23:16:04 INFO geecs_scanner.data_acquisition.data_logger - Logging has started for all event-driven devices.
23:16:04 INFO geecs_scanner.data_acquisition.scan_manager - Using timeout-based synchronization method
23:16:04 INFO geecs_scanner.data_acquisition.data_logger - First TCP event received from UC_ALineEBeam3. Initial dummy timestamp set to 3844563115.077.
23:16:04 INFO geecs_scanner.data_acquisition.data_logger - Not all devices are in standby: {'UC_ALineEBeam3': None}
23:16:05 INFO geecs_scanner.data_acquisition.data_logger - checking standby status of UC_ALineEBeam3
23:16:05 INFO geecs_scanner.data_acquisition.data_logger - UC_ALineEBeam3 is in standby
23:16:05 INFO geecs_scanner.data_acquisition.data_logger - All device names are present in standby_mode_device_status dict and all have True status.
23:16:05 INFO geecs_scanner.data_acquisition.scan_manager - Sending single-shot trigger to synchronize devices.
23:16:05 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.ExecuteSingleShot to on
23:16:05 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state SINGLESHOT.
23:16:05 INFO geecs_scanner.data_acquisition.scan_manager - Result of single shot command: ['on']
23:16:05 INFO geecs_scanner.data_acquisition.data_logger - checking standby status of UC_ALineEBeam3
23:16:05 INFO geecs_scanner.data_acquisition.data_logger - UC_ALineEBeam3 has exited in standby
23:16:05 INFO geecs_scanner.data_acquisition.data_logger - All device names are present in standby_mode_device_status dict and all have False status meaning they have exited standby mode.
23:16:07 INFO geecs_scanner.data_acquisition.scan_manager - Devices synchronized using timeout method.
23:16:07 INFO geecs_scanner.data_acquisition.scan_data_manager - Purging directory: \\192.168.7.161\SharedData\UC_ALineEBeam3
23:16:07 INFO geecs_scanner.data_acquisition.scan_data_manager - Removed file: \\192.168.7.161\SharedData\UC_ALineEBeam3\UC_ALineEBeam3_3844563365.925.png
23:16:07 INFO geecs_scanner.data_acquisition.scan_executor - Attempting to start scan loop with steps: [{'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}, {'variables': {}, 'wait_time': 4, 'is_composite': False}]
23:16:07 INFO geecs_scanner.data_acquisition.scan_executor - Executing scan step: {'variables': {}, 'wait_time': 4, 'is_composite': False}
23:16:07 INFO geecs_scanner.data_acquisition.scan_executor - Preparing scan step: {'variables': {}, 'wait_time': 4, 'is_composite': False}
23:16:07 INFO geecs_scanner.data_acquisition.scan_executor - Pausing logging. Turning trigger off before moving devices.
23:16:08 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:16:08 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:16:08 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:16:08 INFO geecs_scanner.data_acquisition.scan_executor - Moving devices for step: {}
23:16:08 INFO geecs_scanner.data_acquisition.scan_executor - No variables to move for this scan step.
23:16:08 INFO geecs_scanner.data_acquisition.scan_executor - Waiting for acquisition: {'variables': {}, 'wait_time': 4, 'is_composite': False}
23:16:08 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 4.0
23:16:08 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Internal
23:16:08 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state SCAN.
23:16:09 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 4.0. reported by UC_ALineEBeam3
23:16:09 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 0.90048 for elapsed time 4.0.
23:16:09 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.629097 for elapsed time 4.0.
23:16:10 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 5.0. reported by UC_ALineEBeam3
23:16:10 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 0.90048 for elapsed time 5.0.
23:16:10 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.629097 for elapsed time 5.0.
23:16:11 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 6.0. reported by UC_ALineEBeam3
23:16:11 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 0.90048 for elapsed time 6.0.
23:16:11 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.629097 for elapsed time 6.0.
23:16:12 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 7.0. reported by UC_ALineEBeam3
23:16:12 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 0.90048 for elapsed time 7.0.
23:16:12 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.629097 for elapsed time 7.0.
23:16:12 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:16:13 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:16:13 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:16:13 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Running analyzer for device 'UC_ALineEBeam3' on bin 1
23:16:14 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:16:14 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Extracted 10 scalar results from 'UC_ALineEBeam3'
23:16:14 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -270.8433753388375 for shot 1
23:16:14 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -270.8433753388375 for shot 2
23:16:14 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -270.8433753388375 for shot 3
23:16:14 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -270.8433753388375 for shot 4
23:16:14 INFO geecs_scanner.data_acquisition.scan_executor - Successfully evaluated data for scan step 0
23:16:14 INFO geecs_scanner.data_acquisition.scan_executor - Running optimizer initialization
23:16:14 INFO geecs_scanner.data_acquisition.scan_executor - Next experimental step generated using optimizer: {'U_S1H:Current': 0.04440139096349105, 'U_EMQTripletBipolar:Current_Limit.Ch1': 0.7849705861076097}
23:16:14 INFO geecs_scanner.data_acquisition.scan_executor - Updated scan step 1 with new configuration: {'variables': {'U_S1H:Current': 0.04440139096349105, 'U_EMQTripletBipolar:Current_Limit.Ch1': 0.7849705861076097}, 'wait_time': 4, 'is_composite': False}
23:16:14 INFO geecs_scanner.data_acquisition.scan_executor - Executing scan step: {'variables': {'U_S1H:Current': 0.04440139096349105, 'U_EMQTripletBipolar:Current_Limit.Ch1': 0.7849705861076097}, 'wait_time': 4, 'is_composite': False}
23:16:14 INFO geecs_scanner.data_acquisition.scan_executor - Preparing scan step: {'variables': {'U_S1H:Current': 0.04440139096349105, 'U_EMQTripletBipolar:Current_Limit.Ch1': 0.7849705861076097}, 'wait_time': 4, 'is_composite': False}
23:16:14 INFO geecs_scanner.data_acquisition.scan_executor - Pausing logging. Turning trigger off before moving devices.
23:16:14 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:16:14 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:16:14 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:16:14 INFO geecs_scanner.data_acquisition.scan_executor - Moving devices for step: {'U_S1H:Current': 0.04440139096349105, 'U_EMQTripletBipolar:Current_Limit.Ch1': 0.7849705861076097}
23:16:14 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Preparing to set vars: [('Current', 0.04440139096349105)]
23:16:14 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Preparing to set vars: [('Current_Limit.Ch1', 0.7849705861076097)]
23:16:15 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:16:15 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Attempt 1: Set Current=0.04440139096349105, got 0.044401
23:16:15 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Success: Current=0.044401 within tolerance 0.05
23:16:15 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Attempt 1: Set Current_Limit.Ch1=0.7849705861076097, got 0.784971
23:16:15 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Success: Current_Limit.Ch1=0.784971 within tolerance 0.01
23:16:15 INFO geecs_scanner.data_acquisition.scan_executor - Waiting for acquisition: {'variables': {'U_S1H:Current': 0.04440139096349105, 'U_EMQTripletBipolar:Current_Limit.Ch1': 0.7849705861076097}, 'wait_time': 4, 'is_composite': False}
23:16:15 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 4.0
23:16:16 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Internal
23:16:16 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state SCAN.
23:16:16 INFO geecs_scanner.data_acquisition.scan_executor - idle time between scan steps: 3.001396894454956
23:16:16 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 11.0. reported by UC_ALineEBeam3
23:16:16 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 0.04446 for elapsed time 11.0.
23:16:16 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.784971 for elapsed time 11.0.
23:16:17 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 12.0. reported by UC_ALineEBeam3
23:16:17 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 0.04446 for elapsed time 12.0.
23:16:17 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.784971 for elapsed time 12.0.
23:16:18 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 13.0. reported by UC_ALineEBeam3
23:16:18 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 0.04446 for elapsed time 13.0.
23:16:18 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.784971 for elapsed time 13.0.
23:16:19 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 14.0. reported by UC_ALineEBeam3
23:16:19 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 0.04446 for elapsed time 14.0.
23:16:19 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.784971 for elapsed time 14.0.
23:16:20 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:16:20 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:16:20 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:16:20 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Running analyzer for device 'UC_ALineEBeam3' on bin 2
23:16:21 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:16:21 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Extracted 10 scalar results from 'UC_ALineEBeam3'
23:16:21 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -1693.1910541686202 for shot 5
23:16:21 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -1693.1910541686202 for shot 6
23:16:21 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -1693.1910541686202 for shot 7
23:16:21 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -1693.1910541686202 for shot 8
23:16:21 INFO geecs_scanner.data_acquisition.scan_executor - Successfully evaluated data for scan step 1
23:16:21 INFO geecs_scanner.data_acquisition.scan_executor - Running optimizer initialization
23:16:21 INFO geecs_scanner.data_acquisition.scan_executor - Next experimental step generated using optimizer: {'U_S1H:Current': 1.0789035640039852, 'U_EMQTripletBipolar:Current_Limit.Ch1': 0.7483460748017982}
23:16:21 INFO geecs_scanner.data_acquisition.scan_executor - Updated scan step 2 with new configuration: {'variables': {'U_S1H:Current': 1.0789035640039852, 'U_EMQTripletBipolar:Current_Limit.Ch1': 0.7483460748017982}, 'wait_time': 4, 'is_composite': False}
23:16:21 INFO geecs_scanner.data_acquisition.scan_executor - Executing scan step: {'variables': {'U_S1H:Current': 1.0789035640039852, 'U_EMQTripletBipolar:Current_Limit.Ch1': 0.7483460748017982}, 'wait_time': 4, 'is_composite': False}
23:16:21 INFO geecs_scanner.data_acquisition.scan_executor - Preparing scan step: {'variables': {'U_S1H:Current': 1.0789035640039852, 'U_EMQTripletBipolar:Current_Limit.Ch1': 0.7483460748017982}, 'wait_time': 4, 'is_composite': False}
23:16:21 INFO geecs_scanner.data_acquisition.scan_executor - Pausing logging. Turning trigger off before moving devices.
23:16:21 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:16:21 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:16:21 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:16:21 INFO geecs_scanner.data_acquisition.scan_executor - Moving devices for step: {'U_S1H:Current': 1.0789035640039852, 'U_EMQTripletBipolar:Current_Limit.Ch1': 0.7483460748017982}
23:16:21 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Preparing to set vars: [('Current', 1.0789035640039852)]
23:16:21 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Preparing to set vars: [('Current_Limit.Ch1', 0.7483460748017982)]
23:16:22 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:16:23 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Attempt 1: Set Current=1.0789035640039852, got 1.078904
23:16:23 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Success: Current=1.078904 within tolerance 0.05
23:16:23 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Attempt 1: Set Current_Limit.Ch1=0.7483460748017982, got 0.748346
23:16:23 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Success: Current_Limit.Ch1=0.748346 within tolerance 0.01
23:16:23 INFO geecs_scanner.data_acquisition.scan_executor - Waiting for acquisition: {'variables': {'U_S1H:Current': 1.0789035640039852, 'U_EMQTripletBipolar:Current_Limit.Ch1': 0.7483460748017982}, 'wait_time': 4, 'is_composite': False}
23:16:23 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 4.0
23:16:23 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Internal
23:16:23 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state SCAN.
23:16:23 INFO geecs_scanner.data_acquisition.scan_executor - idle time between scan steps: 3.2034733295440674
23:16:23 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 18.0. reported by UC_ALineEBeam3
23:16:23 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.07896 for elapsed time 18.0.
23:16:23 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.748346 for elapsed time 18.0.
23:16:24 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 19.0. reported by UC_ALineEBeam3
23:16:24 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.07896 for elapsed time 19.0.
23:16:24 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.748346 for elapsed time 19.0.
23:16:25 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 20.0. reported by UC_ALineEBeam3
23:16:25 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.07896 for elapsed time 20.0.
23:16:25 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.748346 for elapsed time 20.0.
23:16:26 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 21.0. reported by UC_ALineEBeam3
23:16:26 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.07896 for elapsed time 21.0.
23:16:26 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.748346 for elapsed time 21.0.
23:16:27 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 22.0. reported by UC_ALineEBeam3
23:16:27 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.07896 for elapsed time 22.0.
23:16:27 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.748346 for elapsed time 22.0.
23:16:27 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:16:28 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:16:28 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:16:28 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Running analyzer for device 'UC_ALineEBeam3' on bin 3
23:16:29 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Extracted 10 scalar results from 'UC_ALineEBeam3'
23:16:29 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 147.5429155380384 for shot 9
23:16:29 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 147.5429155380384 for shot 10
23:16:29 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 147.5429155380384 for shot 11
23:16:29 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 147.5429155380384 for shot 12
23:16:29 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 147.5429155380384 for shot 13
23:16:29 INFO geecs_scanner.data_acquisition.scan_executor - Successfully evaluated data for scan step 2
23:16:29 INFO geecs_scanner.data_acquisition.scan_executor - Running advanced optimizer
23:16:29 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:16:30 INFO geecs_scanner.data_acquisition.scan_executor - Next experimental step generated using optimizer: {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.1471007330526735, 'U_S1H:Current': -0.7100713988138393}
23:16:30 INFO geecs_scanner.data_acquisition.scan_executor - Updated scan step 3 with new configuration: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.1471007330526735, 'U_S1H:Current': -0.7100713988138393}, 'wait_time': 4, 'is_composite': False}
23:16:30 INFO geecs_scanner.data_acquisition.scan_executor - Executing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.1471007330526735, 'U_S1H:Current': -0.7100713988138393}, 'wait_time': 4, 'is_composite': False}
23:16:30 INFO geecs_scanner.data_acquisition.scan_executor - Preparing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.1471007330526735, 'U_S1H:Current': -0.7100713988138393}, 'wait_time': 4, 'is_composite': False}
23:16:30 INFO geecs_scanner.data_acquisition.scan_executor - Pausing logging. Turning trigger off before moving devices.
23:16:30 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:16:30 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:16:30 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:16:30 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:16:30 INFO geecs_scanner.data_acquisition.scan_executor - Moving devices for step: {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.1471007330526735, 'U_S1H:Current': -0.7100713988138393}
23:16:30 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Preparing to set vars: [('Current_Limit.Ch1', 1.1471007330526735)]
23:16:30 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Preparing to set vars: [('Current', -0.7100713988138393)]
23:16:30 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Attempt 1: Set Current_Limit.Ch1=1.1471007330526735, got 1.147101
23:16:30 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Success: Current_Limit.Ch1=1.147101 within tolerance 0.01
23:16:32 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Attempt 1: Set Current=-0.7100713988138393, got -0.710071
23:16:32 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Success: Current=-0.710071 within tolerance 0.05
23:16:32 INFO geecs_scanner.data_acquisition.scan_executor - Waiting for acquisition: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.1471007330526735, 'U_S1H:Current': -0.7100713988138393}, 'wait_time': 4, 'is_composite': False}
23:16:32 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:16:32 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 4.0
23:16:32 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Internal
23:16:32 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state SCAN.
23:16:32 INFO geecs_scanner.data_acquisition.scan_executor - idle time between scan steps: 4.39760160446167
23:16:33 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 27.0. reported by UC_ALineEBeam3
23:16:33 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to -0.70976 for elapsed time 27.0.
23:16:33 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.147101 for elapsed time 27.0.
23:16:34 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 28.0. reported by UC_ALineEBeam3
23:16:34 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to -0.70976 for elapsed time 28.0.
23:16:34 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.147101 for elapsed time 28.0.
23:16:35 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 29.0. reported by UC_ALineEBeam3
23:16:35 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to -0.70976 for elapsed time 29.0.
23:16:35 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.147101 for elapsed time 29.0.
23:16:36 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 30.0. reported by UC_ALineEBeam3
23:16:36 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to -0.70976 for elapsed time 30.0.
23:16:36 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.147101 for elapsed time 30.0.
23:16:36 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:16:36 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:16:36 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:16:37 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Running analyzer for device 'UC_ALineEBeam3' on bin 4
23:16:37 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:16:37 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Extracted 10 scalar results from 'UC_ALineEBeam3'
23:16:37 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -3675.3151180979366 for shot 14
23:16:37 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -3675.3151180979366 for shot 15
23:16:37 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -3675.3151180979366 for shot 16
23:16:37 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -3675.3151180979366 for shot 17
23:16:37 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.2125 for shot 14
23:16:37 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 0.07500000000000001 for shot 14
23:16:37 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -1.8627449044146804 for shot 14
23:16:37 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.2125 for shot 15
23:16:37 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 0.07500000000000001 for shot 15
23:16:37 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -1.8627449044146804 for shot 15
23:16:37 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.2125 for shot 16
23:16:37 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 0.07500000000000001 for shot 16
23:16:37 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -1.8627449044146804 for shot 16
23:16:37 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.2125 for shot 17
23:16:37 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 0.07500000000000001 for shot 17
23:16:37 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -1.8627449044146804 for shot 17
23:16:37 INFO geecs_scanner.data_acquisition.scan_executor - Successfully evaluated data for scan step 3
23:16:37 INFO geecs_scanner.data_acquisition.scan_executor - Running advanced optimizer
23:16:38 INFO geecs_scanner.data_acquisition.scan_executor - Next experimental step generated using optimizer: {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.8313072584743808, 'U_S1H:Current': -0.6169964781915066}
23:16:38 INFO geecs_scanner.data_acquisition.scan_executor - Updated scan step 4 with new configuration: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.8313072584743808, 'U_S1H:Current': -0.6169964781915066}, 'wait_time': 4, 'is_composite': False}
23:16:38 INFO geecs_scanner.data_acquisition.scan_executor - Executing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.8313072584743808, 'U_S1H:Current': -0.6169964781915066}, 'wait_time': 4, 'is_composite': False}
23:16:38 INFO geecs_scanner.data_acquisition.scan_executor - Preparing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.8313072584743808, 'U_S1H:Current': -0.6169964781915066}, 'wait_time': 4, 'is_composite': False}
23:16:38 INFO geecs_scanner.data_acquisition.scan_executor - Pausing logging. Turning trigger off before moving devices.
23:16:38 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:16:38 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:16:38 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:16:38 INFO geecs_scanner.data_acquisition.scan_executor - Moving devices for step: {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.8313072584743808, 'U_S1H:Current': -0.6169964781915066}
23:16:38 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Preparing to set vars: [('Current_Limit.Ch1', 1.8313072584743808)]
23:16:38 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Preparing to set vars: [('Current', -0.6169964781915066)]
23:16:39 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Attempt 1: Set Current_Limit.Ch1=1.8313072584743808, got 1.831307
23:16:39 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Success: Current_Limit.Ch1=1.831307 within tolerance 0.01
23:16:39 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:16:40 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Attempt 1: Set Current=-0.6169964781915066, got -0.616996
23:16:40 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Success: Current=-0.616996 within tolerance 0.05
23:16:40 INFO geecs_scanner.data_acquisition.scan_executor - Waiting for acquisition: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.8313072584743808, 'U_S1H:Current': -0.6169964781915066}, 'wait_time': 4, 'is_composite': False}
23:16:40 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 4.0
23:16:40 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:16:40 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Internal
23:16:40 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state SCAN.
23:16:40 INFO geecs_scanner.data_acquisition.scan_executor - idle time between scan steps: 3.801347494125366
23:16:40 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 35.0. reported by UC_ALineEBeam3
23:16:40 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to -0.61685 for elapsed time 35.0.
23:16:40 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.831307 for elapsed time 35.0.
23:16:41 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 36.0. reported by UC_ALineEBeam3
23:16:41 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to -0.61685 for elapsed time 36.0.
23:16:41 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.831307 for elapsed time 36.0.
23:16:42 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 37.0. reported by UC_ALineEBeam3
23:16:42 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to -0.61685 for elapsed time 37.0.
23:16:42 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.831307 for elapsed time 37.0.
23:16:43 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 38.0. reported by UC_ALineEBeam3
23:16:43 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to -0.61685 for elapsed time 38.0.
23:16:43 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.831307 for elapsed time 38.0.
23:16:44 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:16:44 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 39.0. reported by UC_ALineEBeam3
23:16:44 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to -0.61685 for elapsed time 39.0.
23:16:44 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.831307 for elapsed time 39.0.
23:16:45 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:16:45 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:16:45 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Running analyzer for device 'UC_ALineEBeam3' on bin 5
23:16:46 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Extracted 10 scalar results from 'UC_ALineEBeam3'
23:16:46 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -4566.15438672128 for shot 18
23:16:46 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -4566.15438672128 for shot 19
23:16:46 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -4566.15438672128 for shot 20
23:16:46 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -4566.15438672128 for shot 21
23:16:46 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -4566.15438672128 for shot 22
23:16:46 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.325 for shot 18
23:16:46 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 0.5475 for shot 18
23:16:46 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -2.0141572212007 for shot 18
23:16:46 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.325 for shot 19
23:16:46 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 0.5475 for shot 19
23:16:46 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -2.0141572212007 for shot 19
23:16:46 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.325 for shot 20
23:16:46 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 0.5475 for shot 20
23:16:46 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -2.0141572212007 for shot 20
23:16:46 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.325 for shot 21
23:16:46 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 0.5475 for shot 21
23:16:46 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -2.0141572212007 for shot 21
23:16:46 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.325 for shot 22
23:16:46 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 0.5475 for shot 22
23:16:46 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -2.0141572212007 for shot 22
23:16:46 INFO geecs_scanner.data_acquisition.scan_executor - Successfully evaluated data for scan step 4
23:16:46 INFO geecs_scanner.data_acquisition.scan_executor - Running advanced optimizer
23:16:46 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:16:47 INFO geecs_scanner.data_acquisition.scan_executor - Next experimental step generated using optimizer: {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.8853155886688441, 'U_S1H:Current': 1.457265783681458}
23:16:47 INFO geecs_scanner.data_acquisition.scan_executor - Updated scan step 5 with new configuration: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.8853155886688441, 'U_S1H:Current': 1.457265783681458}, 'wait_time': 4, 'is_composite': False}
23:16:47 INFO geecs_scanner.data_acquisition.scan_executor - Executing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.8853155886688441, 'U_S1H:Current': 1.457265783681458}, 'wait_time': 4, 'is_composite': False}
23:16:47 INFO geecs_scanner.data_acquisition.scan_executor - Preparing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.8853155886688441, 'U_S1H:Current': 1.457265783681458}, 'wait_time': 4, 'is_composite': False}
23:16:47 INFO geecs_scanner.data_acquisition.scan_executor - Pausing logging. Turning trigger off before moving devices.
23:16:47 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:16:47 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:16:47 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:16:47 INFO geecs_scanner.data_acquisition.scan_executor - Moving devices for step: {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.8853155886688441, 'U_S1H:Current': 1.457265783681458}
23:16:47 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Preparing to set vars: [('Current_Limit.Ch1', 0.8853155886688441)]
23:16:47 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Preparing to set vars: [('Current', 1.457265783681458)]
23:16:47 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Attempt 1: Set Current_Limit.Ch1=0.8853155886688441, got 0.885316
23:16:47 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Success: Current_Limit.Ch1=0.885316 within tolerance 0.01
23:16:47 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:16:49 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Attempt 1: Set Current=1.457265783681458, got 1.457266
23:16:49 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Success: Current=1.457266 within tolerance 0.05
23:16:49 INFO geecs_scanner.data_acquisition.scan_executor - Waiting for acquisition: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.8853155886688441, 'U_S1H:Current': 1.457265783681458}, 'wait_time': 4, 'is_composite': False}
23:16:49 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 4.0
23:16:49 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Internal
23:16:49 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state SCAN.
23:16:49 INFO geecs_scanner.data_acquisition.scan_executor - idle time between scan steps: 4.218235731124878
23:16:49 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:16:50 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 44.0. reported by UC_ALineEBeam3
23:16:50 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.45723 for elapsed time 44.0.
23:16:50 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.885316 for elapsed time 44.0.
23:16:51 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 45.0. reported by UC_ALineEBeam3
23:16:51 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.45723 for elapsed time 45.0.
23:16:51 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.885316 for elapsed time 45.0.
23:16:52 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 46.0. reported by UC_ALineEBeam3
23:16:52 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.45723 for elapsed time 46.0.
23:16:52 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.885316 for elapsed time 46.0.
23:16:53 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 47.0. reported by UC_ALineEBeam3
23:16:53 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.45723 for elapsed time 47.0.
23:16:53 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.885316 for elapsed time 47.0.
23:16:53 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:16:53 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:16:53 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:16:54 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Running analyzer for device 'UC_ALineEBeam3' on bin 6
23:16:54 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Extracted 10 scalar results from 'UC_ALineEBeam3'
23:16:54 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 863.7863808608553 for shot 23
23:16:54 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 863.7863808608553 for shot 24
23:16:54 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 863.7863808608553 for shot 25
23:16:54 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 863.7863808608553 for shot 26
23:16:54 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:16:54 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.00625 for shot 23
23:16:54 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 0.8550000000000001 for shot 23
23:16:54 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -1.8317602374108182 for shot 23
23:16:54 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.00625 for shot 24
23:16:54 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 0.8550000000000001 for shot 24
23:16:54 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -1.8317602374108182 for shot 24
23:16:54 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.00625 for shot 25
23:16:54 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 0.8550000000000001 for shot 25
23:16:54 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -1.8317602374108182 for shot 25
23:16:54 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.00625 for shot 26
23:16:54 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 0.8550000000000001 for shot 26
23:16:54 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -1.8317602374108182 for shot 26
23:16:54 INFO geecs_scanner.data_acquisition.scan_executor - Successfully evaluated data for scan step 5
23:16:54 INFO geecs_scanner.data_acquisition.scan_executor - Running advanced optimizer
23:16:55 INFO geecs_scanner.data_acquisition.scan_executor - Next experimental step generated using optimizer: {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.6441645228209032, 'U_S1H:Current': 1.3323832006336094}
23:16:55 INFO geecs_scanner.data_acquisition.scan_executor - Updated scan step 6 with new configuration: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.6441645228209032, 'U_S1H:Current': 1.3323832006336094}, 'wait_time': 4, 'is_composite': False}
23:16:55 INFO geecs_scanner.data_acquisition.scan_executor - Executing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.6441645228209032, 'U_S1H:Current': 1.3323832006336094}, 'wait_time': 4, 'is_composite': False}
23:16:55 INFO geecs_scanner.data_acquisition.scan_executor - Preparing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.6441645228209032, 'U_S1H:Current': 1.3323832006336094}, 'wait_time': 4, 'is_composite': False}
23:16:55 INFO geecs_scanner.data_acquisition.scan_executor - Pausing logging. Turning trigger off before moving devices.
23:16:55 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:16:55 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:16:55 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:16:55 INFO geecs_scanner.data_acquisition.scan_executor - Moving devices for step: {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.6441645228209032, 'U_S1H:Current': 1.3323832006336094}
23:16:55 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Preparing to set vars: [('Current_Limit.Ch1', 1.6441645228209032)]
23:16:55 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Preparing to set vars: [('Current', 1.3323832006336094)]
23:16:56 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:16:56 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Attempt 1: Set Current_Limit.Ch1=1.6441645228209032, got 1.644165
23:16:56 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Success: Current_Limit.Ch1=1.644165 within tolerance 0.01
23:16:57 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Attempt 1: Set Current=1.3323832006336094, got 1.332383
23:16:57 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Success: Current=1.332383 within tolerance 0.05
23:16:57 INFO geecs_scanner.data_acquisition.scan_executor - Waiting for acquisition: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.6441645228209032, 'U_S1H:Current': 1.3323832006336094}, 'wait_time': 4, 'is_composite': False}
23:16:57 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:16:57 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 4.0
23:16:58 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Internal
23:16:58 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state SCAN.
23:16:58 INFO geecs_scanner.data_acquisition.scan_executor - idle time between scan steps: 4.420414686203003
23:16:58 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 53.0. reported by UC_ALineEBeam3
23:16:58 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.33237 for elapsed time 53.0.
23:16:58 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.644165 for elapsed time 53.0.
23:16:59 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 54.0. reported by UC_ALineEBeam3
23:16:59 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.33237 for elapsed time 54.0.
23:16:59 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.644165 for elapsed time 54.0.
23:17:00 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 55.0. reported by UC_ALineEBeam3
23:17:00 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.33237 for elapsed time 55.0.
23:17:00 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.644165 for elapsed time 55.0.
23:17:01 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 56.0. reported by UC_ALineEBeam3
23:17:01 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.33237 for elapsed time 56.0.
23:17:01 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.644165 for elapsed time 56.0.
23:17:02 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:17:02 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:17:02 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:17:03 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Running analyzer for device 'UC_ALineEBeam3' on bin 7
23:17:03 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:03 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Extracted 10 scalar results from 'UC_ALineEBeam3'
23:17:03 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 868.035693937756 for shot 27
23:17:03 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 868.035693937756 for shot 28
23:17:03 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 868.035693937756 for shot 29
23:17:03 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 868.035693937756 for shot 30
23:17:03 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.325 for shot 27
23:17:03 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.2225 for shot 27
23:17:03 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -4.568071944292438 for shot 27
23:17:03 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.325 for shot 28
23:17:03 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.2225 for shot 28
23:17:03 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -4.568071944292438 for shot 28
23:17:03 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.325 for shot 29
23:17:03 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.2225 for shot 29
23:17:03 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -4.568071944292438 for shot 29
23:17:03 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.325 for shot 30
23:17:03 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.2225 for shot 30
23:17:03 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -4.568071944292438 for shot 30
23:17:03 INFO geecs_scanner.data_acquisition.scan_executor - Successfully evaluated data for scan step 6
23:17:03 INFO geecs_scanner.data_acquisition.scan_executor - Running advanced optimizer
23:17:04 INFO geecs_scanner.data_acquisition.scan_executor - Next experimental step generated using optimizer: {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.2367499861674898, 'U_S1H:Current': 1.0154332163971995}
23:17:04 INFO geecs_scanner.data_acquisition.scan_executor - Updated scan step 7 with new configuration: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.2367499861674898, 'U_S1H:Current': 1.0154332163971995}, 'wait_time': 4, 'is_composite': False}
23:17:04 INFO geecs_scanner.data_acquisition.scan_executor - Executing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.2367499861674898, 'U_S1H:Current': 1.0154332163971995}, 'wait_time': 4, 'is_composite': False}
23:17:04 INFO geecs_scanner.data_acquisition.scan_executor - Preparing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.2367499861674898, 'U_S1H:Current': 1.0154332163971995}, 'wait_time': 4, 'is_composite': False}
23:17:04 INFO geecs_scanner.data_acquisition.scan_executor - Pausing logging. Turning trigger off before moving devices.
23:17:04 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:17:04 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:04 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:17:04 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:17:04 INFO geecs_scanner.data_acquisition.scan_executor - Moving devices for step: {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.2367499861674898, 'U_S1H:Current': 1.0154332163971995}
23:17:04 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Preparing to set vars: [('Current_Limit.Ch1', 1.2367499861674898)]
23:17:04 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Preparing to set vars: [('Current', 1.0154332163971995)]
23:17:04 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Attempt 1: Set Current_Limit.Ch1=1.2367499861674898, got 1.23675
23:17:04 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Success: Current_Limit.Ch1=1.23675 within tolerance 0.01
23:17:06 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:06 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Attempt 1: Set Current=1.0154332163971995, got 1.015433
23:17:06 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Success: Current=1.015433 within tolerance 0.05
23:17:06 INFO geecs_scanner.data_acquisition.scan_executor - Waiting for acquisition: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.2367499861674898, 'U_S1H:Current': 1.0154332163971995}, 'wait_time': 4, 'is_composite': False}
23:17:06 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 4.0
23:17:06 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Internal
23:17:06 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state SCAN.
23:17:06 INFO geecs_scanner.data_acquisition.scan_executor - idle time between scan steps: 4.006106853485107
23:17:07 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 62.0. reported by UC_ALineEBeam3
23:17:07 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.01538 for elapsed time 62.0.
23:17:07 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.23675 for elapsed time 62.0.
23:17:08 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 63.0. reported by UC_ALineEBeam3
23:17:08 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.01538 for elapsed time 63.0.
23:17:08 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.23675 for elapsed time 63.0.
23:17:09 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 64.0. reported by UC_ALineEBeam3
23:17:09 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.01538 for elapsed time 64.0.
23:17:09 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.23675 for elapsed time 64.0.
23:17:10 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 65.0. reported by UC_ALineEBeam3
23:17:10 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.01538 for elapsed time 65.0.
23:17:10 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.23675 for elapsed time 65.0.
23:17:10 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:17:10 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:17:10 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:17:11 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Running analyzer for device 'UC_ALineEBeam3' on bin 8
23:17:12 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Extracted 10 scalar results from 'UC_ALineEBeam3'
23:17:12 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 30.917228504522615 for shot 31
23:17:12 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 30.917228504522615 for shot 32
23:17:12 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 30.917228504522615 for shot 33
23:17:12 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 30.917228504522615 for shot 34
23:17:12 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.34375 for shot 31
23:17:12 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0275000000000003 for shot 31
23:17:12 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -6.307418659142235 for shot 31
23:17:12 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.34375 for shot 32
23:17:12 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0275000000000003 for shot 32
23:17:12 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -6.307418659142235 for shot 32
23:17:12 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.34375 for shot 33
23:17:12 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0275000000000003 for shot 33
23:17:12 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -6.307418659142235 for shot 33
23:17:12 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.34375 for shot 34
23:17:12 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0275000000000003 for shot 34
23:17:12 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -6.307418659142235 for shot 34
23:17:12 INFO geecs_scanner.data_acquisition.scan_executor - Successfully evaluated data for scan step 7
23:17:12 INFO geecs_scanner.data_acquisition.scan_executor - Running advanced optimizer
23:17:12 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:13 INFO geecs_scanner.data_acquisition.scan_executor - Next experimental step generated using optimizer: {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.1627846423816348, 'U_S1H:Current': 1.0174765557262968}
23:17:13 INFO geecs_scanner.data_acquisition.scan_executor - Updated scan step 8 with new configuration: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.1627846423816348, 'U_S1H:Current': 1.0174765557262968}, 'wait_time': 4, 'is_composite': False}
23:17:13 INFO geecs_scanner.data_acquisition.scan_executor - Executing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.1627846423816348, 'U_S1H:Current': 1.0174765557262968}, 'wait_time': 4, 'is_composite': False}
23:17:13 INFO geecs_scanner.data_acquisition.scan_executor - Preparing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.1627846423816348, 'U_S1H:Current': 1.0174765557262968}, 'wait_time': 4, 'is_composite': False}
23:17:13 INFO geecs_scanner.data_acquisition.scan_executor - Pausing logging. Turning trigger off before moving devices.
23:17:13 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:17:13 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:13 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:17:13 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:17:13 INFO geecs_scanner.data_acquisition.scan_executor - Moving devices for step: {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.1627846423816348, 'U_S1H:Current': 1.0174765557262968}
23:17:13 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Preparing to set vars: [('Current_Limit.Ch1', 1.1627846423816348)]
23:17:13 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Preparing to set vars: [('Current', 1.0174765557262968)]
23:17:14 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Attempt 1: Set Current_Limit.Ch1=1.1627846423816348, got 1.162785
23:17:14 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Success: Current_Limit.Ch1=1.162785 within tolerance 0.01
23:17:15 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:15 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Attempt 1: Set Current=1.0174765557262968, got 1.017477
23:17:15 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Success: Current=1.017477 within tolerance 0.05
23:17:15 INFO geecs_scanner.data_acquisition.scan_executor - Waiting for acquisition: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.1627846423816348, 'U_S1H:Current': 1.0174765557262968}, 'wait_time': 4, 'is_composite': False}
23:17:15 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 4.0
23:17:15 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Internal
23:17:15 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state SCAN.
23:17:15 INFO geecs_scanner.data_acquisition.scan_executor - idle time between scan steps: 4.587541818618774
23:17:16 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 70.0. reported by UC_ALineEBeam3
23:17:16 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.01746 for elapsed time 70.0.
23:17:16 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.162785 for elapsed time 70.0.
23:17:17 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 71.0. reported by UC_ALineEBeam3
23:17:17 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.01746 for elapsed time 71.0.
23:17:17 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.162785 for elapsed time 71.0.
23:17:18 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 72.0. reported by UC_ALineEBeam3
23:17:18 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.01746 for elapsed time 72.0.
23:17:18 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.162785 for elapsed time 72.0.
23:17:19 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 73.0. reported by UC_ALineEBeam3
23:17:19 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.01746 for elapsed time 73.0.
23:17:19 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.162785 for elapsed time 73.0.
23:17:19 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:17:19 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:17:19 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:17:20 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Running analyzer for device 'UC_ALineEBeam3' on bin 9
23:17:20 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:20 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Extracted 10 scalar results from 'UC_ALineEBeam3'
23:17:20 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 44.36084311953081 for shot 35
23:17:20 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 44.36084311953081 for shot 36
23:17:20 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 44.36084311953081 for shot 37
23:17:20 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 44.36084311953081 for shot 38
23:17:20 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.19375 for shot 35
23:17:20 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0275 for shot 35
23:17:20 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.013914135216549 for shot 35
23:17:20 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.19375 for shot 36
23:17:20 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0275 for shot 36
23:17:20 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.013914135216549 for shot 36
23:17:20 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.19375 for shot 37
23:17:20 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0275 for shot 37
23:17:20 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.013914135216549 for shot 37
23:17:20 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.19375 for shot 38
23:17:20 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0275 for shot 38
23:17:20 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.013914135216549 for shot 38
23:17:20 INFO geecs_scanner.data_acquisition.scan_executor - Successfully evaluated data for scan step 8
23:17:20 INFO geecs_scanner.data_acquisition.scan_executor - Running advanced optimizer
23:17:22 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:22 INFO geecs_scanner.data_acquisition.scan_executor - Next experimental step generated using optimizer: {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.5050871569737477, 'U_S1H:Current': 1.0506417224507505}
23:17:22 INFO geecs_scanner.data_acquisition.scan_executor - Updated scan step 9 with new configuration: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.5050871569737477, 'U_S1H:Current': 1.0506417224507505}, 'wait_time': 4, 'is_composite': False}
23:17:22 INFO geecs_scanner.data_acquisition.scan_executor - Executing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.5050871569737477, 'U_S1H:Current': 1.0506417224507505}, 'wait_time': 4, 'is_composite': False}
23:17:22 INFO geecs_scanner.data_acquisition.scan_executor - Preparing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.5050871569737477, 'U_S1H:Current': 1.0506417224507505}, 'wait_time': 4, 'is_composite': False}
23:17:22 INFO geecs_scanner.data_acquisition.scan_executor - Pausing logging. Turning trigger off before moving devices.
23:17:22 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:17:23 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:17:23 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:17:23 INFO geecs_scanner.data_acquisition.scan_executor - Moving devices for step: {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.5050871569737477, 'U_S1H:Current': 1.0506417224507505}
23:17:23 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Preparing to set vars: [('Current_Limit.Ch1', 0.5050871569737477)]
23:17:23 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Preparing to set vars: [('Current', 1.0506417224507505)]
23:17:23 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Attempt 1: Set Current_Limit.Ch1=0.5050871569737477, got 0.505087
23:17:23 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Success: Current_Limit.Ch1=0.505087 within tolerance 0.01
23:17:23 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:24 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Attempt 1: Set Current=1.0506417224507505, got 1.050642
23:17:24 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Success: Current=1.050642 within tolerance 0.05
23:17:24 INFO geecs_scanner.data_acquisition.scan_executor - Waiting for acquisition: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.5050871569737477, 'U_S1H:Current': 1.0506417224507505}, 'wait_time': 4, 'is_composite': False}
23:17:24 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 4.0
23:17:25 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Internal
23:17:25 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state SCAN.
23:17:25 INFO geecs_scanner.data_acquisition.scan_executor - idle time between scan steps: 5.204094409942627
23:17:25 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:25 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 79.0. reported by UC_ALineEBeam3
23:17:25 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.05076 for elapsed time 79.0.
23:17:25 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.505087 for elapsed time 79.0.
23:17:26 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 80.0. reported by UC_ALineEBeam3
23:17:26 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.05076 for elapsed time 80.0.
23:17:26 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.505087 for elapsed time 80.0.
23:17:27 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 81.0. reported by UC_ALineEBeam3
23:17:27 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.05076 for elapsed time 81.0.
23:17:27 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.505087 for elapsed time 81.0.
23:17:28 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 82.0. reported by UC_ALineEBeam3
23:17:28 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.05076 for elapsed time 82.0.
23:17:28 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.505087 for elapsed time 82.0.
23:17:29 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:17:29 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 83.0. reported by UC_ALineEBeam3
23:17:29 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.05076 for elapsed time 83.0.
23:17:29 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.505087 for elapsed time 83.0.
23:17:29 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:17:29 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:17:30 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Running analyzer for device 'UC_ALineEBeam3' on bin 10
23:17:30 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Extracted 10 scalar results from 'UC_ALineEBeam3'
23:17:30 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 82.01115314042124 for shot 39
23:17:30 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 82.01115314042124 for shot 40
23:17:30 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 82.01115314042124 for shot 41
23:17:30 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 82.01115314042124 for shot 42
23:17:30 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 82.01115314042124 for shot 43
23:17:30 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.175 for shot 39
23:17:30 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0274999999999999 for shot 39
23:17:30 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.206133199035316 for shot 39
23:17:30 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.175 for shot 40
23:17:30 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0274999999999999 for shot 40
23:17:30 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.206133199035316 for shot 40
23:17:30 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.175 for shot 41
23:17:30 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0274999999999999 for shot 41
23:17:30 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.206133199035316 for shot 41
23:17:30 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.175 for shot 42
23:17:30 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0274999999999999 for shot 42
23:17:30 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.206133199035316 for shot 42
23:17:30 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.175 for shot 43
23:17:30 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0274999999999999 for shot 43
23:17:30 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.206133199035316 for shot 43
23:17:30 INFO geecs_scanner.data_acquisition.scan_executor - Successfully evaluated data for scan step 9
23:17:30 INFO geecs_scanner.data_acquisition.scan_executor - Running advanced optimizer
23:17:30 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:32 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:32 INFO geecs_scanner.data_acquisition.scan_executor - Next experimental step generated using optimizer: {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.9859574856052973, 'U_S1H:Current': 1.0485177033527864}
23:17:32 INFO geecs_scanner.data_acquisition.scan_executor - Updated scan step 10 with new configuration: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.9859574856052973, 'U_S1H:Current': 1.0485177033527864}, 'wait_time': 4, 'is_composite': False}
23:17:32 INFO geecs_scanner.data_acquisition.scan_executor - Executing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.9859574856052973, 'U_S1H:Current': 1.0485177033527864}, 'wait_time': 4, 'is_composite': False}
23:17:32 INFO geecs_scanner.data_acquisition.scan_executor - Preparing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.9859574856052973, 'U_S1H:Current': 1.0485177033527864}, 'wait_time': 4, 'is_composite': False}
23:17:32 INFO geecs_scanner.data_acquisition.scan_executor - Pausing logging. Turning trigger off before moving devices.
23:17:32 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:17:33 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:17:33 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:17:33 INFO geecs_scanner.data_acquisition.scan_executor - Moving devices for step: {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.9859574856052973, 'U_S1H:Current': 1.0485177033527864}
23:17:33 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Preparing to set vars: [('Current_Limit.Ch1', 1.9859574856052973)]
23:17:33 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Preparing to set vars: [('Current', 1.0485177033527864)]
23:17:33 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Attempt 1: Set Current_Limit.Ch1=1.9859574856052973, got 1.985957
23:17:33 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Success: Current_Limit.Ch1=1.985957 within tolerance 0.01
23:17:33 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:34 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Attempt 1: Set Current=1.0485177033527864, got 1.048518
23:17:34 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Success: Current=1.048518 within tolerance 0.05
23:17:34 INFO geecs_scanner.data_acquisition.scan_executor - Waiting for acquisition: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.9859574856052973, 'U_S1H:Current': 1.0485177033527864}, 'wait_time': 4, 'is_composite': False}
23:17:34 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 4.0
23:17:34 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Internal
23:17:34 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state SCAN.
23:17:34 INFO geecs_scanner.data_acquisition.scan_executor - idle time between scan steps: 5.420702219009399
23:17:35 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:35 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 90.0. reported by UC_ALineEBeam3
23:17:35 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.04844 for elapsed time 90.0.
23:17:35 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.985957 for elapsed time 90.0.
23:17:36 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 91.0. reported by UC_ALineEBeam3
23:17:36 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.04844 for elapsed time 91.0.
23:17:36 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.985957 for elapsed time 91.0.
23:17:37 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 92.0. reported by UC_ALineEBeam3
23:17:37 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.04844 for elapsed time 92.0.
23:17:37 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.985957 for elapsed time 92.0.
23:17:38 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 93.0. reported by UC_ALineEBeam3
23:17:38 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.04844 for elapsed time 93.0.
23:17:38 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.985957 for elapsed time 93.0.
23:17:39 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:17:39 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:17:39 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:17:39 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Running analyzer for device 'UC_ALineEBeam3' on bin 11
23:17:40 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:40 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Extracted 10 scalar results from 'UC_ALineEBeam3'
23:17:40 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 153.81968435502898 for shot 44
23:17:40 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 153.81968435502898 for shot 45
23:17:40 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 153.81968435502898 for shot 46
23:17:40 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 153.81968435502898 for shot 47
23:17:40 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.15625 for shot 44
23:17:40 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.035 for shot 44
23:17:40 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.233797977504445 for shot 44
23:17:40 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.15625 for shot 45
23:17:40 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.035 for shot 45
23:17:40 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.233797977504445 for shot 45
23:17:40 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.15625 for shot 46
23:17:40 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.035 for shot 46
23:17:40 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.233797977504445 for shot 46
23:17:40 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.15625 for shot 47
23:17:40 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.035 for shot 47
23:17:40 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.233797977504445 for shot 47
23:17:40 INFO geecs_scanner.data_acquisition.scan_executor - Successfully evaluated data for scan step 10
23:17:40 INFO geecs_scanner.data_acquisition.scan_executor - Running advanced optimizer
23:17:41 INFO geecs_scanner.data_acquisition.scan_executor - Next experimental step generated using optimizer: {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.5044380851860354, 'U_S1H:Current': 1.0008932233441359}
23:17:41 INFO geecs_scanner.data_acquisition.scan_executor - Updated scan step 11 with new configuration: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.5044380851860354, 'U_S1H:Current': 1.0008932233441359}, 'wait_time': 4, 'is_composite': False}
23:17:41 INFO geecs_scanner.data_acquisition.scan_executor - Executing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.5044380851860354, 'U_S1H:Current': 1.0008932233441359}, 'wait_time': 4, 'is_composite': False}
23:17:41 INFO geecs_scanner.data_acquisition.scan_executor - Preparing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.5044380851860354, 'U_S1H:Current': 1.0008932233441359}, 'wait_time': 4, 'is_composite': False}
23:17:41 INFO geecs_scanner.data_acquisition.scan_executor - Pausing logging. Turning trigger off before moving devices.
23:17:41 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:17:41 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:41 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:17:41 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:17:41 INFO geecs_scanner.data_acquisition.scan_executor - Moving devices for step: {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.5044380851860354, 'U_S1H:Current': 1.0008932233441359}
23:17:41 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Preparing to set vars: [('Current_Limit.Ch1', 0.5044380851860354)]
23:17:41 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Preparing to set vars: [('Current', 1.0008932233441359)]
23:17:42 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Attempt 1: Set Current_Limit.Ch1=0.5044380851860354, got 0.504438
23:17:42 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Success: Current_Limit.Ch1=0.504438 within tolerance 0.01
23:17:43 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:43 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Attempt 1: Set Current=1.0008932233441359, got 1.000893
23:17:43 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Success: Current=1.000893 within tolerance 0.05
23:17:43 INFO geecs_scanner.data_acquisition.scan_executor - Waiting for acquisition: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.5044380851860354, 'U_S1H:Current': 1.0008932233441359}, 'wait_time': 4, 'is_composite': False}
23:17:43 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 4.0
23:17:43 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Internal
23:17:43 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state SCAN.
23:17:43 INFO geecs_scanner.data_acquisition.scan_executor - idle time between scan steps: 4.404714584350586
23:17:44 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 98.0. reported by UC_ALineEBeam3
23:17:44 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.00082 for elapsed time 98.0.
23:17:44 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.504438 for elapsed time 98.0.
23:17:45 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 99.0. reported by UC_ALineEBeam3
23:17:45 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.00082 for elapsed time 99.0.
23:17:45 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.504438 for elapsed time 99.0.
23:17:46 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 100.0. reported by UC_ALineEBeam3
23:17:46 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.00082 for elapsed time 100.0.
23:17:46 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.504438 for elapsed time 100.0.
23:17:47 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 101.0. reported by UC_ALineEBeam3
23:17:47 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.00082 for elapsed time 101.0.
23:17:47 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.504438 for elapsed time 101.0.
23:17:48 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:17:48 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:17:48 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:17:48 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Running analyzer for device 'UC_ALineEBeam3' on bin 12
23:17:48 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:49 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Extracted 10 scalar results from 'UC_ALineEBeam3'
23:17:49 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 10.316046894212349 for shot 48
23:17:49 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 10.316046894212349 for shot 49
23:17:49 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 10.316046894212349 for shot 50
23:17:49 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 10.316046894212349 for shot 51
23:17:49 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.175 for shot 48
23:17:49 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0125 for shot 48
23:17:49 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -6.387281062100314 for shot 48
23:17:49 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.175 for shot 49
23:17:49 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0125 for shot 49
23:17:49 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -6.387281062100314 for shot 49
23:17:49 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.175 for shot 50
23:17:49 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0125 for shot 50
23:17:49 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -6.387281062100314 for shot 50
23:17:49 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.175 for shot 51
23:17:49 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0125 for shot 51
23:17:49 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -6.387281062100314 for shot 51
23:17:49 INFO geecs_scanner.data_acquisition.scan_executor - Successfully evaluated data for scan step 11
23:17:49 INFO geecs_scanner.data_acquisition.scan_executor - Running advanced optimizer
23:17:50 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:50 INFO geecs_scanner.data_acquisition.scan_executor - Next experimental step generated using optimizer: {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.1301360167098307, 'U_S1H:Current': 1.026988583311436}
23:17:50 INFO geecs_scanner.data_acquisition.scan_executor - Updated scan step 12 with new configuration: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.1301360167098307, 'U_S1H:Current': 1.026988583311436}, 'wait_time': 4, 'is_composite': False}
23:17:50 INFO geecs_scanner.data_acquisition.scan_executor - Executing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.1301360167098307, 'U_S1H:Current': 1.026988583311436}, 'wait_time': 4, 'is_composite': False}
23:17:50 INFO geecs_scanner.data_acquisition.scan_executor - Preparing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.1301360167098307, 'U_S1H:Current': 1.026988583311436}, 'wait_time': 4, 'is_composite': False}
23:17:50 INFO geecs_scanner.data_acquisition.scan_executor - Pausing logging. Turning trigger off before moving devices.
23:17:51 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:17:51 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:17:51 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:17:51 INFO geecs_scanner.data_acquisition.scan_executor - Moving devices for step: {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.1301360167098307, 'U_S1H:Current': 1.026988583311436}
23:17:51 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Preparing to set vars: [('Current_Limit.Ch1', 1.1301360167098307)]
23:17:51 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Preparing to set vars: [('Current', 1.026988583311436)]
23:17:51 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Attempt 1: Set Current_Limit.Ch1=1.1301360167098307, got 1.130136
23:17:51 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Success: Current_Limit.Ch1=1.130136 within tolerance 0.01
23:17:51 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:52 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Attempt 1: Set Current=1.026988583311436, got 1.026989
23:17:52 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Success: Current=1.026989 within tolerance 0.05
23:17:52 INFO geecs_scanner.data_acquisition.scan_executor - Waiting for acquisition: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.1301360167098307, 'U_S1H:Current': 1.026988583311436}, 'wait_time': 4, 'is_composite': False}
23:17:52 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 4.0
23:17:53 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Internal
23:17:53 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state SCAN.
23:17:53 INFO geecs_scanner.data_acquisition.scan_executor - idle time between scan steps: 4.802780866622925
23:17:53 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:54 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 108.0. reported by UC_ALineEBeam3
23:17:54 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.02694 for elapsed time 108.0.
23:17:54 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.130136 for elapsed time 108.0.
23:17:55 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 109.0. reported by UC_ALineEBeam3
23:17:55 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.02694 for elapsed time 109.0.
23:17:55 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.130136 for elapsed time 109.0.
23:17:56 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 110.0. reported by UC_ALineEBeam3
23:17:56 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.02694 for elapsed time 110.0.
23:17:56 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.130136 for elapsed time 110.0.
23:17:57 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 111.0. reported by UC_ALineEBeam3
23:17:57 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.02694 for elapsed time 111.0.
23:17:57 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.130136 for elapsed time 111.0.
23:17:57 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:17:57 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:17:57 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:17:57 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Running analyzer for device 'UC_ALineEBeam3' on bin 13
23:17:58 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Extracted 10 scalar results from 'UC_ALineEBeam3'
23:17:58 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 62.07514738124516 for shot 52
23:17:58 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 62.07514738124516 for shot 53
23:17:58 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 62.07514738124516 for shot 54
23:17:58 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 62.07514738124516 for shot 55
23:17:58 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.08125 for shot 52
23:17:58 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0275000000000003 for shot 52
23:17:58 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.276310075827155 for shot 52
23:17:58 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.08125 for shot 53
23:17:58 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0275000000000003 for shot 53
23:17:58 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.276310075827155 for shot 53
23:17:58 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.08125 for shot 54
23:17:58 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0275000000000003 for shot 54
23:17:58 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.276310075827155 for shot 54
23:17:58 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.08125 for shot 55
23:17:58 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0275000000000003 for shot 55
23:17:58 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.276310075827155 for shot 55
23:17:58 INFO geecs_scanner.data_acquisition.scan_executor - Successfully evaluated data for scan step 12
23:17:58 INFO geecs_scanner.data_acquisition.scan_executor - Running advanced optimizer
23:17:58 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:17:59 INFO geecs_scanner.data_acquisition.scan_executor - Next experimental step generated using optimizer: {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.8370927522426984, 'U_S1H:Current': 0.9347352652068902}
23:17:59 INFO geecs_scanner.data_acquisition.scan_executor - Updated scan step 13 with new configuration: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.8370927522426984, 'U_S1H:Current': 0.9347352652068902}, 'wait_time': 4, 'is_composite': False}
23:17:59 INFO geecs_scanner.data_acquisition.scan_executor - Executing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.8370927522426984, 'U_S1H:Current': 0.9347352652068902}, 'wait_time': 4, 'is_composite': False}
23:17:59 INFO geecs_scanner.data_acquisition.scan_executor - Preparing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.8370927522426984, 'U_S1H:Current': 0.9347352652068902}, 'wait_time': 4, 'is_composite': False}
23:17:59 INFO geecs_scanner.data_acquisition.scan_executor - Pausing logging. Turning trigger off before moving devices.
23:17:59 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:17:59 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:17:59 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:17:59 INFO geecs_scanner.data_acquisition.scan_executor - Moving devices for step: {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.8370927522426984, 'U_S1H:Current': 0.9347352652068902}
23:17:59 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Preparing to set vars: [('Current_Limit.Ch1', 0.8370927522426984)]
23:17:59 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Preparing to set vars: [('Current', 0.9347352652068902)]
23:18:00 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Attempt 1: Set Current_Limit.Ch1=0.8370927522426984, got 0.837093
23:18:00 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Success: Current_Limit.Ch1=0.837093 within tolerance 0.01
23:18:00 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:18:01 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Attempt 1: Set Current=0.9347352652068902, got 0.934735
23:18:01 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Success: Current=0.934735 within tolerance 0.05
23:18:01 INFO geecs_scanner.data_acquisition.scan_executor - Waiting for acquisition: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 0.8370927522426984, 'U_S1H:Current': 0.9347352652068902}, 'wait_time': 4, 'is_composite': False}
23:18:01 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 4.0
23:18:01 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:18:01 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Internal
23:18:01 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state SCAN.
23:18:01 INFO geecs_scanner.data_acquisition.scan_executor - idle time between scan steps: 4.206933259963989
23:18:02 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 116.0. reported by UC_ALineEBeam3
23:18:02 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 0.9347 for elapsed time 116.0.
23:18:02 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.837093 for elapsed time 116.0.
23:18:03 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 117.0. reported by UC_ALineEBeam3
23:18:03 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 0.9347 for elapsed time 117.0.
23:18:03 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.837093 for elapsed time 117.0.
23:18:04 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 118.0. reported by UC_ALineEBeam3
23:18:04 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 0.9347 for elapsed time 118.0.
23:18:04 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.837093 for elapsed time 118.0.
23:18:05 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 119.0. reported by UC_ALineEBeam3
23:18:05 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 0.9347 for elapsed time 119.0.
23:18:05 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 0.837093 for elapsed time 119.0.
23:18:05 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:18:06 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:18:06 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:18:06 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Running analyzer for device 'UC_ALineEBeam3' on bin 14
23:18:06 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:18:07 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Extracted 10 scalar results from 'UC_ALineEBeam3'
23:18:07 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -118.94778027438038 for shot 56
23:18:07 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -118.94778027438038 for shot 57
23:18:07 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -118.94778027438038 for shot 58
23:18:07 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = -118.94778027438038 for shot 59
23:18:07 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.08125 for shot 56
23:18:07 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0050000000000001 for shot 56
23:18:07 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -6.915708611483766 for shot 56
23:18:07 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.08125 for shot 57
23:18:07 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0050000000000001 for shot 57
23:18:07 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -6.915708611483766 for shot 57
23:18:07 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.08125 for shot 58
23:18:07 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0050000000000001 for shot 58
23:18:07 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -6.915708611483766 for shot 58
23:18:07 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.08125 for shot 59
23:18:07 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0050000000000001 for shot 59
23:18:07 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -6.915708611483766 for shot 59
23:18:07 INFO geecs_scanner.data_acquisition.scan_executor - Successfully evaluated data for scan step 13
23:18:07 INFO geecs_scanner.data_acquisition.scan_executor - Running advanced optimizer
23:18:08 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:18:09 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:18:11 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:18:11 INFO geecs_scanner.data_acquisition.scan_executor - Next experimental step generated using optimizer: {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.9832353856439926, 'U_S1H:Current': 1.035554761863508}
23:18:11 INFO geecs_scanner.data_acquisition.scan_executor - Updated scan step 14 with new configuration: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.9832353856439926, 'U_S1H:Current': 1.035554761863508}, 'wait_time': 4, 'is_composite': False}
23:18:11 INFO geecs_scanner.data_acquisition.scan_executor - Executing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.9832353856439926, 'U_S1H:Current': 1.035554761863508}, 'wait_time': 4, 'is_composite': False}
23:18:11 INFO geecs_scanner.data_acquisition.scan_executor - Preparing scan step: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.9832353856439926, 'U_S1H:Current': 1.035554761863508}, 'wait_time': 4, 'is_composite': False}
23:18:11 INFO geecs_scanner.data_acquisition.scan_executor - Pausing logging. Turning trigger off before moving devices.
23:18:11 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:18:11 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:18:11 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:18:11 INFO geecs_scanner.data_acquisition.scan_executor - Moving devices for step: {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.9832353856439926, 'U_S1H:Current': 1.035554761863508}
23:18:11 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Preparing to set vars: [('Current_Limit.Ch1', 1.9832353856439926)]
23:18:11 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Preparing to set vars: [('Current', 1.035554761863508)]
23:18:11 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Attempt 1: Set Current_Limit.Ch1=1.9832353856439926, got 1.983235
23:18:11 INFO geecs_scanner.data_acquisition.scan_executor - [U_EMQTripletBipolar] Success: Current_Limit.Ch1=1.983235 within tolerance 0.01
23:18:12 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:18:12 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Attempt 1: Set Current=1.035554761863508, got 1.035555
23:18:12 INFO geecs_scanner.data_acquisition.scan_executor - [U_S1H] Success: Current=1.035555 within tolerance 0.05
23:18:12 INFO geecs_scanner.data_acquisition.scan_executor - Waiting for acquisition: {'variables': {'U_EMQTripletBipolar:Current_Limit.Ch1': 1.9832353856439926, 'U_S1H:Current': 1.035554761863508}, 'wait_time': 4, 'is_composite': False}
23:18:13 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 4.0
23:18:13 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Internal
23:18:13 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state SCAN.
23:18:13 INFO geecs_scanner.data_acquisition.scan_executor - idle time between scan steps: 7.216945171356201
23:18:13 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 128.0. reported by UC_ALineEBeam3
23:18:13 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.0355 for elapsed time 128.0.
23:18:13 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.983235 for elapsed time 128.0.
23:18:14 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 129.0. reported by UC_ALineEBeam3
23:18:14 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.0355 for elapsed time 129.0.
23:18:14 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.983235 for elapsed time 129.0.
23:18:15 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 130.0. reported by UC_ALineEBeam3
23:18:15 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.0355 for elapsed time 130.0.
23:18:15 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.983235 for elapsed time 130.0.
23:18:16 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 131.0. reported by UC_ALineEBeam3
23:18:16 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.0355 for elapsed time 131.0.
23:18:16 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.983235 for elapsed time 131.0.
23:18:17 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:18:17 INFO geecs_scanner.data_acquisition.data_logger - elapsed time in sync devices 132.0. reported by UC_ALineEBeam3
23:18:17 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_S1H:Current to 1.0355 for elapsed time 132.0.
23:18:17 INFO geecs_scanner.data_acquisition.data_logger - Updated async var U_EMQTripletBipolar:Current_Limit.Ch1 to 1.983235 for elapsed time 132.0.
23:18:17 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to Single shot
23:18:17 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state OFF.
23:18:18 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Running analyzer for device 'UC_ALineEBeam3' on bin 15
23:18:18 INFO geecs_scanner.optimization.evaluators.multi_device_scan_evaluator - Extracted 10 scalar results from 'UC_ALineEBeam3'
23:18:18 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 115.62868364243197 for shot 60
23:18:18 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 115.62868364243197 for shot 61
23:18:18 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 115.62868364243197 for shot 62
23:18:18 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 115.62868364243197 for shot 63
23:18:18 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:x_CoM = 115.62868364243197 for shot 64
23:18:18 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.38125 for shot 60
23:18:18 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0425 for shot 60
23:18:18 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.993533947954224 for shot 60
23:18:18 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.38125 for shot 61
23:18:18 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0425 for shot 61
23:18:18 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.993533947954224 for shot 61
23:18:18 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.38125 for shot 62
23:18:18 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0425 for shot 62
23:18:18 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.993533947954224 for shot 62
23:18:18 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.38125 for shot 63
23:18:18 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0425 for shot 63
23:18:18 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.993533947954224 for shot 63
23:18:18 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_EMQTripletBipolar:Current_Limit.Ch1] = 1.38125 for shot 64
23:18:18 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_center[U_S1H:Current] = 1.0425 for shot 64
23:18:18 INFO geecs_scanner.optimization.base_evaluator - Logged Observable:BAX_solution_entropy = -7.993533947954224 for shot 64
23:18:18 INFO geecs_scanner.data_acquisition.scan_executor - Successfully evaluated data for scan step 14
23:18:18 INFO geecs_scanner.data_acquisition.scan_executor - Scan loop completed. Stopping logging.
23:18:18 INFO geecs_scanner.data_acquisition.scan_manager - scan Scan020: stopping
23:18:18 INFO geecs_scanner.data_acquisition.data_logger - Timestamp hasn't changed for UC_ALineEBeam3. Skipping log.
23:18:19 WARNING geecs_python_api.controls.interface.event_handler - attempted to unregister from unknown event update
23:18:19 WARNING geecs_python_api.controls.interface.event_handler - attempted to unregister from unknown event update
23:18:19 INFO geecs_scanner.data_acquisition.scan_manager - Setting save to off for UC_ALineEBeam3
23:18:19 INFO geecs_scanner.data_acquisition.scan_manager - Setting save to off for UC_ALineEBeam3 complete
23:18:19 INFO geecs_scanner.data_acquisition.scan_manager - Setting save path back to temp for UC_ALineEBeam3 complete
23:18:21 INFO geecs_scanner.data_acquisition.scan_manager - scanning has stopped for all devices.
23:18:21 INFO geecs_scanner.data_acquisition.scan_manager - Setting Amplitude.Ch AB to 0.5
23:18:22 INFO geecs_scanner.data_acquisition.scan_manager - Setting Trigger.Source to External rising edges
23:18:22 INFO geecs_scanner.data_acquisition.scan_manager - Trigger turned to state STANDBY.
23:18:22 INFO geecs_scanner.data_acquisition.scan_manager - Attempting to execute closeout actions.
23:18:22 INFO geecs_scanner.data_acquisition.scan_manager - Action list steps=[]
23:18:22 INFO geecs_scanner.data_acquisition.action_manager - Added action sequence: closeout_action
23:18:22 INFO geecs_scanner.data_acquisition.action_manager - Starting execution of action sequence: closeout_action
23:18:22 INFO geecs_scanner.data_acquisition.action_manager - Checking connectivity for 0 devices
23:18:22 INFO geecs_scanner.data_acquisition.action_manager - Successfully completed action sequence: closeout_action
23:18:22 INFO geecs_scanner.data_acquisition.scan_data_manager - Filled remaining NaN and empty values with 0.
23:18:22 INFO geecs_scanner.data_acquisition.scan_data_manager - Data logging complete. Returning DataFrame.
23:18:22 INFO geecs_scanner.data_acquisition.scan_data_manager - Scan data saved to Z:\data\Undulator\Y2025\10-Oct\25_1028\scans\Scan020\ScanDataScan020.txt
23:18:22 INFO geecs_scanner.data_acquisition.scan_data_manager - TDMS file written successfully with comprehensive metadata.
23:18:22 INFO geecs_scanner.data_acquisition.data_logger - looking to handle orphaned data files
23:18:22 INFO geecs_scanner.data_acquisition.data_logger - FileMover worker stopped.
23:18:22 INFO geecs_scanner.data_acquisition.data_logger - FileMover worker stopped.
23:18:23 INFO geecs_scanner.data_acquisition.data_logger - FileMover worker stopped.
23:18:23 INFO geecs_scanner.data_acquisition.data_logger - FileMover worker stopped.
23:18:23 INFO geecs_scanner.data_acquisition.data_logger - FileMover worker stopped.
23:18:23 INFO geecs_scanner.data_acquisition.data_logger - FileMover worker stopped.
23:18:23 INFO geecs_scanner.data_acquisition.data_logger - FileMover worker stopped.
23:18:23 INFO geecs_scanner.data_acquisition.data_logger - FileMover worker stopped.
23:18:23 INFO geecs_scanner.data_acquisition.data_logger - FileMover worker stopped.
23:18:23 INFO geecs_scanner.data_acquisition.data_logger - FileMover worker stopped.
23:18:23 INFO geecs_scanner.data_acquisition.data_logger - FileMover worker stopped.
23:18:23 INFO geecs_scanner.data_acquisition.data_logger - FileMover worker stopped.
23:18:23 INFO geecs_scanner.data_acquisition.data_logger - FileMover worker stopped.
23:18:23 INFO geecs_scanner.data_acquisition.data_logger - FileMover worker stopped.
23:18:23 INFO geecs_scanner.data_acquisition.data_logger - FileMover worker stopped.
23:18:23 INFO geecs_scanner.data_acquisition.data_logger - FileMover worker stopped.
23:18:23 INFO geecs_scanner.data_acquisition.data_logger - FileMover has been shut down gracefully.
23:18:23 INFO geecs_scanner.data_acquisition.scan_data_manager - Analysis-ready data saved to Z:\data\Undulator\Y2025\10-Oct\25_1028\analysis\s20.txt
23:18:23 INFO geecs_scanner.data_acquisition.device_manager - Attempting to unsubscribe from UC_ALineEBeam3...
23:18:24 INFO geecs_scanner.data_acquisition.device_manager - Successfully unsubscribed from UC_ALineEBeam3.
23:18:24 INFO geecs_scanner.data_acquisition.device_manager - Attempting to unsubscribe from U_S1H...
23:18:25 INFO geecs_scanner.data_acquisition.device_manager - Successfully unsubscribed from U_S1H.
23:18:25 INFO geecs_scanner.data_acquisition.device_manager - Attempting to unsubscribe from U_EMQTripletBipolar...
23:18:26 INFO geecs_scanner.data_acquisition.device_manager - Successfully unsubscribed from U_EMQTripletBipolar.
23:18:26 INFO geecs_scanner.data_acquisition.device_manager - synchronous variables after reset: []
23:18:26 INFO geecs_scanner.data_acquisition.device_manager - asynchronous variables after reset: []
23:18:26 INFO geecs_scanner.data_acquisition.device_manager - non_scalar_saving_devices devices after reset: []
23:18:26 INFO geecs_scanner.data_acquisition.device_manager - devices devices after reset: {}
23:18:26 INFO geecs_scanner.data_acquisition.device_manager - DeviceManager instance has been reset and is ready for reinitialization.
23:18:26 INFO geecs_scanner.data_acquisition.scan_manager - scan Scan020: finished
In [2]:
Copied!
# success = manager.reinitialize(config_path=config_filename)
success = manager.reinitialize()
scan_config = ScanConfig
scan_config.start = 1
scan_config.end = 15
scan_config.step = 1
scan_config.wait_time = 4
scan_config.scan_mode = ScanMode.OPTIMIZATION
scan_config.optimizer_config_path = optimizer_config_path
# success = manager.reinitialize(config_path=config_filename)
success = manager.reinitialize()
scan_config = ScanConfig
scan_config.start = 1
scan_config.end = 15
scan_config.step = 1
scan_config.wait_time = 4
scan_config.scan_mode = ScanMode.OPTIMIZATION
scan_config.optimizer_config_path = optimizer_config_path
In [3]:
Copied!
manager.scan_config = scan_config
manager.start_scan_thread(scan_config)
manager.scan_config = scan_config
manager.start_scan_thread(scan_config)
C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-08 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-08 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-08 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-07 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-08 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-07 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-08 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-07 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-08 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-07 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-08 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-07 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-08 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-07 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-08 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-07 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-08 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-07 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-08 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-07 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-08 to the diagonal warnings.warn( C:\Users\loasis.LOASIS\AppData\Local\pypoetry\Cache\virtualenvs\geecs-docs-R2O6GHEs-py3.10\lib\site-packages\linear_operator\utils\cholesky.py:40: NumericalWarning: A not p.d., added jitter of 1.0e-07 to the diagonal warnings.warn(
In [4]:
Copied!
import pickle
import torch
data = pickle.load(open("bax_probe_results_8.pkl", "rb"))
data["solution_center"]
import pickle
import torch
data = pickle.load(open("bax_probe_results_8.pkl", "rb"))
data["solution_center"]
Out[4]:
array([1.1975, 0.9225])
In [ ]:
Copied!
manager.stop_scan()
manager.stop_scan()
In [ ]:
Copied!
data.keys()
data.keys()
In [10]:
Copied!
import pickle
import matplotlib.pyplot as plt
# Path to scan directory
scan_dir = Path("Z:/data/Undulator/Y2025/10-Oct/25_1028/scans/Scan020")
# Extract solution_center from each iteration
iterations = []
s1h_values = []
emq_values = []
entropies = []
for i in range(1, scan_config.end - 3 + 1): # Files 1-11
pkl_file = Path(scan_dir / f"multipoint_bax_alignment_l2_algo_results_{i}.pkl")
if pkl_file.exists():
with open(pkl_file, "rb") as f:
data = pickle.load(f)
solution = data["solution_center"]
entropy = data["solution_entropy"]
iterations.append(i)
emq_values.append(solution[0]) # EMQ (should be constant ~1.25)
s1h_values.append(solution[1]) # S1H (the control variable)
entropies.append(entropy)
# Plot convergence
fig, (ax1, ax2) = plt.subplots(2, 1, figsize=(5, 4), sharex=True)
# S1H convergence
ax1.plot(iterations, s1h_values, "o-", linewidth=2, markersize=8)
ax1.axhline(y=1.0, color="r", linestyle="--", label="True optimum (S1H=1.0)")
ax1.set_ylabel("S1H Current (A)", fontsize=12)
ax1.set_title("BAX Convergence: Solution Center vs Iteration", fontsize=14)
ax1.grid(True, alpha=0.3)
ax1.legend()
# Entropy (uncertainty)
ax2.plot(iterations, entropies, "s-", color="orange", linewidth=2, markersize=8)
ax2.set_xlabel("Iteration", fontsize=12)
ax2.set_ylabel("Solution Entropy", fontsize=12)
ax2.set_title("Uncertainty in Solution", fontsize=14)
ax2.grid(True, alpha=0.3)
plt.tight_layout()
plt.show()
# Print summary
print("Iteration | S1H Value | Entropy")
print("-" * 40)
for i, s1h, ent in zip(iterations, s1h_values, entropies):
print(f"{i:9d} | {s1h:9.4f} | {ent:7.4f}")
print(f"\nFinal S1H: {s1h_values[-1]:.4f} A")
print("True optimum: 1.0000 A")
print(f"Error: {abs(s1h_values[-1] - 1.0):.4f} A")
import pickle
import matplotlib.pyplot as plt
# Path to scan directory
scan_dir = Path("Z:/data/Undulator/Y2025/10-Oct/25_1028/scans/Scan020")
# Extract solution_center from each iteration
iterations = []
s1h_values = []
emq_values = []
entropies = []
for i in range(1, scan_config.end - 3 + 1): # Files 1-11
pkl_file = Path(scan_dir / f"multipoint_bax_alignment_l2_algo_results_{i}.pkl")
if pkl_file.exists():
with open(pkl_file, "rb") as f:
data = pickle.load(f)
solution = data["solution_center"]
entropy = data["solution_entropy"]
iterations.append(i)
emq_values.append(solution[0]) # EMQ (should be constant ~1.25)
s1h_values.append(solution[1]) # S1H (the control variable)
entropies.append(entropy)
# Plot convergence
fig, (ax1, ax2) = plt.subplots(2, 1, figsize=(5, 4), sharex=True)
# S1H convergence
ax1.plot(iterations, s1h_values, "o-", linewidth=2, markersize=8)
ax1.axhline(y=1.0, color="r", linestyle="--", label="True optimum (S1H=1.0)")
ax1.set_ylabel("S1H Current (A)", fontsize=12)
ax1.set_title("BAX Convergence: Solution Center vs Iteration", fontsize=14)
ax1.grid(True, alpha=0.3)
ax1.legend()
# Entropy (uncertainty)
ax2.plot(iterations, entropies, "s-", color="orange", linewidth=2, markersize=8)
ax2.set_xlabel("Iteration", fontsize=12)
ax2.set_ylabel("Solution Entropy", fontsize=12)
ax2.set_title("Uncertainty in Solution", fontsize=14)
ax2.grid(True, alpha=0.3)
plt.tight_layout()
plt.show()
# Print summary
print("Iteration | S1H Value | Entropy")
print("-" * 40)
for i, s1h, ent in zip(iterations, s1h_values, entropies):
print(f"{i:9d} | {s1h:9.4f} | {ent:7.4f}")
print(f"\nFinal S1H: {s1h_values[-1]:.4f} A")
print("True optimum: 1.0000 A")
print(f"Error: {abs(s1h_values[-1] - 1.0):.4f} A")
Iteration | S1H Value | Entropy
----------------------------------------
1 | 0.0750 | -1.8627
2 | 0.5475 | -2.0142
3 | 0.8550 | -1.8318
4 | 1.2225 | -4.5681
5 | 1.0275 | -6.3074
6 | 1.0275 | -7.0139
7 | 1.0275 | -7.2061
8 | 1.0350 | -7.2338
9 | 1.0125 | -6.3873
10 | 1.0275 | -7.2763
11 | 1.0050 | -6.9157
12 | 1.0425 | -7.9935
Final S1H: 1.0425 A
True optimum: 1.0000 A
Error: 0.0425 A
In [11]:
Copied!
import pickle
import matplotlib.pyplot as plt
from pathlib import Path
# Collect data from all iterations
results = []
for sample in range(1, scan_config.end - 2): # Iterations 1-11
try:
file_path = Path(
scan_dir / f"multipoint_bax_alignment_l2_algo_results_{sample}.pkl"
)
data = pickle.load(open(file_path, "rb"))
test_points = data["test_points"]
posterior_samples = data["posterior_samples"]
solution_center = data["solution_center"]
S, N, _ = posterior_samples.shape
# Get minimum location for each MC sample
min_indices = posterior_samples.argmin(dim=1).squeeze()
min_s1h_values = test_points[min_indices, 1] # S1H values at minima
# Statistics
results.append(
{
"iteration": sample,
"solution_center_s1h": solution_center[1], # S1H from solution_center
"vo_min": float(posterior_samples.min()),
"vo_max": float(posterior_samples.max()),
"vo_mean": float(posterior_samples.mean()),
"vo_std": float(posterior_samples.std()),
"n_unique_minima": len(torch.unique(min_indices)),
"mean_min_s1h": float(min_s1h_values.mean()),
"std_min_s1h": float(min_s1h_values.std()),
"most_common_s1h": float(
test_points[torch.mode(min_indices).values, 1]
),
}
)
except FileNotFoundError:
print(f"{file_path} not found")
# Print summary table
print("=" * 100)
print(
f"{'Iter':<6} {'Sol Ctr':<10} {'Mean Min':<10} {'Most Common':<12} {'VO Range':<20} {'# Unique':<10}"
)
print("=" * 100)
for r in results:
print(
f"{r['iteration']:<6} "
f"{r['solution_center_s1h']:>9.4f} "
f"{r['mean_min_s1h']:>9.4f} "
f"{r['most_common_s1h']:>11.4f} "
f"{r['vo_min']:.4f}-{r['vo_max']:.4f} "
f"{r['n_unique_minima']:>9}"
)
print("=" * 100)
# Create comprehensive plot
fig, axes = plt.subplots(2, 2, figsize=(8.0, 5))
# Plot 1: Solution center convergence
ax = axes[0, 0]
iters = [r["iteration"] for r in results]
sol_centers = [r["solution_center_s1h"] for r in results]
ax.plot(iters, sol_centers, "o-", linewidth=2, markersize=8)
ax.axhline(y=1.0, color="r", linestyle="--", label="True optimum")
ax.set_xlabel("Iteration")
ax.set_ylabel("S1H (A)")
ax.set_title("Solution Center vs Iteration")
ax.grid(True, alpha=0.3)
ax.legend()
# Plot 2: Virtual objective range evolution
ax = axes[0, 1]
vo_mins = [r["vo_min"] for r in results]
vo_maxs = [r["vo_max"] for r in results]
vo_means = [r["vo_mean"] for r in results]
ax.fill_between(iters, vo_mins, vo_maxs, alpha=0.3, label="Min-Max range")
ax.plot(iters, vo_means, "o-", linewidth=2, markersize=6, label="Mean")
ax.set_xlabel("Iteration")
ax.set_ylabel("Virtual Objective (|slope|)")
ax.set_title("Virtual Objective Range vs Iteration")
ax.grid(True, alpha=0.3)
ax.legend()
# Plot 3: Number of unique minima
ax = axes[1, 0]
n_unique = [r["n_unique_minima"] for r in results]
ax.plot(iters, n_unique, "s-", linewidth=2, markersize=8, color="orange")
ax.set_xlabel("Iteration")
ax.set_ylabel("Number of Unique Minima")
ax.set_title("Diversity of MC Solutions")
ax.grid(True, alpha=0.3)
# Plot 4: Final iteration virtual objective landscape
ax = axes[1, 1]
final_data = pickle.load(open(file_path, "rb"))
s1h_vals = final_data["test_points"][:, 1].numpy()
vo_vals = final_data["posterior_samples"][0, :, 0].numpy()
ax.scatter(s1h_vals, vo_vals, alpha=0.6, s=50)
ax.axvline(x=1.0, color="r", linestyle="--", linewidth=2, label="True optimum")
ax.axvline(
x=results[-1]["solution_center_s1h"],
color="g",
linestyle="--",
linewidth=2,
label=f"BAX solution ({results[-1]['solution_center_s1h']:.2f})",
)
ax.set_xlabel("S1H Current (A)")
ax.set_ylabel("Virtual Objective (|slope|)")
ax.set_title("Final Virtual Objective Landscape (Iter 11, Sample 0)")
ax.grid(True, alpha=0.3)
ax.legend()
plt.tight_layout()
plt.savefig("bax_convergence_analysis.png", dpi=150, bbox_inches="tight")
plt.show()
print(f"\nFinal result: S1H = {results[-1]['solution_center_s1h']:.4f} A")
print("True optimum: S1H = 1.0000 A")
print(f"Error: {abs(results[-1]['solution_center_s1h'] - 1.0):.4f} A")
import pickle
import matplotlib.pyplot as plt
from pathlib import Path
# Collect data from all iterations
results = []
for sample in range(1, scan_config.end - 2): # Iterations 1-11
try:
file_path = Path(
scan_dir / f"multipoint_bax_alignment_l2_algo_results_{sample}.pkl"
)
data = pickle.load(open(file_path, "rb"))
test_points = data["test_points"]
posterior_samples = data["posterior_samples"]
solution_center = data["solution_center"]
S, N, _ = posterior_samples.shape
# Get minimum location for each MC sample
min_indices = posterior_samples.argmin(dim=1).squeeze()
min_s1h_values = test_points[min_indices, 1] # S1H values at minima
# Statistics
results.append(
{
"iteration": sample,
"solution_center_s1h": solution_center[1], # S1H from solution_center
"vo_min": float(posterior_samples.min()),
"vo_max": float(posterior_samples.max()),
"vo_mean": float(posterior_samples.mean()),
"vo_std": float(posterior_samples.std()),
"n_unique_minima": len(torch.unique(min_indices)),
"mean_min_s1h": float(min_s1h_values.mean()),
"std_min_s1h": float(min_s1h_values.std()),
"most_common_s1h": float(
test_points[torch.mode(min_indices).values, 1]
),
}
)
except FileNotFoundError:
print(f"{file_path} not found")
# Print summary table
print("=" * 100)
print(
f"{'Iter':<6} {'Sol Ctr':<10} {'Mean Min':<10} {'Most Common':<12} {'VO Range':<20} {'# Unique':<10}"
)
print("=" * 100)
for r in results:
print(
f"{r['iteration']:<6} "
f"{r['solution_center_s1h']:>9.4f} "
f"{r['mean_min_s1h']:>9.4f} "
f"{r['most_common_s1h']:>11.4f} "
f"{r['vo_min']:.4f}-{r['vo_max']:.4f} "
f"{r['n_unique_minima']:>9}"
)
print("=" * 100)
# Create comprehensive plot
fig, axes = plt.subplots(2, 2, figsize=(8.0, 5))
# Plot 1: Solution center convergence
ax = axes[0, 0]
iters = [r["iteration"] for r in results]
sol_centers = [r["solution_center_s1h"] for r in results]
ax.plot(iters, sol_centers, "o-", linewidth=2, markersize=8)
ax.axhline(y=1.0, color="r", linestyle="--", label="True optimum")
ax.set_xlabel("Iteration")
ax.set_ylabel("S1H (A)")
ax.set_title("Solution Center vs Iteration")
ax.grid(True, alpha=0.3)
ax.legend()
# Plot 2: Virtual objective range evolution
ax = axes[0, 1]
vo_mins = [r["vo_min"] for r in results]
vo_maxs = [r["vo_max"] for r in results]
vo_means = [r["vo_mean"] for r in results]
ax.fill_between(iters, vo_mins, vo_maxs, alpha=0.3, label="Min-Max range")
ax.plot(iters, vo_means, "o-", linewidth=2, markersize=6, label="Mean")
ax.set_xlabel("Iteration")
ax.set_ylabel("Virtual Objective (|slope|)")
ax.set_title("Virtual Objective Range vs Iteration")
ax.grid(True, alpha=0.3)
ax.legend()
# Plot 3: Number of unique minima
ax = axes[1, 0]
n_unique = [r["n_unique_minima"] for r in results]
ax.plot(iters, n_unique, "s-", linewidth=2, markersize=8, color="orange")
ax.set_xlabel("Iteration")
ax.set_ylabel("Number of Unique Minima")
ax.set_title("Diversity of MC Solutions")
ax.grid(True, alpha=0.3)
# Plot 4: Final iteration virtual objective landscape
ax = axes[1, 1]
final_data = pickle.load(open(file_path, "rb"))
s1h_vals = final_data["test_points"][:, 1].numpy()
vo_vals = final_data["posterior_samples"][0, :, 0].numpy()
ax.scatter(s1h_vals, vo_vals, alpha=0.6, s=50)
ax.axvline(x=1.0, color="r", linestyle="--", linewidth=2, label="True optimum")
ax.axvline(
x=results[-1]["solution_center_s1h"],
color="g",
linestyle="--",
linewidth=2,
label=f"BAX solution ({results[-1]['solution_center_s1h']:.2f})",
)
ax.set_xlabel("S1H Current (A)")
ax.set_ylabel("Virtual Objective (|slope|)")
ax.set_title("Final Virtual Objective Landscape (Iter 11, Sample 0)")
ax.grid(True, alpha=0.3)
ax.legend()
plt.tight_layout()
plt.savefig("bax_convergence_analysis.png", dpi=150, bbox_inches="tight")
plt.show()
print(f"\nFinal result: S1H = {results[-1]['solution_center_s1h']:.4f} A")
print("True optimum: S1H = 1.0000 A")
print(f"Error: {abs(results[-1]['solution_center_s1h'] - 1.0):.4f} A")
==================================================================================================== Iter Sol Ctr Mean Min Most Common VO Range # Unique ==================================================================================================== 1 0.0750 0.0750 -0.7500 5.1099-1699.4860 18 2 0.5475 0.5475 1.0500 0.7284-2157.0091 19 3 0.8550 0.8550 1.5000 0.4461-2322.1223 15 4 1.2225 1.2225 1.0500 1.3875-3139.6170 16 5 1.0275 1.0275 1.0500 0.8552-3125.4158 10 6 1.0275 1.0275 1.0500 5.3408-2979.6022 8 7 1.0275 1.0275 1.0500 11.2768-2607.4171 7 8 1.0350 1.0350 1.0500 1.1270-2208.5611 7 9 1.0125 1.0125 1.0500 8.8410-2110.4025 8 10 1.0275 1.0275 1.0500 14.7573-2237.8769 7 11 1.0050 1.0050 1.0500 3.0773-2161.1800 8 12 1.0425 1.0425 1.0500 1.7341-2188.0054 6 ====================================================================================================
Final result: S1H = 1.0425 A True optimum: S1H = 1.0000 A Error: 0.0425 A
In [12]:
Copied!
from xopt.generators.bayesian.visualize import visualize_model
model = manager.optimizer.xopt.generator.model
vocs2 = manager.optimizer.xopt.vocs
data2 = manager.optimizer.xopt.data
from xopt.generators.bayesian.visualize import visualize_model
model = manager.optimizer.xopt.generator.model
vocs2 = manager.optimizer.xopt.vocs
data2 = manager.optimizer.xopt.data
In [ ]:
Copied!
%matplotlib inline
fig, ax = visualize_model(
model,
vocs2,
data2,
variable_names=[
"U_EMQTripletBipolar:Current_Limit.Ch1",
"U_S1H:Current",
],
reference_point={"U_S1V:Current": 1.0},
show_prior_mean=False,
n_grid=50,
)
%matplotlib inline
fig, ax = visualize_model(
model,
vocs2,
data2,
variable_names=[
"U_EMQTripletBipolar:Current_Limit.Ch1",
"U_S1H:Current",
],
reference_point={"U_S1V:Current": 1.0},
show_prior_mean=False,
n_grid=50,
)