Skip to content

Instantly share code, notes, and snippets.

@OGtrilliams

OGtrilliams/neutronlog Secret

Created August 7, 2018 14:55
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save OGtrilliams/ba4861dfd8eab7ae5a39dc236552524b to your computer and use it in GitHub Desktop.
Save OGtrilliams/ba4861dfd8eab7ae5a39dc236552524b to your computer and use it in GitHub Desktop.
Download ZIP
Raw
neutronlog
# systemctl status network.service -l
● network.service - LSB: Bring up/down networking
Loaded: loaded (/etc/rc.d/init.d/network; bad; vendor preset: disabled)
Active: active (exited) since Mon 2018-08-06 13:55:44 CDT; 19h ago
Docs: man:systemd-sysv-generator(8)
Process: 18994 ExecStop=/etc/rc.d/init.d/network stop (code=exited, status=0/SUCCESS)
Process: 19236 ExecStart=/etc/rc.d/init.d/network start (code=exited, status=0/SUCCESS)
Aug 06 13:55:40 compute2.strugglestack.org systemd[1]: Starting LSB: Bring up/down networking...
Aug 06 13:55:40 compute2.strugglestack.org network[19236]: Bringing up loopback interface: [ OK ]
Aug 06 13:55:40 compute2.strugglestack.org ovs-vsctl[19370]: ovs|00001|vsctl|INFO|Called as ovs-vsctl -t 10 -- --may-exist add-br br-ex -- set bridge br-ex fail_mode=standalone
Aug 06 13:55:44 compute2.strugglestack.org network[19236]: Bringing up interface br-ex: [ OK ]
Aug 06 13:55:44 compute2.strugglestack.org ovs-vsctl[19514]: ovs|00001|vsctl|INFO|Called as ovs-vsctl -t 10 -- --if-exists del-port br-ex eno1 -- add-port br-ex eno1
Aug 06 13:55:44 compute2.strugglestack.org network[19236]: Bringing up interface eno1: [ OK ]
Aug 06 13:55:44 compute2.strugglestack.org systemd[1]: Started LSB: Bring up/down networking.
# systemctl status neutron-openvswitch-agent -l
● neutron-openvswitch-agent.service - OpenStack Neutron Open vSwitch Agent
Loaded: loaded (/usr/lib/systemd/system/neutron-openvswitch-agent.service; enabled; vendor preset: disabled)
Active: active (running) since Mon 2018-08-06 13:55:44 CDT; 19h ago
Process: 19546 ExecStartPre=/usr/bin/neutron-enable-bridge-firewall.sh (code=exited, status=0/SUCCESS)
Main PID: 19552 (neutron-openvsw)
CGroup: /system.slice/neutron-openvswitch-agent.service
├─19552 /usr/bin/python2 /usr/bin/neutron-openvswitch-agent --config-file /usr/share/neutron/neutron-dist.conf --config-file /etc/neutron/neutron.conf --config-file /etc/neutron/plugins/ml2/openvswitch_agent.ini --config-dir /etc/neutron/conf.d/common --config-dir /etc/neutron/conf.d/neutron-openvswitch-agent --log-file /var/log/neutron/openvswitch-agent.log
├─19588 sudo neutron-rootwrap-daemon /etc/neutron/rootwrap.conf
├─19589 /usr/bin/python2 /usr/bin/neutron-rootwrap-daemon /etc/neutron/rootwrap.conf
├─19600 ovsdb-client monitor tcp:127.0.0.1:6640 Interface name,ofport,external_ids --format=json
└─19602 ovsdb-client monitor tcp:127.0.0.1:6640 Bridge name --format=json
Aug 06 13:55:44 compute2.strugglestack.org systemd[1]: Starting OpenStack Neutron Open vSwitch Agent...
Aug 06 13:55:44 compute2.strugglestack.org neutron-enable-bridge-firewall.sh[19546]: net.bridge.bridge-nf-call-iptables = 1
Aug 06 13:55:44 compute2.strugglestack.org neutron-enable-bridge-firewall.sh[19546]: net.bridge.bridge-nf-call-ip6tables = 1
Aug 06 13:55:44 compute2.strugglestack.org systemd[1]: Started OpenStack Neutron Open vSwitch Agent.
Aug 06 13:55:53 compute2.strugglestack.org sudo[19588]: neutron : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/neutron-rootwrap-daemon /etc/neutron/rootwrap.conf
Aug 06 13:55:54 compute2.strugglestack.org ovs-vsctl[19599]: ovs|00001|vsctl|INFO|Called as /bin/ovs-vsctl --timeout=5 --id=@manager -- create Manager "target=\"ptcp:6640:127.0.0.1\"" -- add Open_vSwitch . manager_options @manager
Aug 06 13:55:54 compute2.strugglestack.org ovs-vsctl[19599]: ovs|00002|ovsdb_idl|WARN|transaction error: {"details":"Transaction causes multiple rows in \"Manager\" table to have identical values (\"ptcp:6640:127.0.0.1\") for index on column \"target\". First row, with UUID 7d2c2214-68ee-4a3e-a4b8-24e834b184d2, was inserted by this transaction. Second row, with UUID a9e0c99e-073a-480f-a68c-52d1aa2a7566, existed in the database before this transaction and was not modified by the transaction.","error":"constraint violation"}
Aug 06 13:55:54 compute2.strugglestack.org ovs-vsctl[19601]: ovs|00001|vsctl|INFO|Called as /bin/ovs-vsctl --timeout=5 --id=@manager -- create Manager "target=\"ptcp:6640:127.0.0.1\"" -- add Open_vSwitch . manager_options @manager
Aug 06 13:55:54 compute2.strugglestack.org ovs-vsctl[19601]: ovs|00002|ovsdb_idl|WARN|transaction error: {"details":"Transaction causes multiple rows in \"Manager\" table to have identical values (\"ptcp:6640:127.0.0.1\") for index on column \"target\". First row, with UUID 3dc1cfce-69a6-4d5b-a3ac-cd8edeea12ef, was inserted by this transaction. Second row, with UUID a9e0c99e-073a-480f-a68c-52d1aa2a7566, existed in the database before this transaction and was not modified by the transaction.","error":"constraint violation"}
tail -200 server.log
2018-08-06 13:07:25.764 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: provider
2018-08-06 13:07:25.765 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: qos
2018-08-06 13:07:25.766 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: qos-bw-limit-direction
2018-08-06 13:07:25.766 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: qos-default
2018-08-06 13:07:25.766 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: qos-fip
2018-08-06 13:07:25.767 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: qos-rule-type-details
2018-08-06 13:07:25.767 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: quotas
2018-08-06 13:07:25.768 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: quota_details
2018-08-06 13:07:25.768 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: rbac-policies
2018-08-06 13:07:25.769 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: revision-if-match
2018-08-06 13:07:25.769 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: standard-attr-revisions
2018-08-06 13:07:25.770 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: router_availability_zone
2018-08-06 13:07:25.771 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Extension router-service-type not supported by any of loaded plugins
2018-08-06 13:07:25.772 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: security-group
2018-08-06 13:07:25.773 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Extension segment not supported by any of loaded plugins
2018-08-06 13:07:25.773 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: service-type
2018-08-06 13:07:25.774 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: sorting
2018-08-06 13:07:25.774 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: standard-attr-description
2018-08-06 13:07:25.775 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: subnet-service-types
2018-08-06 13:07:25.775 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: subnet_allocation
2018-08-06 13:07:25.776 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: tag
2018-08-06 13:07:25.776 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: tag-ext
2018-08-06 13:07:25.777 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: standard-attr-tag
2018-08-06 13:07:25.778 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: standard-attr-timestamp
2018-08-06 13:07:25.778 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: trunk
2018-08-06 13:07:25.779 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: trunk-details
2018-08-06 13:07:25.779 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Extension vlan-transparent not supported by any of loaded plugins
2018-08-06 13:07:25.780 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: hm_max_retries_down
2018-08-06 13:07:25.781 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: l7
2018-08-06 13:07:25.781 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: lb-graph
2018-08-06 13:07:25.782 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: lb_network_vip
2018-08-06 13:07:25.782 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: lbaas_agent_schedulerv2
2018-08-06 13:07:25.784 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: lbaasv2
2018-08-06 13:07:25.785 4940 INFO neutron.api.extensions [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Loaded extension: shared_pools
2018-08-06 13:07:25.786 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of TrackedResource for resource:subnet
2018-08-06 13:07:25.786 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of TrackedResource for resource:subnetpool
2018-08-06 13:07:25.787 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of TrackedResource for resource:network
2018-08-06 13:07:25.787 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of TrackedResource for resource:port
2018-08-06 13:07:25.788 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of CountableResource for resource:l7policy
2018-08-06 13:07:25.790 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of CountableResource for resource:trunk
2018-08-06 13:07:25.790 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of CountableResource for resource:member
2018-08-06 13:07:25.790 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of CountableResource for resource:loadbalancer
2018-08-06 13:07:25.790 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of CountableResource for resource:pool
2018-08-06 13:07:25.791 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of CountableResource for resource:listener
2018-08-06 13:07:25.791 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of CountableResource for resource:healthmonitor
2018-08-06 13:07:25.792 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of TrackedResource for resource:security_group
2018-08-06 13:07:25.794 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of TrackedResource for resource:security_group_rule
2018-08-06 13:07:25.794 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of CountableResource for resource:rbac_policy
2018-08-06 13:07:25.795 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of TrackedResource for resource:router
2018-08-06 13:07:25.795 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of TrackedResource for resource:floatingip
2018-08-06 13:07:25.798 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of CountableResource for resource:l7policy
2018-08-06 13:07:25.798 4940 WARNING neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] l7policy is already registered
2018-08-06 13:07:25.799 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of CountableResource for resource:trunk
2018-08-06 13:07:25.799 4940 WARNING neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] trunk is already registered
2018-08-06 13:07:25.799 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of CountableResource for resource:member
2018-08-06 13:07:25.800 4940 WARNING neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] member is already registered
2018-08-06 13:07:25.800 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of CountableResource for resource:loadbalancer
2018-08-06 13:07:25.800 4940 WARNING neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] loadbalancer is already registered
2018-08-06 13:07:25.800 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of CountableResource for resource:pool
2018-08-06 13:07:25.800 4940 WARNING neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] pool is already registered
2018-08-06 13:07:25.801 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of CountableResource for resource:listener
2018-08-06 13:07:25.801 4940 WARNING neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] listener is already registered
2018-08-06 13:07:25.801 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of CountableResource for resource:healthmonitor
2018-08-06 13:07:25.801 4940 WARNING neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] healthmonitor is already registered
2018-08-06 13:07:25.802 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of TrackedResource for resource:security_group
2018-08-06 13:07:25.802 4940 WARNING neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] security_group is already registered
2018-08-06 13:07:25.803 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of TrackedResource for resource:security_group_rule
2018-08-06 13:07:25.803 4940 WARNING neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] security_group_rule is already registered
2018-08-06 13:07:25.804 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of CountableResource for resource:rbac_policy
2018-08-06 13:07:25.805 4940 WARNING neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] rbac_policy is already registered
2018-08-06 13:07:25.805 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of TrackedResource for resource:router
2018-08-06 13:07:25.806 4940 WARNING neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] router is already registered
2018-08-06 13:07:25.806 4940 INFO neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Creating instance of TrackedResource for resource:floatingip
2018-08-06 13:07:25.807 4940 WARNING neutron.quota.resource_registry [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] floatingip is already registered
2018-08-06 13:07:25.848 4940 WARNING keystonemiddleware._common.config [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] The option "__file__" in conf is not known to auth_token
2018-08-06 13:07:25.849 4940 WARNING keystonemiddleware._common.config [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] The option "here" in conf is not known to auth_token
2018-08-06 13:07:25.851 4940 WARNING keystonemiddleware.auth_token [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] AuthToken middleware is set with keystone_authtoken.service_token_roles_required set to False. This is backwards compatible but deprecated behaviour. Please set this to True.
2018-08-06 13:07:25.853 4940 WARNING oslo_config.cfg [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Option "auth_uri" from group "keystone_authtoken" is deprecated. Use option "www_authenticate_uri" from group "keystone_authtoken".
2018-08-06 13:07:25.863 4940 INFO oslo_service.service [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Starting 4 workers
2018-08-06 13:07:25.868 4960 INFO neutron.wsgi [-] (4960) wsgi starting up on http://0.0.0.0:9696
2018-08-06 13:07:25.870 4961 INFO neutron.wsgi [-] (4961) wsgi starting up on http://0.0.0.0:9696
2018-08-06 13:07:25.872 4940 INFO neutron.service [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Neutron service started, listening on 0.0.0.0:9696
2018-08-06 13:07:25.872 4962 INFO neutron.wsgi [-] (4962) wsgi starting up on http://0.0.0.0:9696
2018-08-06 13:07:25.874 4940 INFO oslo_service.service [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Starting 4 workers
2018-08-06 13:07:25.874 4963 INFO neutron.wsgi [-] (4963) wsgi starting up on http://0.0.0.0:9696
2018-08-06 13:07:25.884 4940 INFO oslo_service.service [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Starting 1 workers
2018-08-06 13:07:25.888 4940 INFO oslo_service.service [req-2b7d1803-1bc7-46e1-b920-b8fcf343dafc - - - - -] Starting 1 workers
2018-08-06 13:07:25.929 4940 WARNING oslo_config.cfg [-] Option "auth_uri" from group "keystone_authtoken" is deprecated for removal (The auth_uri option is deprecated in favor of www_authenticate_uri and will be removed in the S release.). Its value may be silently ignored in the future.
2018-08-06 13:07:27.869 4960 WARNING keystonemiddleware.auth_token [-] Using the in-process token cache is deprecated as of the 4.2.0 release and may be removed in the 5.0.0 release or the 'O' development cycle. The in-process cache causes inconsistent results and high memory usage. When the feature is removed the auth_token middleware will not cache tokens by default which may result in performance issues. It is recommended to use memcache for the auth_token token cache by setting the memcached_servers option.
2018-08-06 13:07:28.713 4960 INFO neutron.wsgi [req-b6147359-6bab-429e-bddb-a0555d32a7fe 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/networks HTTP/1.1" status: 200 len: 213 time: 0.8452561
2018-08-06 13:07:28.747 4960 INFO neutron.wsgi [req-92e73909-43bb-4a7b-8fe7-8ff330feb3a9 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/subnets?fields=id&fields=cidr HTTP/1.1" status: 200 len: 212 time: 0.0277851
2018-08-06 13:07:30.357 4962 WARNING keystonemiddleware.auth_token [-] Using the in-process token cache is deprecated as of the 4.2.0 release and may be removed in the 5.0.0 release or the 'O' development cycle. The in-process cache causes inconsistent results and high memory usage. When the feature is removed the auth_token middleware will not cache tokens by default which may result in performance issues. It is recommended to use memcache for the auth_token token cache by setting the memcached_servers option.
2018-08-06 13:07:31.133 4962 INFO neutron.quota [req-c72c0d1a-8e46-409f-8d08-c65bc4dc1c64 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] Loaded quota_driver: <neutron.db.quota.driver.DbQuotaDriver object at 0x7fc688e3cbd0>.
2018-08-06 13:07:31.338 4962 INFO neutron.db.segments_db [req-c72c0d1a-8e46-409f-8d08-c65bc4dc1c64 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] Added segment dc9ab3e9-b5c3-4206-b136-c9f621ca945a of type flat for network eef3545c-bc54-4d46-93f3-8599d1c2fc3c
2018-08-06 13:07:31.502 4962 INFO neutron.wsgi [req-c72c0d1a-8e46-409f-8d08-c65bc4dc1c64 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "POST /v2.0/networks HTTP/1.1" status: 201 len: 867 time: 1.1470749
2018-08-06 13:07:33.075 4963 WARNING keystonemiddleware.auth_token [-] Using the in-process token cache is deprecated as of the 4.2.0 release and may be removed in the 5.0.0 release or the 'O' development cycle. The in-process cache causes inconsistent results and high memory usage. When the feature is removed the auth_token middleware will not cache tokens by default which may result in performance issues. It is recommended to use memcache for the auth_token token cache by setting the memcached_servers option.
2018-08-06 13:07:33.833 4963 INFO neutron.wsgi [req-55a87941-1471-43a4-8c8e-efe1d7db1599 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/subnets HTTP/1.1" status: 200 len: 212 time: 0.7600310
2018-08-06 13:07:35.396 4961 WARNING keystonemiddleware.auth_token [-] Using the in-process token cache is deprecated as of the 4.2.0 release and may be removed in the 5.0.0 release or the 'O' development cycle. The in-process cache causes inconsistent results and high memory usage. When the feature is removed the auth_token middleware will not cache tokens by default which may result in performance issues. It is recommended to use memcache for the auth_token token cache by setting the memcached_servers option.
2018-08-06 13:07:36.274 4961 INFO neutron.wsgi [req-17b09bcd-3c57-4563-bfd6-89b280efbefe 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/networks?fields=id&name=public HTTP/1.1" status: 200 len: 258 time: 0.8792989
2018-08-06 13:07:36.283 4961 INFO neutron.quota [req-ccfced8d-9323-4afb-af33-c26474fbfdf2 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] Loaded quota_driver: <neutron.db.quota.driver.DbQuotaDriver object at 0x7fc688a918d0>.
2018-08-06 13:07:36.550 4961 WARNING neutron.scheduler.dhcp_agent_scheduler [req-ccfced8d-9323-4afb-af33-c26474fbfdf2 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] No more DHCP agents
2018-08-06 13:07:36.551 4961 WARNING neutron.api.rpc.agentnotifiers.dhcp_rpc_agent_api [req-ccfced8d-9323-4afb-af33-c26474fbfdf2 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] Unable to schedule network eef3545c-bc54-4d46-93f3-8599d1c2fc3c: no agents available; will retry on subsequent port and subnet creation events.
2018-08-06 13:07:36.604 4961 INFO neutron.wsgi [req-ccfced8d-9323-4afb-af33-c26474fbfdf2 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "POST /v2.0/subnets HTTP/1.1" status: 201 len: 825 time: 0.3273330
2018-08-06 13:07:38.246 4963 INFO neutron.quota [req-03547699-8beb-44b2-b8a3-cf996c6f7b7d 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] Loaded quota_driver: <neutron.db.quota.driver.DbQuotaDriver object at 0x7fc688b99b50>.
2018-08-06 13:07:38.427 4963 INFO neutron.db.segments_db [req-03547699-8beb-44b2-b8a3-cf996c6f7b7d 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] Added segment b7124cea-aa8f-4803-b3c3-8ee2f20cb199 of type vxlan for network cb4f13ae-7bcb-40e3-ab94-5892faa6a30b
2018-08-06 13:07:38.579 4963 INFO neutron.wsgi [req-03547699-8beb-44b2-b8a3-cf996c6f7b7d 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "POST /v2.0/networks HTTP/1.1" status: 201 len: 864 time: 0.4188120
2018-08-06 13:07:40.303 4963 INFO neutron.wsgi [req-36202ab9-072a-45be-b281-afbf430fbe93 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/networks?fields=id&name=private HTTP/1.1" status: 200 len: 258 time: 0.1446211
2018-08-06 13:07:40.549 4963 WARNING neutron.scheduler.dhcp_agent_scheduler [req-723bc8e5-2513-49b9-b9cb-ad86d4435718 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] No more DHCP agents
2018-08-06 13:07:40.550 4963 WARNING neutron.api.rpc.agentnotifiers.dhcp_rpc_agent_api [req-723bc8e5-2513-49b9-b9cb-ad86d4435718 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] Unable to schedule network cb4f13ae-7bcb-40e3-ab94-5892faa6a30b: no agents available; will retry on subsequent port and subnet creation events.
2018-08-06 13:07:40.590 4963 INFO neutron.wsgi [req-723bc8e5-2513-49b9-b9cb-ad86d4435718 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "POST /v2.0/subnets HTTP/1.1" status: 201 len: 813 time: 0.2833951
2018-08-06 13:07:42.271 4961 INFO neutron.wsgi [req-4c11a411-f671-4d40-a214-1e067ffc77c4 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/routers HTTP/1.1" status: 200 len: 213 time: 0.1202960
2018-08-06 13:07:44.106 4961 INFO neutron.wsgi [req-3da3ea0d-c655-4c7c-b173-adb40a6a8415 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "POST /v2.0/routers HTTP/1.1" status: 201 len: 709 time: 0.2540319
2018-08-06 13:07:45.868 4962 INFO neutron.wsgi [req-539a533d-b816-4f1a-843c-40322ed8b004 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/routers?fields=id&name=router1 HTTP/1.1" status: 200 len: 259 time: 0.2039931
2018-08-06 13:07:45.980 4962 INFO neutron.wsgi [req-c50f8edc-a109-486a-9817-1cbfa819660a 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/networks?fields=id&name=public HTTP/1.1" status: 200 len: 258 time: 0.1082809
2018-08-06 13:07:47.106 4962 WARNING neutron.scheduler.dhcp_agent_scheduler [req-b1486757-de83-442f-8137-df15d9ebae2c 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] No more DHCP agents
2018-08-06 13:07:47.107 4962 WARNING neutron.api.rpc.agentnotifiers.dhcp_rpc_agent_api [req-b1486757-de83-442f-8137-df15d9ebae2c 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] Unable to schedule network eef3545c-bc54-4d46-93f3-8599d1c2fc3c: no agents available; will retry on subsequent port and subnet creation events.
2018-08-06 13:07:47.347 4962 WARNING neutron.scheduler.l3_agent_scheduler [req-b1486757-de83-442f-8137-df15d9ebae2c 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] No active L3 agents
2018-08-06 13:07:47.453 4962 INFO neutron.wsgi [req-b1486757-de83-442f-8137-df15d9ebae2c 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "PUT /v2.0/routers/ff39d718-6af8-4a9b-a82f-6de4fce0a0ce HTTP/1.1" status: 200 len: 884 time: 1.4688399
2018-08-06 13:07:49.227 4962 INFO neutron.wsgi [req-0cc95dac-5876-45ba-bd26-61037d590d46 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/routers?fields=id&name=router1 HTTP/1.1" status: 200 len: 259 time: 0.1918240
2018-08-06 13:07:49.333 4962 INFO neutron.wsgi [req-da8ee789-900d-4d51-ac3e-6bbe1dbb9e79 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/routers/ff39d718-6af8-4a9b-a82f-6de4fce0a0ce HTTP/1.1" status: 200 len: 884 time: 0.1014051
2018-08-06 13:07:51.118 4962 INFO neutron.wsgi [req-32756a4a-2747-4dda-ae96-61dc65771752 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/subnets HTTP/1.1" status: 200 len: 1423 time: 0.2215600
2018-08-06 13:07:52.813 4963 INFO neutron.wsgi [req-3dafd51d-06bf-4a44-8e17-cc962820f750 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/subnets?fields=id&id=350e4b17-f887-4fac-b432-6dfdf8ede2eb HTTP/1.1" status: 200 len: 257 time: 0.1217871
2018-08-06 13:07:52.852 4963 INFO neutron.wsgi [req-1b747301-cacc-4ea3-97b1-44b6691abea9 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/subnets/350e4b17-f887-4fac-b432-6dfdf8ede2eb HTTP/1.1" status: 200 len: 808 time: 0.0359631
2018-08-06 13:07:54.511 4963 INFO neutron.wsgi [req-d695f5ef-7682-4b2c-b4e2-6eac98f271b7 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/subnets?fields=id&id=aa562a0e-6b6b-414b-8866-7d9d3c162d42 HTTP/1.1" status: 200 len: 257 time: 0.1143382
2018-08-06 13:07:54.547 4963 INFO neutron.wsgi [req-5bee120d-a22d-4ec3-a5bf-3b78f9910b32 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/subnets/aa562a0e-6b6b-414b-8866-7d9d3c162d42 HTTP/1.1" status: 200 len: 820 time: 0.0334399
2018-08-06 13:07:56.386 4963 INFO neutron.wsgi [req-be7e1285-97c4-45dc-843a-dd90b3184c68 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/routers HTTP/1.1" status: 200 len: 887 time: 0.2698410
2018-08-06 13:07:58.132 4961 INFO neutron.wsgi [req-cb42ffc3-600f-4c92-812b-dd4eb8771d18 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/routers?fields=id&id=ff39d718-6af8-4a9b-a82f-6de4fce0a0ce HTTP/1.1" status: 200 len: 259 time: 0.1779518
2018-08-06 13:07:58.227 4961 INFO neutron.wsgi [req-a6987626-ddc1-4c67-87b6-da40ab66c518 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/routers/ff39d718-6af8-4a9b-a82f-6de4fce0a0ce HTTP/1.1" status: 200 len: 884 time: 0.0923300
2018-08-06 13:07:59.998 4960 INFO neutron.wsgi [req-677b3754-794a-436c-8fe6-69c332143c2c 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/routers?fields=id&id=ff39d718-6af8-4a9b-a82f-6de4fce0a0ce HTTP/1.1" status: 200 len: 259 time: 0.1964762
2018-08-06 13:08:00.039 4960 INFO neutron.wsgi [req-2994d166-8b96-43f7-8992-8ca8cb4657ae 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/ports?device_id=ff39d718-6af8-4a9b-a82f-6de4fce0a0ce HTTP/1.1" status: 200 len: 971 time: 0.0377049
2018-08-06 13:08:02.148 4962 INFO neutron.wsgi [req-260bbc34-1c07-4d70-8583-4e41abc69d5d 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/routers?fields=id&name=router1 HTTP/1.1" status: 200 len: 259 time: 0.1950390
2018-08-06 13:08:02.189 4962 INFO neutron.wsgi [req-1ecf8f85-eae3-4faa-95b5-9dc847bc728d 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/subnets?fields=id&name=private_subnet HTTP/1.1" status: 200 len: 257 time: 0.0378280
2018-08-06 13:08:03.171 4962 WARNING neutron.scheduler.dhcp_agent_scheduler [req-ff354397-9fdc-4e46-a746-dbe94a40fb69 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] No more DHCP agents
2018-08-06 13:08:03.172 4962 WARNING neutron.api.rpc.agentnotifiers.dhcp_rpc_agent_api [req-ff354397-9fdc-4e46-a746-dbe94a40fb69 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] Unable to schedule network cb4f13ae-7bcb-40e3-ab94-5892faa6a30b: no agents available; will retry on subsequent port and subnet creation events.
2018-08-06 13:08:03.508 4962 WARNING neutron.scheduler.dhcp_agent_scheduler [req-ff354397-9fdc-4e46-a746-dbe94a40fb69 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] No more DHCP agents
2018-08-06 13:08:03.509 4962 WARNING neutron.api.rpc.agentnotifiers.dhcp_rpc_agent_api [req-ff354397-9fdc-4e46-a746-dbe94a40fb69 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] Unable to schedule network cb4f13ae-7bcb-40e3-ab94-5892faa6a30b: no agents available; will retry on subsequent port and subnet creation events.
2018-08-06 13:08:03.684 4962 WARNING neutron.scheduler.l3_agent_scheduler [req-ff354397-9fdc-4e46-a746-dbe94a40fb69 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] No active L3 agents
2018-08-06 13:08:03.883 4962 INFO neutron.wsgi [req-ff354397-9fdc-4e46-a746-dbe94a40fb69 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "PUT /v2.0/routers/ff39d718-6af8-4a9b-a82f-6de4fce0a0ce/add_router_interface HTTP/1.1" status: 200 len: 508 time: 1.6922641
2018-08-06 13:10:00.048 4968 INFO neutron.db.l3_hamode_db [req-3f6fd045-9f1e-4841-8ceb-ae6a3c206979 - - - - -] Number of active agents lower than max_l3_agents_per_router. L3 agents available: 1
2018-08-06 13:10:06.179 4962 INFO neutron.wsgi [req-f19e64a1-2526-4d8a-874b-36bb394d72bb 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/subnets HTTP/1.1" status: 200 len: 1423 time: 0.4526660
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines [req-38b13be5-00af-4311-9141-c3ce0bbd0656 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] Database connection was found disconnected; reconnecting: DBConnectionError: (pymysql.err.OperationalError) (2006, "MySQL server has gone away (error(110, 'Connection timed out'))") [SQL: u'SELECT 1'] (Background on this error at: http://sqlalche.me/e/e3q8)
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines Traceback (most recent call last):
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines File "/usr/lib/python2.7/site-packages/oslo_db/sqlalchemy/engines.py", line 73, in _connect_ping_listener
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines connection.scalar(select([1]))
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines File "/usr/lib64/python2.7/site-packages/sqlalchemy/engine/base.py", line 880, in scalar
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines return self.execute(object, *multiparams, **params).scalar()
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines File "/usr/lib64/python2.7/site-packages/sqlalchemy/engine/base.py", line 948, in execute
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines return meth(self, multiparams, params)
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines File "/usr/lib64/python2.7/site-packages/sqlalchemy/sql/elements.py", line 269, in _execute_on_connection
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines return connection._execute_clauseelement(self, multiparams, params)
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines File "/usr/lib64/python2.7/site-packages/sqlalchemy/engine/base.py", line 1060, in _execute_clauseelement
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines compiled_sql, distilled_params
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines File "/usr/lib64/python2.7/site-packages/sqlalchemy/engine/base.py", line 1200, in _execute_context
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines context)
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines File "/usr/lib64/python2.7/site-packages/sqlalchemy/engine/base.py", line 1409, in _handle_dbapi_exception
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines util.raise_from_cause(newraise, exc_info)
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines File "/usr/lib64/python2.7/site-packages/sqlalchemy/util/compat.py", line 203, in raise_from_cause
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines reraise(type(exception), exception, tb=exc_tb, cause=cause)
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines File "/usr/lib64/python2.7/site-packages/sqlalchemy/engine/base.py", line 1193, in _execute_context
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines context)
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines File "/usr/lib64/python2.7/site-packages/sqlalchemy/engine/default.py", line 507, in do_execute
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines cursor.execute(statement, parameters)
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines File "/usr/lib/python2.7/site-packages/pymysql/cursors.py", line 166, in execute
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines result = self._query(query)
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines File "/usr/lib/python2.7/site-packages/pymysql/cursors.py", line 322, in _query
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines conn.query(q)
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines File "/usr/lib/python2.7/site-packages/pymysql/connections.py", line 855, in query
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines self._execute_command(COMMAND.COM_QUERY, sql)
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines File "/usr/lib/python2.7/site-packages/pymysql/connections.py", line 1092, in _execute_command
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines self._write_bytes(packet)
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines File "/usr/lib/python2.7/site-packages/pymysql/connections.py", line 1044, in _write_bytes
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines "MySQL server has gone away (%r)" % (e,))
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines DBConnectionError: (pymysql.err.OperationalError) (2006, "MySQL server has gone away (error(110, 'Connection timed out'))") [SQL: u'SELECT 1'] (Background on this error at: http://sqlalche.me/e/e3q8)
2018-08-06 13:10:08.211 4960 ERROR oslo_db.sqlalchemy.engines
2018-08-06 13:10:08.233 4960 INFO neutron.wsgi [req-38b13be5-00af-4311-9141-c3ce0bbd0656 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/subnets?fields=id&id=350e4b17-f887-4fac-b432-6dfdf8ede2eb HTTP/1.1" status: 200 len: 257 time: 0.1915250
2018-08-06 13:10:08.272 4960 INFO neutron.wsgi [req-467fb3c4-9b1f-4e46-8ebf-ec872e45f8ee 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/subnets/350e4b17-f887-4fac-b432-6dfdf8ede2eb HTTP/1.1" status: 200 len: 808 time: 0.0357358
2018-08-06 13:10:10.060 4961 INFO neutron.wsgi [req-f9eb7bac-f1fc-4d8b-9b81-43f3cc01a7ba 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/subnets?fields=id&id=aa562a0e-6b6b-414b-8866-7d9d3c162d42 HTTP/1.1" status: 200 len: 257 time: 0.1871150
2018-08-06 13:10:10.113 4961 INFO neutron.wsgi [req-1f2160be-1d71-4115-890d-f84463866838 16f8857445f341a28dac6707e1407945 593edd46e3a342e8a071e3b4034647b9 - default default] 192.168.1.165 "GET /v2.0/subnets/aa562a0e-6b6b-414b-8866-7d9d3c162d42 HTTP/1.1" status: 200 len: 820 time: 0.0499651
2018-08-06 13:13:17.492 4960 INFO neutron.wsgi [req-3aa392d0-b3bb-4a2d-97e0-f0d0056c0acd c970350181f34d62bea1795f48610625 8fb58894cea04c66a471eea20a900029 - default default] 192.168.1.165 "GET /v2.0/extensions HTTP/1.1" status: 200 len: 11349 time: 0.1559331
2018-08-06 13:13:24.515 4962 INFO neutron.wsgi [req-a8c9ac06-0125-4882-a9ca-0e2bacfc2782 c970350181f34d62bea1795f48610625 8fb58894cea04c66a471eea20a900029 - default default] 192.168.1.165 "GET /v2.0/extensions HTTP/1.1" status: 200 len: 11349 time: 0.1526978
2018-08-06 13:13:28.440 4962 INFO neutron.wsgi [req-3c6f1636-18b0-46ed-8bad-d41dfebc4f77 c970350181f34d62bea1795f48610625 8fb58894cea04c66a471eea20a900029 - default default] 192.168.1.165 "GET /v2.0/extensions HTTP/1.1" status: 200 len: 11349 time: 0.0061119
2018-08-06 13:13:33.730 4960 INFO neutron.wsgi [req-547c0097-250b-48a8-9503-25c925bf51aa c970350181f34d62bea1795f48610625 8fb58894cea04c66a471eea20a900029 - default default] 192.168.1.165 "GET /v2.0/extensions HTTP/1.1" status: 200 len: 11349 time: 0.0054832
2018-08-06 13:13:43.907 4961 INFO neutron.wsgi [req-9d022845-186f-413c-81b4-3162f8e78846 c970350181f34d62bea1795f48610625 8fb58894cea04c66a471eea20a900029 - default default] 192.168.1.165 "GET /v2.0/extensions HTTP/1.1" status: 200 len: 11349 time: 0.1612639
2018-08-06 13:13:46.034 4961 INFO neutron.wsgi [req-3c824e95-cb0d-4f21-bbb7-e22ad8cc4dbf c970350181f34d62bea1795f48610625 8fb58894cea04c66a471eea20a900029 - default default] 192.168.1.165 "GET /v2.0/extensions HTTP/1.1" status: 200 len: 11349 time: 0.0054300
2018-08-06 13:13:50.264 4960 INFO neutron.wsgi [req-51ae4b39-aeea-42e7-b554-297ed0805229 c970350181f34d62bea1795f48610625 8fb58894cea04c66a471eea20a900029 - default default] 192.168.1.165 "GET /v2.0/extensions HTTP/1.1" status: 200 len: 11349 time: 0.0053310
2018-08-06 13:13:58.114 4962 INFO neutron.wsgi [req-c09d0302-5b39-4bbd-9172-07b673c91b16 c970350181f34d62bea1795f48610625 8fb58894cea04c66a471eea20a900029 - default default] 192.168.1.165 "GET /v2.0/extensions HTTP/1.1" status: 200 len: 11349 time: 0.0055189
2018-08-06 13:13:59.312 4962 INFO neutron.wsgi [req-611d48b9-0109-486f-a69e-e4e4019fb016 c970350181f34d62bea1795f48610625 8fb58894cea04c66a471eea20a900029 - default default] 192.168.1.165 "GET /v2.0/quotas/8fb58894cea04c66a471eea20a900029/default HTTP/1.1" status: 200 len: 485 time: 0.0059609
2018-08-06 13:14:03.792 4961 INFO neutron.wsgi [req-f8543b9b-569d-4f4a-b7a8-2fd13fdb317c c970350181f34d62bea1795f48610625 8fb58894cea04c66a471eea20a900029 - default default] 192.168.1.165 "GET /v2.0/extensions HTTP/1.1" status: 200 len: 11349 time: 0.0056322
2018-08-06 13:14:05.862 4960 INFO neutron.wsgi [req-6e9be426-b063-4cdc-a8e0-951b46a52af6 c970350181f34d62bea1795f48610625 8fb58894cea04c66a471eea20a900029 - default default] 192.168.1.165 "GET /v2.0/extensions HTTP/1.1" status: 200 len: 11349 time: 0.0055590
2018-08-06 13:14:05.928 4960 INFO neutron.wsgi [req-b7c521f5-a342-435f-992b-4d007f9e783e c970350181f34d62bea1795f48610625 8fb58894cea04c66a471eea20a900029 - default default] 192.168.1.165 "GET /v2.0/agents HTTP/1.1" status: 200 len: 3965 time: 0.0107491
2018-08-06 13:14:18.889 4961 INFO neutron.wsgi [req-3100fc4f-2867-4f18-ab3b-62b5d61910ca c970350181f34d62bea1795f48610625 8fb58894cea04c66a471eea20a900029 - default default] 192.168.1.165 "GET /v2.0/extensions HTTP/1.1" status: 200 len: 11349 time: 0.0053680
2018-08-06 13:14:21.536 4961 INFO neutron.wsgi [req-51465828-2c2b-4640-a716-8168b436b2ab c970350181f34d62bea1795f48610625 8fb58894cea04c66a471eea20a900029 - default default] 192.168.1.165 "GET /v2.0/security-groups?tenant_id=8fb58894cea04c66a471eea20a900029 HTTP/1.1" status: 200 len: 2634 time: 0.0574040
2018-08-06 13:14:22.240 4962 INFO neutron.wsgi [req-850dc251-bd75-458b-bd5e-9cdd9efb601f c970350181f34d62bea1795f48610625 8fb58894cea04c66a471eea20a900029 - default default] 192.168.1.165 "GET /v2.0/networks?shared=False&tenant_id=8fb58894cea04c66a471eea20a900029 HTTP/1.1" status: 200 len: 903 time: 0.1288521
2018-08-06 13:14:22.278 4962 INFO neutron.wsgi [req-12e8a026-6f9e-4ec1-949c-ccd62a826ca9 c970350181f34d62bea1795f48610625 8fb58894cea04c66a471eea20a900029 - default default] 192.168.1.165 "GET /v2.0/subnets HTTP/1.1" status: 200 len: 1423 time: 0.0364609
2018-08-06 13:14:22.300 4962 INFO neutron.wsgi [req-28c98e9b-7c7b-4b1b-b099-99dc90f11e97 c970350181f34d62bea1795f48610625 8fb58894cea04c66a471eea20a900029 - default default] 192.168.1.165 "GET /v2.0/networks?shared=True HTTP/1.1" status: 200 len: 213 time: 0.0187778
2018-08-06 13:14:22.830 4962 INFO neutron.wsgi [req-c10e8e49-d6a2-43b4-94d6-1b2c459cb798 c970350181f34d62bea1795f48610625 8fb58894cea04c66a471eea20a900029 - default default] 192.168.1.165 "GET /v2.0/extensions HTTP/1.1" status: 200 len: 11349 time: 0.0088251
2018-08-06 13:14:22.901 4960 INFO neutron.wsgi [req-726a6187-ece3-4aa3-ace7-bc530d582f20 c970350181f34d62bea1795f48610625 8fb58894cea04c66a471eea20a900029 - default default] 192.168.1.165 "GET /v2.0/ports?network_id=eef3545c-bc54-4d46-93f3-8599d1c2fc3c HTTP/1.1" status: 200 len: 1061 time: 0.0656521
2018-08-06 13:14:22.928 4961 INFO neutron.wsgi [req-765a2f51-a107-4e09-9043-b2e0856efb97 c970350181f34d62bea1795f48610625 8fb58894cea04c66a471eea20a900029 - default default] 192.168.1.165 "GET /v2.0/trunks HTTP/1.1" status: 200 len: 212 time: 0.0222471
2018-08-06 13:55:54.252 4966 WARNING neutron.plugins.ml2.drivers.type_tunnel [req-52c8ebaa-7154-4c12-8cac-032246eefc44 - - - - -] Endpoint with ip 192.168.1.165 already exists: DBDuplicateEntry: (pymysql.err.IntegrityError) (1062, u"Duplicate entry '192.168.1.165' for key 'PRIMARY'") [SQL: u'INSERT INTO ml2_vxlan_endpoints (ip_address, udp_port, host) VALUES (%(ip_address)s, %(udp_port)s, %(host)s)'] [parameters: {'host': u'compute2.strugglestack.org', 'ip_address': u'192.168.1.165', 'udp_port': 4789}] (Background on this error at: http://sqlalche.me/e/gkpj)
2018-08-06 13:55:59.886 4969 WARNING oslo.messaging._drivers.impl_rabbit [-] Unexpected error during heartbeart thread processing, retrying...: IOError: Socket closed
[root@compute2 neutron]#
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment