Comment 2 for bug 1709215

Revision history for this message
Hengqing Hu (hudayou) wrote :

Aug 17 14:56:47 server01 neutron-dhcp-agent[9649]: INFO neutron.agent.dhcp.agent [-] Synchronizing state
Aug 17 14:56:52 server01 neutron-dhcp-agent[9649]: INFO neutron.agent.dhcp.agent [None req-04a25782-0f4c-4219-8e50-07c5e85cea73 None None] All active networks have been fetched through RPC.
Aug 17 14:56:52 server01 neutron-dhcp-agent[9649]: INFO neutron.agent.dhcp.agent [-] Starting network ba1019d4-9b31-46d2-8f81-99693f1ed277 dhcp configuration
Aug 17 14:56:52 server01 neutron-dhcp-agent[9649]: DEBUG neutron.agent.dhcp.agent [-] Calling driver for network: ba1019d4-9b31-46d2-8f81-99693f1ed277 action: enable {{(pid=9649) call_driver /opt/stack/neutron/neutron/agent/dhcp/agent.py:133}}
Aug 17 14:56:52 server01 neutron-dhcp-agent[9649]: DEBUG neutron.agent.linux.utils [-] Running command (rootwrap daemon): ['ip', '-o', 'netns', 'list'] {{(pid=9649) execute_rootwrap_daemon /opt/stack/neutron/neutron/agent/linux/utils.py:108}}
Aug 17 14:56:52 server01 neutron-dhcp-agent[9649]: DEBUG oslo_rootwrap.client [-] Popen for ['sudo', '/usr/local/bin/neutron-rootwrap-daemon', '/etc/neutron/rootwrap.conf'] command has been instantiated {{(pid=9649) _initialize /usr/local/lib/python2.7/dist-packages/oslo_rootwrap/client.py:68}}
Aug 17 14:56:52 server01 neutron-dhcp-agent[9649]: INFO neutron.agent.dhcp.agent [-] Starting network bcf3c383-dc35-4ec3-87cc-db3ac5e5f681 dhcp configuration
Aug 17 14:56:52 server01 neutron-dhcp-agent[9649]: DEBUG neutron.agent.dhcp.agent [-] Calling driver for network: bcf3c383-dc35-4ec3-87cc-db3ac5e5f681 action: enable {{(pid=9649) call_driver /opt/stack/neutron/neutron/agent/dhcp/agent.py:133}}
Aug 17 14:56:52 server01 neutron-dhcp-agent[9649]: DEBUG neutron.agent.linux.utils [-] Running command (rootwrap daemon): ['ip', '-o', 'netns', 'list'] {{(pid=9649) execute_rootwrap_daemon /opt/stack/neutron/neutron/agent/linux/utils.py:108}}
Aug 17 14:56:52 server01 sudo[9708]: stack : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/local/bin/neutron-rootwrap-daemon /etc/neutron/rootwrap.conf
Aug 17 14:56:52 server01 sudo[9708]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 17 14:56:54 server01 neutron-dhcp-agent[9649]: INFO oslo_rootwrap.client [-] Spawned new rootwrap daemon process with pid=9708
Aug 17 14:56:54 server01 neutron-dhcp-agent[9649]: DEBUG neutron.agent.linux.utils [-] Running command (rootwrap daemon): ['ip', 'netns', 'exec', 'qdhcp-ba1019d4-9b31-46d2-8f81-99693f1ed277', 'sysctl', '-w', 'net.ipv6.conf.default.accept_ra=0'] {{(pid=9649) execute_rootwrap_daemon /opt/stack/neutron/neutron/agent/linux/utils.py:108}}
Aug 17 14:56:54 server01 neutron-dhcp-agent[9649]: DEBUG neutron.agent.linux.utils [-] Running command (rootwrap daemon): ['ip', 'netns', 'exec', 'qdhcp-bcf3c383-dc35-4ec3-87cc-db3ac5e5f681', 'sysctl', '-w', 'net.ipv6.conf.default.accept_ra=0'] {{(pid=9649) execute_rootwrap_daemon /opt/stack/neutron/neutron/agent/linux/utils.py:108}}
Aug 17 14:56:54 server01 neutron-dhcp-agent[9649]: DEBUG neutron.agent.linux.utils [-] Running command (rootwrap daemon): ['ip', 'netns', 'exec', 'qdhcp-ba1019d4-9b31-46d2-8f81-99693f1ed277', 'ip', 'link', 'set', 'ns-f455f818-70', 'up'] {{(pid=9649) execute_rootwrap_daemon /opt/stack/neutron/neutron/agent/linux/utils.py:108}}
Aug 17 14:56:54 server01 neutron-dhcp-agent[9649]: DEBUG neutron.agent.linux.utils [-] Running command (rootwrap daemon): ['ip', 'netns', 'exec', 'qdhcp-bcf3c383-dc35-4ec3-87cc-db3ac5e5f681', 'ip', 'link', 'set', 'ns-b088acca-80', 'up'] {{(pid=9649) execute_rootwrap_daemon /opt/stack/neutron/neutron/agent/linux/utils.py:108}}
Aug 17 14:56:54 server01 neutron-dhcp-agent[9649]: DEBUG neutron.agent.linux.dhcp [-] Reusing existing device: ns-b088acca-80. {{(pid=9649) setup /opt/stack/neutron/neutron/agent/linux/dhcp.py:1416}}
Aug 17 14:56:54 server01 neutron-dhcp-agent[9649]: DEBUG neutron.agent.linux.utils [-] Running command (rootwrap daemon): ['ip', 'netns', 'exec', 'qdhcp-bcf3c383-dc35-4ec3-87cc-db3ac5e5f681', 'ip', '-o', 'link', 'show', 'ns-b088acca-80'] {{(pid=9649) execute_rootwrap_daemon /opt/stack/neutron/neutron/agent/linux/utils.py:108}}
Aug 17 14:56:54 server01 neutron-dhcp-agent[9649]: DEBUG neutron.agent.linux.dhcp [-] Reusing existing device: ns-f455f818-70. {{(pid=9649) setup /opt/stack/neutron/neutron/agent/linux/dhcp.py:1416}}
Aug 17 14:56:54 server01 neutron-dhcp-agent[9649]: DEBUG neutron.agent.linux.utils [-] Running command (rootwrap daemon): ['ip', 'netns', 'exec', 'qdhcp-ba1019d4-9b31-46d2-8f81-99693f1ed277', 'ip', '-o', 'link', 'show', 'ns-f455f818-70'] {{(pid=9649) execute_rootwrap_daemon /opt/stack/neutron/neutron/agent/linux/utils.py:108}}
Aug 17 14:56:54 server01 neutron-dhcp-agent[9649]: DEBUG neutron.agent.linux.utils [-] Running command (rootwrap daemon): ['ip', 'netns', 'exec', 'qdhcp-bcf3c383-dc35-4ec3-87cc-db3ac5e5f681', 'ip', 'addr', 'show', 'ns-b088acca-80'] {{(pid=9649) execute_rootwrap_daemon /opt/stack/neutron/neutron/agent/linux/utils.py:108}}
Aug 17 14:56:54 server01 neutron-dhcp-agent[9649]: DEBUG neutron.agent.linux.utils [-] Running command (rootwrap daemon): ['ip', 'netns', 'exec', 'qdhcp-ba1019d4-9b31-46d2-8f81-99693f1ed277', 'ip', 'addr', 'show', 'ns-f455f818-70'] {{(pid=9649) execute_rootwrap_daemon /opt/stack/neutron/neutron/agent/linux/utils.py:108}}
Aug 17 14:56:54 server01 neutron-dhcp-agent[9649]: DEBUG neutron.agent.linux.utils [-] Running command (rootwrap daemon): ['ip', 'netns', 'exec', 'qdhcp-bcf3c383-dc35-4ec3-87cc-db3ac5e5f681', 'ip', '-6', 'addr', 'add', 'fe80::f816:3eff:fe58:bf97/64', 'scope', 'global', 'dev', 'ns-b088acca-80'] {{(pid=9649) execute_rootwrap_daemon /opt/stack/neutron/neutron/agent/linux/utils.py:108}}
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: DEBUG neutron.agent.linux.utils [-] Running command (rootwrap daemon): ['ip', 'netns', 'exec', 'qdhcp-ba1019d4-9b31-46d2-8f81-99693f1ed277', 'ip', '-4', 'route', 'list', 'dev', 'ns-f455f818-70'] {{(pid=9649) execute_rootwrap_daemon /opt/stack/neutron/neutron/agent/linux/utils.py:108}}
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.linux.utils [-] Exit code: 2; Stdin: ; Stdout: ; Stderr: RTNETLINK answers: File exists
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]:
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent [-] Unable to enable dhcp for bcf3c383-dc35-4ec3-87cc-db3ac5e5f681.: ProcessExecutionError: Exit code: 2; Stdin: ; Stdout: ; Stderr: RTNETLINK answers: File exists
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent Traceback (most recent call last):
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent File "/opt/stack/neutron/neutron/agent/dhcp/agent.py", line 142, in call_driver
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent getattr(driver, action)(**action_kwargs)
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent File "/opt/stack/neutron/neutron/agent/linux/dhcp.py", line 216, in enable
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent interface_name = self.device_manager.setup(self.network)
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent File "/opt/stack/neutron/neutron/agent/linux/dhcp.py", line 1450, in setup
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent namespace=network.namespace)
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent File "/opt/stack/neutron/neutron/agent/linux/interface.py", line 162, in init_l3
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent device.addr.add(ip_cidr)
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent File "/opt/stack/neutron/neutron/agent/linux/ip_lib.py", line 586, in add
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent self._as_root([net.version], tuple(args))
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent File "/opt/stack/neutron/neutron/agent/linux/ip_lib.py", line 370, in _as_root
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent use_root_namespace=use_root_namespace)
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent File "/opt/stack/neutron/neutron/agent/linux/ip_lib.py", line 100, in _as_root
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent log_fail_as_error=self.log_fail_as_error)
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent File "/opt/stack/neutron/neutron/agent/linux/ip_lib.py", line 109, in _execute
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent log_fail_as_error=log_fail_as_error)
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent File "/opt/stack/neutron/neutron/agent/linux/utils.py", line 151, in execute
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent raise ProcessExecutionError(msg, returncode=returncode)
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent ProcessExecutionError: Exit code: 2; Stdin: ; Stdout: ; Stderr: RTNETLINK answers: File exists
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: ERROR neutron.agent.dhcp.agent
Aug 17 14:56:55 server01 neutron-dhcp-agent[9649]: INFO neutron.agent.dhcp.agent [-] Finished network bcf3c383-dc35-4ec3-87cc-db3ac5e5f681 dhcp configuration