{"body":"artuuid\": null,\n[ 153.903339] ironic-python-agent[534]: \"serial\": null,\n[ 153.903923] ironic-python-agent[534]: \"wwn\": null,\n[ 153.908989] ironic-python-agent[534]: \"log-sec\": 512,\n[ 153.909676] ironic-python-agent[534]: \"phy-sec\": 512\n[ 153.910244] ironic-python-agent[534]: },{\n[ 153.911966] ironic-python-agent[534]: \"kname\": \"loop4\",\n[ 153.918294] ironic-python-agent[534]: \"model\": null,\n[ 153.919001] ironic-python-agent[534]: \"size\": 0,\n[ 153.920003] ironic-python-agent[534]: \"rota\": true,\n[ 153.925550] ironic-python-agent[534]: \"type\": \"loop\",\n[ 153.926286] ironic-python-agent[534]: \"uuid\": null,\n[ 153.927911] ironic-python-agent[534]: \"partuuid\": null,\n[ 153.928822] ironic-python-agent[534]: \"serial\": null,\n[ 153.929435] ironic-python-agent[534]: \"wwn\": null,\n[ 153.930001] ironic-python-agent[534]: \"log-sec\": 512,\n[ 153.930593] ironic-python-agent[534]: \"phy-sec\": 512\n[ 153.931169] ironic-python-agent[534]: },{\n[ 153.936937] ironic-python-agent[534]: \"kname\": \"loop5\",\n[ 153.938568] ironic-python-agent[534]: \"model\": null,\n[ 153.939155] ironic-python-agent[534]: \"size\": 0,\n[ 153.944764] ironic-python-agent[534]: \"rota\": true,\n[ 153.946158] ironic-python-agent[534]: \"type\": \"loop\",\n[ 153.946812] ironic-python-agent[534]: \"uuid\": null,\n[ 153.947428] ironic-python-agent[534]: \"partuuid\": null,\n[ 153.948004] ironic-python-agent[534]: \"serial\": null,\n[ 153.953324] ironic-python-agent[534]: \"wwn\": null,\n[ 153.953966] ironic-python-agent[534]: \"log-sec\": 512,\n[ 153.954575] ironic-python-agent[534]: \"phy-sec\": 512\n[ 153.955145] ironic-python-agent[534]: },{\n[ 153.962121] ironic-python-agent[534]: \"kname\": \"loop6\",\n[ 153.963290] ironic-python-agent[534]: \"model\": null,\n[ 153.963947] ironic-python-agent[534]: \"size\": 0,\n[ 153.964575] ironic-python-agent[534]: \"rota\": true,\n[ 153.965146] ironic-python-agent[534]: \"type\": \"loop\",\n[ 153.965722] ironic-python-agent[534]: \"uuid\": null,\n[ 153.974513] ironic-python-agent[534]: \"partuuid\": null,\n[ 153.975706] ironic-python-agent[534]: \"serial\": null,\n[ 153.976743] ironic-python-agent[534]: \"wwn\": null,\n[ 153.977367] ironic-python-agent[534]: \"log-sec\": 512,\n[ 153.977966] ironic-python-agent[534]: \"phy-sec\": 512\n[ 153.979013] ironic-python-agent[534]: },{\n[ 153.984794] ironic-python-agent[534]: \"kname\": \"loop7\",\n[ 153.986077] ironic-python-agent[534]: \"model\": null,\n[ 153.986793] ironic-python-agent[534]: \"size\": 0,\n[ 153.987434] ironic-python-agent[534]: \"rota\": true,\n[ 153.988008] ironic-python-agent[534]: \"type\": \"loop\",\n[ 153.993254] ironic-python-agent[534]: \"uuid\": null,\n[ 153.993881] ironic-python-agent[534]: \"partuuid\": null,\n[ 153.994496] ironic-python-agent[534]: \"serial\": null,\n[ 153.995062] ironic-python-agent[534]: \"wwn\": null,\n[ 154.000630] ironic-python-agent[534]: \"log-sec\": 512,\n[ 154.002070] ironic-python-agent[534]: \"phy-sec\": 512\n[ 154.003146] ironic-python-agent[534]: },{\n[ 154.003743] ironic-python-agent[534]: \"kname\": \"vda\",\n[ 154.009319] ironic-python-agent[534]: \"model\": null,\n[ 154.010096] ironic-python-agent[534]: \"size\": 11811160064,\n[ 154.010727] ironic-python-agent[534]: \"rota\": true,\n[ 154.011322] ironic-python-agent[534]: \"type\": \"disk\",\n[ 154.011919] ironic-python-agent[534]: \"uuid\": null,\n[ 154.021628] ironic-python-agent[534]: \"partuuid\": null,\n[ 154.022415] ironic-python-agent[534]: \"serial\": null,\n[ 154.023014] ironic-python-agent[534]: \"wwn\": null,\n[ 154.023730] ironic-python-agent[534]: \"log-sec\": 512,\n[ 154.029084] ironic-python-agent[534]: \"phy-sec\": 512\n[ 154.030005] ironic-python-agent[534]: }\n[ 154.031078] ironic-python-agent[534]: ]\n[ 154.031703] ironic-python-agent[534]: }\n[ 154.037193] ironic-python-agent[534]: \" _log /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/utils.py:106\n[ 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\n[ 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\n[ 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\n[ 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\n[ 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\n[ 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\n[ 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\n[ 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\n[ 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\n[ 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'\n[ 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\n[ 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\n[ 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\n[ 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\n[ 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\n[ 154.185406] ironic-python-agent[534]: cache\n[ 154.186046] ironic-python-agent[534]: \" _log /opt/ironic-python-agent/lib/python3.11/site-packages/ironic_python_agent/utils.py:106\n[ 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\n[ 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\n[ 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\n[ 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\n[ 154.572285] ironic-python-agent[534]: 2025-02-24 17:19:51.397 534 INFO oslo.service.wsgi [-] ironic-python-agent listening on :::9999\n[ 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\n[ 154.707644] ironic-python-agent[534]: 2025-02-24 17:19:51.529 534 INFO ironic_python_agent.agent [-] Starting heartbeater\n[ 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\n[ 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\n[ 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\n[ 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\n[ 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\n[ 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\n[ 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\n[ 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\n[ 155.355195] ironic-python-agent[534]: 2025-02-24 17:19:52.167 534 INFO root [-] Asynchronous command execute_clean_step started execution\n[ 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\n[ 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\n[ 156.716609] ironic-python-agent[626]: Trying to pull 172.24.4.1:5000/cleaning-image:latest...\n[ 156.850552] ironic-python-agent[626]: Getting image source signatures\n[ 156.868901] ironic-python-agent[626]: Copying blob sha256:b0f6f1c319a1570f67352d490370f0aeb5c0e67a087baf2d5f301ad51ec18858\n[ 162.823066] ironic-python-agent[626]: Copying config sha256:d6cda735b4ad39bdec6f0fef913da14bfe15afa0dec4441ef9aa4367e9c1abdd\n[ 162.839080] ironic-python-agent[626]: Writing manifest to image destination\n[ 162.843160] ironic-python-agent[626]: Storing signatures\n[ 162.928778] ironic-python-agent[626]: d6cda735b4ad39bdec6f0fef913da14bfe15afa0dec4441ef9aa4367e9c1abdd\n[ 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\n[ 162.963028] systemd[1]: var-lib-containers-storage-overlay.mount: Deactivated successfully.\n[ 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\n[ 164.914184] podman[641]: \n[ 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)\n[ 165.387692] systemd[1]: var-lib-containers-storage-overlay-volatile\\x2dcheck228117913-merged.mount: Deactivated successfully.\n[ 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\n[ 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\n[ 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\n[ 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.\n[ 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\n[ 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\n[ 166.413403] systemd-networkd[170]: cni-podman0: Link UP\n[ 166.590696] systemd-networkd[170]: veth22982df2: Link UP\n[ 166.585867] cni-podman0: port 1(veth22982df2) entered blocking state\n[ 166.586215] cni-podman0: port 1(veth22982df2) entered disabled state\n[ 166.602562] device veth22982df2 entered promiscuous mode\n[ 166.618059] cni-podman0: port 1(veth22982df2) entered blocking state\n[ 166.619322] cni-podman0: port 1(veth22982df2) entered forwarding state\n[ 166.623401] cni-podman0: port 1(veth22982df2) entered disabled state\n[ 167.302853] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready\n[ 167.304226] IPv6: ADDRCONF(NETDEV_CHANGE): veth22982df2: link becomes ready\n[ 167.305296] cni-podman0: port 1(veth22982df2) entered blocking state\n[ 167.305477] cni-podman0: port 1(veth22982df2) entered forwarding state\n[ 167.323152] IPv6: ADDRCONF(NETDEV_CHANGE): cni-podman0: link becomes ready\n[ 167.377955] systemd-networkd[170]: veth22982df2: Gained carrier\n[ 167.407520] systemd-networkd[170]: cni-podman0: Gained carrier\n[ 168.103646] systemd[1]: Starting dhcp-interface@cni-podman0.service - DHCP interface cni-podman0...\n[ 168.686277] systemd[1]: Starting dhcp-interface@veth22982df2.service - DHCP interface veth22982df2...\n[ 168.714527] systemd-networkd[170]: cni-podman0: Gained IPv6LL\n[ 169.091610] systemd-networkd[170]: veth22982df2: Gained IPv6LL\n[ 169.675145] dhcp-all-interfaces.sh[705]: Inspecting interface: cni-podman0...Device has generated MAC, skipping.\n[ 170.036837] ifup[720]: ifup: unknown interface cni-podman0\n[ 170.075348] systemd[1]: dhcp-interface@cni-podman0.service: Main process exited, code=exited, status=1/FAILURE\n[ 170.109855] systemd[1]: dhcp-interface@cni-podman0.service: Failed with result 'exit-code'.\n[ 170.154945] systemd[1]: Failed to start dhcp-interface@cni-podman0.service - DHCP interface cni-podman0.\n[ 170.293949] dhcp-all-interfaces.sh[708]: Inspecting interface: veth22982df2...Device has generated MAC, skipping.\n[ 170.479585] ifup[724]: ifup: unknown interface veth22982df2\n[ 170.492796] systemd[1]: dhcp-interface@veth22982df2.service: Main process exited, code=exited, status=1/FAILURE\n[ 170.503720] systemd[1]: dhcp-interface@veth22982df2.service: Failed with result 'exit-code'.\n[ 170.522738] systemd[1]: Failed to start dhcp-interface@veth22982df2.service - DHCP interface veth22982df2.\n[ 170.964017] ICMPv6: RA: ndisc_router_discovery failed to add default route\n[ 170.964524] ICMPv6: RA: ndisc_router_discovery failed to add default route\n[ 173.041426] systemd[1]: tmp-crun.QTmuMo.mount: Deactivated successfully.\n[ 173.342898] systemd[1]: Created slice machine.slice - Virtual Machine and Container Slice.\n[ 173.379423] systemd[1]: Started libpod-d56193e71d75e38429abbbb38ab518d069b4f79b765f28ab39b66dfb0aa064aa.scope - libcrun container.\n[ 173.610129] systemd[1]: libpod-d56193e71d75e38429abbbb38ab518d069b4f79b765f28ab39b66dfb0aa064aa.scope: Deactivated successfully.\n[ 174.055077] systemd[1]: tmp-crun.D1azne.mount: Deactivated successfully.\n[ 175.757760] systemd-networkd[170]: veth22982df2: Link DOWN\n[ 175.759180] systemd-networkd[170]: veth22982df2: Lost carrier\n[ 175.713941] cni-podman0: port 1(veth22982df2) entered disabled state\n[ 175.736161] device veth22982df2 left promiscuous mode\n[ 175.736386] cni-podman0: port 1(veth22982df2) entered disabled state\n[ 175.823235] systemd-networkd[170]: cni-podman0: Lost carrier\n[ 176.690453] systemd[1]: run-netns-netns\\x2d91129fc2\\x2d8e16\\x2da2b1\\x2d6529\\x2dce32eb837f8c.mount: Deactivated successfully.\n[ 176.722885] systemd[1]: var-lib-containers-storage-overlay\\x2dcontainers-d56193e71d75e38429abbbb38ab518d069b4f79b765f28ab39b66dfb0aa064aa-userdata-shm.mount: Deactivated successfully.\n[ 176.755102] systemd[1]: var-lib-containers-storage-overlay-98c48e240d056c3adda3a973202dbe8101d0234d6fe29b215376cb74dabee5a4-merged.mount: Deactivated successfully.\n[ 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)\n[ 177.140846] systemd[1]: var-lib-containers-storage-overlay.mount: Deactivated successfully.\n[ 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\n[ 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\n[ 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}}\n[ 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\n[ 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\n[ 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\n[ 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\n[ 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\n[ 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\n[ 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\n[ 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\n[ 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\n[ 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.\n[ 211.339746] ironic-python-agent[534]: 2025-02-24 17:20:48.138 534 ERROR ironic_python_agent.agent Traceback (most recent call last):\n[ 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\n[ 211.343012] ironic-python-agent[534]: 2025-02-24 17:20:48.138 534 ERROR ironic_python_agent.agent self.api.heartbeat(\n[ 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\n[ 211.345892] ironic-python-agent[534]: 2025-02-24 17:20:48.138 534 ERROR ironic_python_agent.agent raise errors.HeartbeatError(error)\n[ 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.\n[ 211.348626] ironic-python-agent[534]: 2025-02-24 17:20:48.138 534 ERROR ironic_python_agent.agent\n[ 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\n","name":"","extension":"txt","url":"https://www.irccloud.com/pastebin/pYLWgGUe","modified":1740429237,"id":"pYLWgGUe","size":29907,"lines":191,"own_paste":false,"theme":"","date":1740429237}