# Pastebin pYLWgGUe artuuid": null, [ 153.903339] ironic-python-agent[534]: "serial": null, [ 153.903923] ironic-python-agent[534]: "wwn": null, [ 153.908989] ironic-python-agent[534]: "log-sec": 512, [ 153.909676] ironic-python-agent[534]: "phy-sec": 512 [ 153.910244] ironic-python-agent[534]: },{ [ 153.911966] ironic-python-agent[534]: "kname": "loop4", [ 153.918294] ironic-python-agent[534]: "model": null, [ 153.919001] ironic-python-agent[534]: "size": 0, [ 153.920003] ironic-python-agent[534]: "rota": true, [ 153.925550] ironic-python-agent[534]: "type": "loop", [ 153.926286] ironic-python-agent[534]: "uuid": null, [ 153.927911] ironic-python-agent[534]: "partuuid": null, [ 153.928822] ironic-python-agent[534]: "serial": null, [ 153.929435] ironic-python-agent[534]: "wwn": null, [ 153.930001] ironic-python-agent[534]: "log-sec": 512, [ 153.930593] ironic-python-agent[534]: "phy-sec": 512 [ 153.931169] ironic-python-agent[534]: },{ [ 153.936937] ironic-python-agent[534]: "kname": "loop5", [ 153.938568] ironic-python-agent[534]: "model": null, [ 153.939155] ironic-python-agent[534]: "size": 0, [ 153.944764] ironic-python-agent[534]: "rota": true, [ 153.946158] ironic-python-agent[534]: "type": "loop", [ 153.946812] ironic-python-agent[534]: "uuid": null, [ 153.947428] ironic-python-agent[534]: "partuuid": null, [ 153.948004] ironic-python-agent[534]: "serial": null, [ 153.953324] ironic-python-agent[534]: "wwn": null, [ 153.953966] ironic-python-agent[534]: "log-sec": 512, [ 153.954575] ironic-python-agent[534]: "phy-sec": 512 [ 153.955145] ironic-python-agent[534]: },{ [ 153.962121] ironic-python-agent[534]: "kname": "loop6", [ 153.963290] ironic-python-agent[534]: "model": null, [ 153.963947] ironic-python-agent[534]: "size": 0, [ 153.964575] ironic-python-agent[534]: "rota": true, [ 153.965146] ironic-python-agent[534]: "type": "loop", [ 153.965722] ironic-python-agent[534]: "uuid": null, [ 153.974513] ironic-python-agent[534]: "partuuid": null, [ 153.975706] ironic-python-agent[534]: "serial": null, [ 153.976743] ironic-python-agent[534]: "wwn": null, [ 153.977367] ironic-python-agent[534]: "log-sec": 512, [ 153.977966] ironic-python-agent[534]: "phy-sec": 512 [ 153.979013] ironic-python-agent[534]: },{ [ 153.984794] ironic-python-agent[534]: "kname": "loop7", [ 153.986077] ironic-python-agent[534]: "model": null, [ 153.986793] ironic-python-agent[534]: "size": 0, [ 153.987434] ironic-python-agent[534]: "rota": true, [ 153.988008] ironic-python-agent[534]: "type": "loop", [ 153.993254] ironic-python-agent[534]: "uuid": null, [ 153.993881] ironic-python-agent[534]: "partuuid": null, [ 153.994496] ironic-python-agent[534]: "serial": null, [ 153.995062] ironic-python-agent[534]: "wwn": null, [ 154.000630] ironic-python-agent[534]: "log-sec": 512, [ 154.002070] ironic-python-agent[534]: "phy-sec": 512 [ 154.003146] ironic-python-agent[534]: },{ [ 154.003743] ironic-python-agent[534]: "kname": "vda", [ 154.009319] ironic-python-agent[534]: "model": null, [ 154.010096] ironic-python-agent[534]: "size": 11811160064, [ 154.010727] ironic-python-agent[534]: "rota": true, [ 154.011322] ironic-python-agent[534]: "type": "disk", [ 154.011919] ironic-python-agent[534]: "uuid": null, [ 154.021628] ironic-python-agent[534]: "partuuid": null, [ 154.022415] ironic-python-agent[534]: "serial": null, [ 154.023014] ironic-python-agent[534]: "wwn": null, [ 154.023730] ironic-python-agent[534]: "log-sec": 512, [ 154.029084] ironic-python-agent[534]: "phy-sec": 512 [ 154.030005] ironic-python-agent[534]: } [ 154.031078] ironic-python-agent[534]: ] [ 154.031703] ironic-python-agent[534]: } [ 154.037193] ironic-python-agent[534]: " _log /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/utils.py:106 [ 154.038075] ironic-python-agent[534]: 2025-02-24 17:19:50.645 534 DEBUG ironic_python_agent.utils [-] Command stderr is: "" _log /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/utils.py:113 [ 154.039016] ironic-python-agent[534]: 2025-02-24 17:19:50.727 534 DEBUG root [-] TYPE did not match. Wanted: disk but found: {'kname': 'loop0', 'model': None, 'size': 0, 'rota': True, 'type': 'loop', 'uuid': None, 'partuuid': None, 'serial': None, 'wwn': None, 'log-sec': 512, 'phy-sec': 512} (RAID devices are ignored) list_all_block_devices /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/hardware.py:635 [ 154.046630] ironic-python-agent[534]: 2025-02-24 17:19:50.735 534 DEBUG root [-] TYPE did not match. Wanted: disk but found: {'kname': 'loop1', 'model': None, 'size': 0, 'rota': True, 'type': 'loop', 'uuid': None, 'partuuid': None, 'serial': None, 'wwn': None, 'log-sec': 512, 'phy-sec': 512} (RAID devices are ignored) list_all_block_devices /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/hardware.py:635 [ 154.048027] ironic-python-agent[534]: 2025-02-24 17:19:50.741 534 DEBUG root [-] TYPE did not match. Wanted: disk but found: {'kname': 'loop2', 'model': None, 'size': 0, 'rota': True, 'type': 'loop', 'uuid': None, 'partuuid': None, 'serial': None, 'wwn': None, 'log-sec': 512, 'phy-sec': 512} (RAID devices are ignored) list_all_block_devices /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/hardware.py:635 [ 154.054966] ironic-python-agent[534]: 2025-02-24 17:19:50.743 534 DEBUG root [-] TYPE did not match. Wanted: disk but found: {'kname': 'loop3', 'model': None, 'size': 0, 'rota': True, 'type': 'loop', 'uuid': None, 'partuuid': None, 'serial': None, 'wwn': None, 'log-sec': 512, 'phy-sec': 512} (RAID devices are ignored) list_all_block_devices /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/hardware.py:635 [ 154.064416] ironic-python-agent[534]: 2025-02-24 17:19:50.744 534 DEBUG root [-] TYPE did not match. Wanted: disk but found: {'kname': 'loop4', 'model': None, 'size': 0, 'rota': True, 'type': 'loop', 'uuid': None, 'partuuid': None, 'serial': None, 'wwn': None, 'log-sec': 512, 'phy-sec': 512} (RAID devices are ignored) list_all_block_devices /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/hardware.py:635 [ 154.066606] ironic-python-agent[534]: 2025-02-24 17:19:50.749 534 DEBUG root [-] TYPE did not match. Wanted: disk but found: {'kname': 'loop5', 'model': None, 'size': 0, 'rota': True, 'type': 'loop', 'uuid': None, 'partuuid': None, 'serial': None, 'wwn': None, 'log-sec': 512, 'phy-sec': 512} (RAID devices are ignored) list_all_block_devices /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/hardware.py:635 [ 154.067974] ironic-python-agent[534]: 2025-02-24 17:19:50.750 534 DEBUG root [-] TYPE did not match. Wanted: disk but found: {'kname': 'loop6', 'model': None, 'size': 0, 'rota': True, 'type': 'loop', 'uuid': None, 'partuuid': None, 'serial': None, 'wwn': None, 'log-sec': 512, 'phy-sec': 512} (RAID devices are ignored) list_all_block_devices /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/hardware.py:635 [ 154.070451] ironic-python-agent[534]: 2025-02-24 17:19:50.752 534 DEBUG root [-] TYPE did not match. Wanted: disk but found: {'kname': 'loop7', 'model': None, 'size': 0, 'rota': True, 'type': 'loop', 'uuid': None, 'partuuid': None, 'serial': None, 'wwn': None, 'log-sec': 512, 'phy-sec': 512} (RAID devices are ignored) list_all_block_devices /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/hardware.py:635 [ 154.077467] ironic-python-agent[534]: 2025-02-24 17:19:50.777 534 WARNING root [-] Could not find the SCSI address (HCTL) for device /dev/vda. Skipping: FileNotFoundError: [Errno 2] No such file or directory: '/sys/block/vda/device/scsi_device' [ 154.078597] ironic-python-agent[534]: 2025-02-24 17:19:50.787 534 INFO root [-] Picked root device /dev/vda for node 40d4409f-6860-40d4-bcca-9e7115dad3cc based on root device hints None [ 154.079471] ironic-python-agent[534]: 2025-02-24 17:19:50.798 534 DEBUG ironic_python_agent.agent [-] Agent token recorded as designated by the ironic installation. process_lookup_data /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/agent.py:486 [ 154.090327] ironic-python-agent[534]: 2025-02-24 17:19:50.826 534 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ip route get 172.31.85.49 execute /opt/ironic-python-agent/lib/python3.11/site-packages/oslo_concurrency/processutils.py:349 [ 154.178144] ironic-python-agent[534]: 2025-02-24 17:19:51.003 534 DEBUG oslo_concurrency.processutils [-] CMD "ip route get 172.31.85.49" returned: 0 in 0.175s execute /opt/ironic-python-agent/lib/python3.11/site-packages/oslo_concurrency/processutils.py:372 [ 154.183403] ironic-python-agent[534]: 2025-02-24 17:19:51.009 534 DEBUG ironic_python_agent.utils [-] Command stdout is: "172.31.85.49 via 10.0.0.1 dev enp1s0 src 10.0.0.22 uid 0 [ 154.185406] ironic-python-agent[534]: cache [ 154.186046] ironic-python-agent[534]: " _log /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/utils.py:106 [ 154.190412] ironic-python-agent[534]: 2025-02-24 17:19:51.015 534 DEBUG ironic_python_agent.utils [-] Command stderr is: "" _log /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/utils.py:113 [ 154.197425] ironic-python-agent[534]: 2025-02-24 17:19:51.023 534 INFO ironic_python_agent.agent [-] Generating TLS parameters automatically for IP 10.0.0.22 [ 154.202006] ironic-python-agent[534]: 2025-02-24 17:19:51.027 534 DEBUG root [-] HardwareManager does not support generate_tls_certificate dispatch_to_managers /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/hardware.py:3664 [ 154.551167] ironic-python-agent[534]: 2025-02-24 17:19:51.373 534 INFO root [-] Generated TLS certificate for IP address 10.0.0.22 valid from 2025-02-24 16:19:51.306383+00:00 to 2025-03-26 17:19:51.307925+00:00 [ 154.572285] ironic-python-agent[534]: 2025-02-24 17:19:51.397 534 INFO oslo.service.wsgi [-] ironic-python-agent listening on :::9999 [ 154.687525] ironic-python-agent[534]: 2025-02-24 17:19:51.509 534 INFO ironic_python_agent.api.app [-] Started API service on port 9999 [ 154.707644] ironic-python-agent[534]: 2025-02-24 17:19:51.529 534 INFO ironic_python_agent.agent [-] Starting heartbeater [ 154.714860] ironic-python-agent[534]: 2025-02-24 17:19:51.539 534 DEBUG ironic_python_agent.ironic_api_client [-] Heartbeat: announcing callback URL https://10.0.0.22:9999, API version is 1.68 heartbeat /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/ironic_api_client.py:186 [ 154.725475] ironic-python-agent[534]: 2025-02-24 17:19:51.535 534 INFO eventlet.wsgi.server [-] (534) wsgi starting up on https://[::]:9999 [ 155.006809] ironic-python-agent[534]: 2025-02-24 17:19:51.833 534 DEBUG eventlet.wsgi.server [-] (534) accepted ('::ffff:172.24.4.1', 39768, 0, 0) server /opt/ironic-python-agent/lib/python3.11/site-packages/eventlet/wsgi.py:1067 [ 155.192860] ironic-python-agent[534]: 2025-02-24 17:19:52.018 534 INFO eventlet.wsgi.server [-] ::ffff:172.24.4.1 "GET /v1/commands/?agent_token=TuVUUB0HFDLrhby6qI0OhyKmsGz9vxXk9q3el8mFPro HTTP/1.1" status: 200 len: 148 time: 0.0146053 [ 155.280452] ironic-python-agent[534]: 2025-02-24 17:19:52.105 534 DEBUG ironic_python_agent.agent [-] heartbeat successful do_heartbeat /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/agent.py:163 [ 155.296879] ironic-python-agent[534]: 2025-02-24 17:19:52.113 534 INFO ironic_python_agent.agent [-] sleeping before next heartbeat, interval: 10.096241397335621 [ 155.316642] ironic-python-agent[534]: 2025-02-24 17:19:52.140 534 DEBUG root [-] Executing command: clean.execute_clean_step with args: {'step': {'step': 'manage_container_cleanup', 'priority': 20, 'interface': 'deploy', 'reboot_requested': True, 'abortable': True, 'requires_ramdisk': True}, 'node': {'id': 1, 'uuid': '40d4409f-6860-40d4-bcca-9e7115dad3cc', 'name': 'node-0', 'chassis_id': 1, 'instance_uuid': None, 'driver': 'ipmi', 'driver_info': {'ipmi_address': '172.31.85.49', 'ipmi_username': 'admin', 'ipmi_password': '******', 'ipmi_port': 6230, 'deploy_ramdisk': 'c1256137-a078-4b24-8a1d-d0bb2e6bb72a', 'deploy_kernel': '0b385ae5-8d50-4ce0-9e13-10061bececcb'}, 'driver_internal_info': {'clean_steps': [{'step': 'erase_devices_metadata', 'priority': 99, 'interface': 'deploy', 'reboot_requested': False, 'abortable': True, 'requires_ramdisk': True}, {'step': 'upgrade_example_device_model1234_firmware', 'priority': 37, 'interface': 'deploy', 'reboot_requested': True, 'abortable': False, 'requires_ramdisk': True}, {'step': 'manage_container_cleanup', 'priority': 20, 'interface': 'deploy', 'reboot_requested': True, 'abortable': True, 'requires_ramdisk': True}], 'agent_erase_devices_iterations': 1, 'agent_erase_devices_zeroize': True, 'agent_continue_if_secure_erase_failed': False, 'agent_continue_if_ata_erase_failed': False, 'agent_enable_nvme_secure_erase': True, 'agent_enable_ata_secure_erase': True, 'disk_erasure_concurrency': 4, 'agent_erase_skip_read_only': False, 'last_power_state_change': '2025-02-24T17:16:19.003774', 'agent_version': '10.1.1.dev18', 'agent_last_heartbeat': '2025-02-24T17:19:52.261919', 'hardware_manager_version': {'ContainerHardwareManager': '1', 'generic_hardware_manager': '1.2'}, 'agent_cached_clean_steps_refreshed': '2025-02-24T17:15:31.275076', 'clean_step_index': 2, 'agent_secret_token': '******', 'agent_url': 'https://10.0.0.22:9999', 'agent_verify_ca': '/var/lib/ironic/certificates/40d4409f-6860-40d4-bcca-9e7115dad3cc.crt'}, 'clean_step': {'step': 'manage_container_cleanup', 'priority': 20, 'interface': 'deploy', 'reboot_requested': True, 'abortable': True, 'requires_ramdisk': True}, 'deploy_step': {}, 'service_step': {}, 'raid_config': {}, 'target_raid_config': {}, 'properties': {'cpu_arch': 'x86_64', 'capabilities': 'boot_mode:uefi', 'vendor': 'unknown'}, 'reservation': 'ip-172-31-85-49', 'conductor_affinity': None, 'conductor_group': '', 'power_state': 'power on', 'target_power_state': None, 'provision_state': 'cleaning', 'provision_updated_at': '2025-02-24T17:19:53.000000', 'target_provision_state': 'available', 'maintenance': False, 'maintenance_reason': None, 'fault': None, 'console_enabled': False, 'last_error': None, 'resource_class': 'baremetal', 'inspection_finished_at': None, 'inspection_started_at': None, 'extra': {}, 'automated_clean': None, 'protected': False, 'protected_reason': None, 'allocation_id': None, 'bios_interface': 'no-bios', 'boot_interface': 'ipxe', 'console_interface': 'no-console', 'deploy_interface': 'direct', 'firmware_interface': 'no-firmware', 'inspect_interface': 'agent', 'management_interface': 'ipmitool', 'network_interface': 'flat', 'power_interface': 'ipmitool', 'raid_interface': 'no-raid', 'rescue_interface': 'no-rescue', 'storage_interface': 'noop', 'vendor_interface': 'ipmitool', 'traits': {'objects': [{'trait': 'CUSTOM_GOLD'}]}, 'owner': None, 'lessee': None, 'description': None, 'retired': False, 'retired_reason': None, 'network_data': {}, 'boot_mode': None, 'secure_boot': None, 'shard': None, 'parent_node': None, 'disable_power_off': False, 'created_at': '2025-02-18T18:31:08.000000', 'updated_at': '2025-02-24T17:19:53.000000', 'instance_info': {}}, 'ports': [{'id': 1, 'uuid': '1408446f-4af5-45ac-aea7-f3b3cb6b3d0e', 'node_id': 1, 'node_uuid': '40d4409f-6860-40d4-bcca-9e7115dad3cc', 'address': '52:54:00:22:8f:78', 'extra': {}, 'local_link_connection': {}, 'portgroup_id': None, 'pxe_enabled': True, 'internal_info': {'cleaning_vif_port_id': 'c489b561-e833-41cb-8d7e-afc643788abb'}, 'physical_network': None, 'is_smartnic': False, 'name': None, 'created_at': '2025-02-18T18:31:21.000000', 'updated_at': '2025-02-24T17:11:46.000000'}, {'id': 2, 'uuid': '598f1c65-b6e2-49dc-8495-95558a6ce00d', 'node_id': 1, 'node_uuid': '40d4409f-6860-40d4-bcca-9e7115dad3cc', 'address': '52:54:00:1a:7e:3c', 'extra': {}, 'local_link_connection': {}, 'portgroup_id': None, 'pxe_enabled': True, 'internal_info': {'cleaning_vif_port_id': '0ab0bf6c-0c24-4725-9918-e9768d2089d5'}, 'physical_network': None, 'is_smartnic': False, 'name': None, 'created_at': '2025-02-18T18:31:24.000000', 'updated_at': '2025-02-24T17:11:46.000000'}], 'clean_version': {'ContainerHardwareManager': '1', 'generic_hardware_manager': '1.2'}} execute_command /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/extensions/base.py:253 [ 155.353578] ironic-python-agent[534]: 2025-02-24 17:19:52.164 534 DEBUG root [-] Executing clean step {'step': 'manage_container_cleanup', 'priority': 20, 'interface': 'deploy', 'reboot_requested': True, 'abortable': True, 'requires_ramdisk': True} execute_clean_step /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/extensions/clean.py:66 [ 155.355195] ironic-python-agent[534]: 2025-02-24 17:19:52.167 534 INFO root [-] Asynchronous command execute_clean_step started execution [ 155.358860] ironic-python-agent[534]: 2025-02-24 17:19:52.170 534 DEBUG root [-] Cleaning up via container... manage_container_cleanup /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/hardware_managers/container.py:119 [ 155.370763] ironic-python-agent[534]: 2025-02-24 17:19:52.197 534 INFO eventlet.wsgi.server [-] ::ffff:172.24.4.1 "POST /v1/commands/?wait=false&agent_token=TuVUUB0HFDLrhby6qI0OhyKmsGz9vxXk9q3el8mFPro HTTP/1.1" status: 200 len: 293 time: 0.0682044 [ 156.716609] ironic-python-agent[626]: Trying to pull 172.24.4.1:5000/cleaning-image:latest... [ 156.850552] ironic-python-agent[626]: Getting image source signatures [ 156.868901] ironic-python-agent[626]: Copying blob sha256:b0f6f1c319a1570f67352d490370f0aeb5c0e67a087baf2d5f301ad51ec18858 [ 162.823066] ironic-python-agent[626]: Copying config sha256:d6cda735b4ad39bdec6f0fef913da14bfe15afa0dec4441ef9aa4367e9c1abdd [ 162.839080] ironic-python-agent[626]: Writing manifest to image destination [ 162.843160] ironic-python-agent[626]: Storing signatures [ 162.928778] ironic-python-agent[626]: d6cda735b4ad39bdec6f0fef913da14bfe15afa0dec4441ef9aa4367e9c1abdd [ 162.937537] podman[626]: 2025-02-24 17:19:53.420108863 +0000 UTC m=+1.038649028 image pull docker://172.24.4.1:5000/cleaning-image:latest [ 162.963028] systemd[1]: var-lib-containers-storage-overlay.mount: Deactivated successfully. [ 163.766514] podman[641]: 2025-02-24 17:20:00.494174947 +0000 UTC m=+0.522001345 image pull docker://172.24.4.1:5000/cleaning-image:latest [ 164.914184] podman[641]: [ 165.015876] podman[641]: 2025-02-24 17:20:01.841845749 +0000 UTC m=+1.869672116 container create d56193e71d75e38429abbbb38ab518d069b4f79b765f28ab39b66dfb0aa064aa (image=172.24.4.1:5000/cleaning-image:latest, name=keen_dirac, io.buildah.version=1.33.7) [ 165.387692] systemd[1]: var-lib-containers-storage-overlay-volatile\x2dcheck228117913-merged.mount: Deactivated successfully. [ 165.412399] ironic-python-agent[534]: 2025-02-24 17:20:02.220 534 DEBUG ironic_python_agent.ironic_api_client [-] Heartbeat: announcing callback URL https://10.0.0.22:9999, API version is 1.68 heartbeat /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/ironic_api_client.py:186 [ 165.644814] ironic-python-agent[534]: 2025-02-24 17:20:02.471 534 DEBUG eventlet.wsgi.server [-] (534) accepted ('::ffff:172.24.4.1', 57252, 0, 0) server /opt/ironic-python-agent/lib/python3.11/site-packages/eventlet/wsgi.py:1067 [ 165.731411] ironic-python-agent[534]: 2025-02-24 17:20:02.555 534 INFO eventlet.wsgi.server [-] ::ffff:172.24.4.1 "GET /v1/commands/?agent_token=TuVUUB0HFDLrhby6qI0OhyKmsGz9vxXk9q3el8mFPro HTTP/1.1" status: 200 len: 309 time: 0.0126030 [ 165.924275] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. [ 166.248670] ironic-python-agent[534]: 2025-02-24 17:20:03.074 534 DEBUG ironic_python_agent.agent [-] heartbeat successful do_heartbeat /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/agent.py:163 [ 166.259743] ironic-python-agent[534]: 2025-02-24 17:20:03.084 534 INFO ironic_python_agent.agent [-] sleeping before next heartbeat, interval: 14.067340565577299 [ 166.413403] systemd-networkd[170]: cni-podman0: Link UP [ 166.590696] systemd-networkd[170]: veth22982df2: Link UP [ 166.585867] cni-podman0: port 1(veth22982df2) entered blocking state [ 166.586215] cni-podman0: port 1(veth22982df2) entered disabled state [ 166.602562] device veth22982df2 entered promiscuous mode [ 166.618059] cni-podman0: port 1(veth22982df2) entered blocking state [ 166.619322] cni-podman0: port 1(veth22982df2) entered forwarding state [ 166.623401] cni-podman0: port 1(veth22982df2) entered disabled state [ 167.302853] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 167.304226] IPv6: ADDRCONF(NETDEV_CHANGE): veth22982df2: link becomes ready [ 167.305296] cni-podman0: port 1(veth22982df2) entered blocking state [ 167.305477] cni-podman0: port 1(veth22982df2) entered forwarding state [ 167.323152] IPv6: ADDRCONF(NETDEV_CHANGE): cni-podman0: link becomes ready [ 167.377955] systemd-networkd[170]: veth22982df2: Gained carrier [ 167.407520] systemd-networkd[170]: cni-podman0: Gained carrier [ 168.103646] systemd[1]: Starting dhcp-interface@cni-podman0.service - DHCP interface cni-podman0... [ 168.686277] systemd[1]: Starting dhcp-interface@veth22982df2.service - DHCP interface veth22982df2... [ 168.714527] systemd-networkd[170]: cni-podman0: Gained IPv6LL [ 169.091610] systemd-networkd[170]: veth22982df2: Gained IPv6LL [ 169.675145] dhcp-all-interfaces.sh[705]: Inspecting interface: cni-podman0...Device has generated MAC, skipping. [ 170.036837] ifup[720]: ifup: unknown interface cni-podman0 [ 170.075348] systemd[1]: dhcp-interface@cni-podman0.service: Main process exited, code=exited, status=1/FAILURE [ 170.109855] systemd[1]: dhcp-interface@cni-podman0.service: Failed with result 'exit-code'. [ 170.154945] systemd[1]: Failed to start dhcp-interface@cni-podman0.service - DHCP interface cni-podman0. [ 170.293949] dhcp-all-interfaces.sh[708]: Inspecting interface: veth22982df2...Device has generated MAC, skipping. [ 170.479585] ifup[724]: ifup: unknown interface veth22982df2 [ 170.492796] systemd[1]: dhcp-interface@veth22982df2.service: Main process exited, code=exited, status=1/FAILURE [ 170.503720] systemd[1]: dhcp-interface@veth22982df2.service: Failed with result 'exit-code'. [ 170.522738] systemd[1]: Failed to start dhcp-interface@veth22982df2.service - DHCP interface veth22982df2. [ 170.964017] ICMPv6: RA: ndisc_router_discovery failed to add default route [ 170.964524] ICMPv6: RA: ndisc_router_discovery failed to add default route [ 173.041426] systemd[1]: tmp-crun.QTmuMo.mount: Deactivated successfully. [ 173.342898] systemd[1]: Created slice machine.slice - Virtual Machine and Container Slice. [ 173.379423] systemd[1]: Started libpod-d56193e71d75e38429abbbb38ab518d069b4f79b765f28ab39b66dfb0aa064aa.scope - libcrun container. [ 173.610129] systemd[1]: libpod-d56193e71d75e38429abbbb38ab518d069b4f79b765f28ab39b66dfb0aa064aa.scope: Deactivated successfully. [ 174.055077] systemd[1]: tmp-crun.D1azne.mount: Deactivated successfully. [ 175.757760] systemd-networkd[170]: veth22982df2: Link DOWN [ 175.759180] systemd-networkd[170]: veth22982df2: Lost carrier [ 175.713941] cni-podman0: port 1(veth22982df2) entered disabled state [ 175.736161] device veth22982df2 left promiscuous mode [ 175.736386] cni-podman0: port 1(veth22982df2) entered disabled state [ 175.823235] systemd-networkd[170]: cni-podman0: Lost carrier [ 176.690453] systemd[1]: run-netns-netns\x2d91129fc2\x2d8e16\x2da2b1\x2d6529\x2dce32eb837f8c.mount: Deactivated successfully. [ 176.722885] systemd[1]: var-lib-containers-storage-overlay\x2dcontainers-d56193e71d75e38429abbbb38ab518d069b4f79b765f28ab39b66dfb0aa064aa-userdata-shm.mount: Deactivated successfully. [ 176.755102] systemd[1]: var-lib-containers-storage-overlay-98c48e240d056c3adda3a973202dbe8101d0234d6fe29b215376cb74dabee5a4-merged.mount: Deactivated successfully. [ 177.114536] podman[763]: 2025-02-24 17:20:13.928176294 +0000 UTC m=+2.908335049 container remove d56193e71d75e38429abbbb38ab518d069b4f79b765f28ab39b66dfb0aa064aa (image=172.24.4.1:5000/cleaning-image:latest, name=keen_dirac, io.buildah.version=1.33.7) [ 177.140846] systemd[1]: var-lib-containers-storage-overlay.mount: Deactivated successfully. [ 177.186917] ironic-python-agent[534]: 2025-02-24 17:20:14.003 534 DEBUG root [-] Container run output: manage_container_cleanup /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/hardware_managers/container.py:139 [ 177.193505] ironic-python-agent[534]: 2025-02-24 17:20:14.017 534 INFO root [-] Clean step completed: {'step': 'manage_container_cleanup', 'priority': 20, 'interface': 'deploy', 'reboot_requested': True, 'abortable': True, 'requires_ramdisk': True}, result: True [ 177.200809] ironic-python-agent[534]: 2025-02-24 17:20:14.020 534 INFO root [-] Asynchronous command execute_clean_step completed: {'clean_result': True, 'clean_step': {'step': 'manage_container_cleanup', 'priority': 20, 'interface': 'deploy', 'reboot_requested': True, 'abortable': True, 'requires_ramdisk': True}} [ 180.330540] ironic-python-agent[534]: 2025-02-24 17:20:17.153 534 DEBUG ironic_python_agent.ironic_api_client [-] Heartbeat: announcing callback URL https://10.0.0.22:9999, API version is 1.68 heartbeat /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/ironic_api_client.py:186 [ 180.440685] ironic-python-agent[534]: 2025-02-24 17:20:17.267 534 DEBUG eventlet.wsgi.server [-] (534) accepted ('::ffff:172.24.4.1', 50926, 0, 0) server /opt/ironic-python-agent/lib/python3.11/site-packages/eventlet/wsgi.py:1067 [ 180.469769] ironic-python-agent[534]: 2025-02-24 17:20:17.296 534 INFO eventlet.wsgi.server [-] ::ffff:172.24.4.1 "GET /v1/commands/?agent_token=TuVUUB0HFDLrhby6qI0OhyKmsGz9vxXk9q3el8mFPro HTTP/1.1" status: 200 len: 491 time: 0.0044127 [ 180.882868] ironic-python-agent[534]: 2025-02-24 17:20:17.709 534 DEBUG ironic_python_agent.agent [-] heartbeat successful do_heartbeat /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/agent.py:163 [ 180.890688] ironic-python-agent[534]: 2025-02-24 17:20:17.715 534 INFO ironic_python_agent.agent [-] sleeping before next heartbeat, interval: 14.776128251488403 [ 195.671170] ironic-python-agent[534]: 2025-02-24 17:20:32.493 534 DEBUG ironic_python_agent.ironic_api_client [-] Heartbeat: announcing callback URL https://10.0.0.22:9999, API version is 1.68 heartbeat /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/ironic_api_client.py:186 [ 196.222480] ironic-python-agent[534]: 2025-02-24 17:20:33.048 534 DEBUG ironic_python_agent.agent [-] heartbeat successful do_heartbeat /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/agent.py:163 [ 196.228584] ironic-python-agent[534]: 2025-02-24 17:20:33.053 534 INFO ironic_python_agent.agent [-] sleeping before next heartbeat, interval: 14.52533268581574 [ 210.762869] ironic-python-agent[534]: 2025-02-24 17:20:47.579 534 DEBUG ironic_python_agent.ironic_api_client [-] Heartbeat: announcing callback URL https://10.0.0.22:9999, API version is 1.68 heartbeat /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/ironic_api_client.py:186 [ 211.332785] ironic-python-agent[534]: 2025-02-24 17:20:48.138 534 ERROR ironic_python_agent.agent [-] error sending heartbeat to ['http://172.31.85.49/baremetal']: ironic_python_agent.errors.HeartbeatError: Error heartbeating to agent API: Error 400: Invalid or missing agent token received. [ 211.339746] ironic-python-agent[534]: 2025-02-24 17:20:48.138 534 ERROR ironic_python_agent.agent Traceback (most recent call last): [ 211.341978] ironic-python-agent[534]: 2025-02-24 17:20:48.138 534 ERROR ironic_python_agent.agent File "/opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/agent.py", line 146, in do_heartbeat [ 211.343012] ironic-python-agent[534]: 2025-02-24 17:20:48.138 534 ERROR ironic_python_agent.agent self.api.heartbeat( [ 211.343772] ironic-python-agent[534]: 2025-02-24 17:20:48.138 534 ERROR ironic_python_agent.agent File "/opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/ironic_api_client.py", line 200, in heartbeat [ 211.345892] ironic-python-agent[534]: 2025-02-24 17:20:48.138 534 ERROR ironic_python_agent.agent raise errors.HeartbeatError(error) [ 211.347188] ironic-python-agent[534]: 2025-02-24 17:20:48.138 534 ERROR ironic_python_agent.agent ironic_python_agent.errors.HeartbeatError: Error heartbeating to agent API: Error 400: Invalid or missing agent token received. [ 211.348626] ironic-python-agent[534]: 2025-02-24 17:20:48.138 534 ERROR ironic_python_agent.agent [ 211.349537] ironic-python-agent[534]: 2025-02-24 17:20:48.165 534 INFO ironic_python_agent.agent [-] sleeping before next heartbeat, interval: 9.75741701010859