# Pastebin kyIk8GgZ Sep 12 20:04:35.588093 np0038485220 ironic-conductor[63012]: DEBUG ironic.conductor.manager [None req-43efb6b5-1c08-41b0-8a73-b1485e7fdd7d None None] RPC change_node_power_state called for node a051f6e4-81e1-4ae8-b1c1-89fabdd7984b. The desired new state is power off. {{(pid=63012) change_node_power_state /opt/stack/ironic/ironic/conductor/manager.py:323}} Sep 12 20:04:35.595521 np0038485220 ironic-conductor[63012]: DEBUG ironic.conductor.task_manager [None req-43efb6b5-1c08-41b0-8a73-b1485e7fdd7d None None] Attempting to get exclusive lock on node a051f6e4-81e1-4ae8-b1c1-89fabdd7984b (for changing node power state) {{(pid=63012) __init__ /opt/stack/ironic/ironic/conductor/task_manager.py:235}} Sep 12 20:04:36.313084 np0038485220 ironic-conductor[63012]: DEBUG ironic.conductor.task_manager [None req-43efb6b5-1c08-41b0-8a73-b1485e7fdd7d None None] Node a051f6e4-81e1-4ae8-b1c1-89fabdd7984b successfully reserved for changing node power state (took 0.72 seconds) {{(pid=63012) reserve_node /opt/stack/ironic/ironic/conductor/task_manager.py:352}} Sep 12 20:04:36.336023 np0038485220 ironic-conductor[63012]: DEBUG ironic_lib.json_rpc.server [None req-43efb6b5-1c08-41b0-8a73-b1485e7fdd7d None None] RPC change_node_power_state returned None {{(pid=63012) _handle_requests /opt/stack/data/venv/lib/python3.10/site-packages/ironic_lib/json_rpc/server.py:289}} Sep 12 20:04:36.336705 np0038485220 ironic-conductor[63012]: INFO eventlet.wsgi.server [None req-43efb6b5-1c08-41b0-8a73-b1485e7fdd7d None None] 2607:5300:201:2000::cb6 "POST / HTTP/1.1" status: 200 len: 211 time: 0.7506618 Sep 12 20:04:36.337810 np0038485220 ironic-conductor[63012]: INFO ironic.conductor.utils [None req-43efb6b5-1c08-41b0-8a73-b1485e7fdd7d None None] Node a051f6e4-81e1-4ae8-b1c1-89fabdd7984b current power state is 'None', requested state is 'power off'. Sep 12 20:04:50.173220 np0038485220 ironic-conductor[63012]: DEBUG dbcounter [-] [63012] Writing DB stats ironic:SELECT=438,ironic:UPDATE=81,ironic:DELETE=52,ironic:INSERT=5 {{(pid=63012) stat_writer /opt/stack/data/venv/lib/python3.10/site-packages/dbcounter.py:115}} Sep 12 20:05:01.584958 np0038485220 ironic-conductor[63012]: INFO ironic.conductor.utils [None req-43efb6b5-1c08-41b0-8a73-b1485e7fdd7d None None] Successfully set node a051f6e4-81e1-4ae8-b1c1-89fabdd7984b power state to power off by power off. Sep 12 20:05:02.126055 np0038485220 ironic-conductor[63012]: DEBUG ironic_lib.json_rpc.server [None req-71931953-4598-4779-a98a-2dadb41717b4 None None] RPC do_node_deploy with {'node_id': 'a051f6e4-81e1-4ae8-b1c1-89fabdd7984b', 'rebuild': False, 'configdrive': '***', 'context': {'user': '2f7449bf390e471b9d5ff1474f8279d1', 'project_id': None, 'system_scope': 'all', 'project': None, 'domain': None, 'user_domain': 'default', 'project_domain': None, 'is_admin': False, 'read_only': False, 'show_deleted': False, 'auth_token': '***', 'request_id': 'req-71931953-4598-4779-a98a-2dadb41717b4', 'global_request_id': None, 'resource_uuid': None, 'roles': ['admin', 'manager', 'member', 'reader'], 'user_identity': '2f7449bf390e471b9d5ff1474f8279d1 - - default -', 'is_admin_project': True, 'auth_token_info': {'token': {'methods': ['password'], 'user': {'domain': {'id': 'default', 'name': 'Default'}, 'id': '2f7449bf390e471b9d5ff1474f8279d1', 'name': 'tempest-TestBackfill-2115709546-system-admin', 'password_expires_at': '***'}, 'audit_ids': ['kKLpo2UqSemi95Rd7jJfnA'], 'expires_at': '2024-09-12T21:04:32.000000Z', 'issued_at': '2024-09-12T20:04:32.000000Z', 'roles': [{'id': '6062a3a8047e48f9809bc2aa0ba5f16c', 'name': 'admin'}, {'id': '7190f4f4b9b843aba9889ad19ae8ae5f', 'name': 'manager'}, {'id': 'b3d998aeb26544ebb14f6b8d89d81c24', 'name': 'member'}, {'id': 'f3b6b841b5b3436dbaa6ed235d0dd336', 'name': 'reader'}], 'system': {'all': True}, 'catalog': [{'endpoints': [{'id': '2e1bdfa6297c4b01a829f778f78fe7fd', 'interface': 'public', 'region_id': 'RegionOne', 'url': 'https://158.69.75.189:9696/networking', 'region': 'RegionOne'}], 'id': '05953ca520114bc1a3f1d93e163f9cc0', 'type': 'network', 'name': 'neutron'}, {'endpoints': [{'id': '9c494eff062d40deb4aafe01df30a658', 'interface': 'public', 'region_id': 'RegionOne', 'url': 'https://158.69.75.189/identity', 'region': 'RegionOne'}], 'id': '5b5d3e3fe72c42a59ff3595d91fa6f33', 'type': 'identity', 'name': 'keystone'}, {'endpoints': [{'id': 'ba068535438a45ed9b5a6bb4ad2b0eb6', 'interface': 'public', 'region_id': 'RegionOne', 'url': 'https://158.69.75.189/baremetal', 'region': 'RegionOne'}], 'id': 'bd9e7f97b4df47cd9523514df14c2b97', 'type': 'baremetal', 'name': 'ironic'}], 'version': 'v3', 'auth_token': '***'}}}} {{(pid=63012) _handle_requests /opt/stack/data/venv/lib/python3.10/site-packages/ironic_lib/json_rpc/server.py:276}} Sep 12 20:05:02.126332 np0038485220 ironic-conductor[63012]: DEBUG ironic.conductor.manager [None req-71931953-4598-4779-a98a-2dadb41717b4 None None] RPC do_node_deploy called for node a051f6e4-81e1-4ae8-b1c1-89fabdd7984b. {{(pid=63012) do_node_deploy /opt/stack/ironic/ironic/conductor/manager.py:925}} Sep 12 20:05:02.138650 np0038485220 ironic-conductor[63012]: DEBUG ironic.conductor.task_manager [None req-71931953-4598-4779-a98a-2dadb41717b4 None None] Attempting to get exclusive lock on node a051f6e4-81e1-4ae8-b1c1-89fabdd7984b (for node deployment) {{(pid=63012) __init__ /opt/stack/ironic/ironic/conductor/task_manager.py:235}} Sep 12 20:05:04.149978 np0038485220 ironic-conductor[63012]: DEBUG ironic_lib.json_rpc.server [None req-71931953-4598-4779-a98a-2dadb41717b4 None None] RPC error NodeLocked: Node a051f6e4-81e1-4ae8-b1c1-89fabdd7984b is locked by host np0038485220, please retry after the current operation is completed. {{(pid=63012) _handle_error /opt/stack/data/venv/lib/python3.10/site-packages/ironic_lib/json_rpc/server.py:176}} Sep 12 20:05:04.150543 np0038485220 ironic-conductor[63012]: INFO eventlet.wsgi.server [None req-71931953-4598-4779-a98a-2dadb41717b4 None None] 2607:5300:201:2000::cb6 "POST / HTTP/1.1" status: 200 len: 414 time: 2.0261002 Sep 12 20:05:08.667834 np0038485220 ironic-conductor[63012]: DEBUG ironic.conductor.task_manager [None req-43efb6b5-1c08-41b0-8a73-b1485e7fdd7d None None] Successfully released exclusive lock for changing node power state on node a051f6e4-81e1-4ae8-b1c1-89fabdd7984b (lock was held 32.35 sec) {{(pid=63012) release_resources /opt/stack/ironic/ironic/conductor/task_manager.py:448}}