You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@cloudstack.apache.org by "Parth Jagirdar (JIRA)" <ji...@apache.org> on 2014/02/10 23:01:23 UTC
[jira] [Commented] (CLOUDSTACK-6071) Contrail:MS:DB: DBSync
exceptions in MS log
[ https://issues.apache.org/jira/browse/CLOUDSTACK-6071?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13897087#comment-13897087 ]
Parth Jagirdar commented on CLOUDSTACK-6071:
--------------------------------------------
This was observed after a addNic attempt was made, (Which failed due to PV Drivers not available).
> Contrail:MS:DB: DBSync exceptions in MS log
> -------------------------------------------
>
> Key: CLOUDSTACK-6071
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-6071
> Project: CloudStack
> Issue Type: Bug
> Security Level: Public(Anyone can view this level - this is the default.)
> Components: Contrail, Management Server
> Affects Versions: 4.2.1
> Environment: Contrail
> Reporter: Parth Jagirdar
> Priority: Critical
> Fix For: 4.2.1
>
>
> MS Log,
> 2014-02-10 12:23:31,122 INFO [n.j.c.a.ApiConnector] (DBSyncTimer:null) << Response Status: HTTP/1.1 200 OK
> 2014-02-10 12:23:31,122 INFO [n.j.c.a.ApiConnector] (DBSyncTimer:null) >> Request: GET, /floating-ip-pool/33b357b3-4f88-4ae6-af86-fb869999afa3
> 2014-02-10 12:23:31,125 INFO [n.j.c.a.ApiConnector] (DBSyncTimer:null) << Response Status: HTTP/1.1 200 OK
> 2014-02-10 12:23:31,126 INFO [n.j.c.a.ApiConnector] (DBSyncTimer:null) >> Request: GET, /floating-ip/6a6fa311-e5a0-4dfb-b289-cbad2122a756
> 2014-02-10 12:23:31,129 INFO [n.j.c.a.ApiConnector] (DBSyncTimer:null) << Response Status: HTTP/1.1 200 OK
> 2014-02-10 12:23:31,130 DEBUG [o.a.c.n.c.m.DBSyncGeneric] (DBSyncTimer:null) Generic db sync : FloatingIp
> 2014-02-10 12:23:31,141 INFO [n.j.c.a.ApiConnector] (DBSyncTimer:null) >> Request: GET, /virtual-network/e6c067bc-bc63-4613-a7af-84d0182ff6d2
> 2014-02-10 12:23:31,150 INFO [n.j.c.a.ApiConnector] (DBSyncTimer:null) << Response Status: HTTP/1.1 200 OK
> 2014-02-10 12:23:31,155 INFO [n.j.c.a.ApiConnector] (DBSyncTimer:null) >> Request: PUT, /virtual-network/e6c067bc-bc63-4613-a7af-84d0182ff6d2, {"virtual-network":{"virtual_network_properties":{"extend_to_external_routers":false,"network_id":4},"route_target_list":{"route_target":["target:64512:1002","target:64512:1002","target:64512:1002","target:64512:1002","target:64512:1002","target:64512:1002","target:64512:1002"]},"network_ipam_refs":[{"to":["default-domain","default-project","default-network-ipam"],"attr":{"ipam_subnets":[{"subnet":{"ip_prefix":"10.223.138.64","ip_prefix_len":26},"default_gateway":"10.223.138.65"}]},"href":null,"uuid":null}],"floating_ip_pools":[{"to":["default-domain","default-project","__default_Public__","PublicIpPool"],"attr":null,"href":"http://10.223.58.3:8082/floating-ip-pool/33b357b3-4f88-4ae6-af86-fb869999afa3","uuid":"33b357b3-4f88-4ae6-af86-fb869999afa3"}],"routing_instances":[{"to":["default-domain","default-project","__default_Public__","__default_Public__"],"attr":null,"href":"http://10.223.58.3:8082/routing-instance/74769566-dfce-4b6d-bbb5-db772bccb2f9","uuid":"74769566-dfce-4b6d-bbb5-db772bccb2f9"}],"name":"__default_Public__","uuid":"e6c067bc-bc63-4613-a7af-84d0182ff6d2","fq_name":["default-domain","default-project","__default_Public__"],"parent_type":"project","parent_uuid":"fafebf0e-5d9c-4c99-928d-25ab65bd7ebc"}}
> 2014-02-10 12:23:31,193 INFO [n.j.c.a.ApiConnector] (DBSyncTimer:null) << Response Status: HTTP/1.1 200 OK
> 2014-02-10 12:23:31,194 INFO [n.j.c.a.ApiConnector] (DBSyncTimer:null) >> Request: GET, /virtual-machine-interface/07c6bdac-7be4-4d88-83ef-6f5ffe2f8b19
> 2014-02-10 12:23:31,198 INFO [n.j.c.a.ApiConnector] (DBSyncTimer:null) << Response Status: HTTP/1.1 404 Not Found
> 2014-02-10 12:23:31,199 INFO [n.j.c.a.ApiConnector] (DBSyncTimer:null) >> Request: POST, /virtual-machine-interfaces, {"virtual-machine-interface":{"virtual_machine_interface_mac_addresses":{"mac_address":["06:e4:2a:00:00:35"]},"virtual_network_refs":[{"to":["default-domain","default-project","__default_Public__"],"attr":null,"href":null,"uuid":null}],"name":"s-11-VM-2","uuid":"07c6bdac-7be4-4d88-83ef-6f5ffe2f8b19","fq_name":["s-11-VM","s-11-VM-2"],"parent_type":"virtual-machine"}}
> 2014-02-10 12:23:31,205 INFO [n.j.c.a.ApiConnector] (DBSyncTimer:null) << Response Status: HTTP/1.1 400 Bad Request
> 2014-02-10 12:23:31,205 ERROR [n.j.c.a.ApiConnector] (DBSyncTimer:null) create api request failed: Bad Request
> 2014-02-10 12:23:31,206 ERROR [n.j.c.a.ApiConnector] (DBSyncTimer:null) Failure message:
> <!DOCTYPE HTML PUBLIC "-//IETF//DTD HTML 2.0//EN">
> <html>
> <head>
> <title>Error: 400 Bad Request</title>
> <style type="text/css">
> html {background-color: #eee; font-family: sans;}
> body {background-color: #fff; border: 1px solid #ddd;
> padding: 15px; margin: 15px;}
> pre {background-color: #eee; border: 1px solid #ddd; padding: 5px;}
> </style>
> </head>
> <body>
> <h1>Error: 400 Bad Request</h1>
> <p>Sorry, the requested URL <tt>'http://10.223.58.3:8082/virtual-machine-interfaces'</tt>
> caused an error:</p>
> <pre>Parent [u's-11-VM'] type virtual-machine does not exist</pre>
> </body>
> </html>
> 2014-02-10 12:23:31,206 WARN [o.a.c.n.c.m.ContrailManager] (DBSyncTimer:null) virtual-network update:
> com.cloud.exception.InternalErrorException: Unable to create virtual-machine-interface 07c6bdac-7be4-4d88-83ef-6f5ffe2f8b19
> at org.apache.cloudstack.network.contrail.model.VMInterfaceModel.update(VMInterfaceModel.java:229)
> at org.apache.cloudstack.network.contrail.model.VirtualNetworkModel.update(VirtualNetworkModel.java:305)
> at org.apache.cloudstack.network.contrail.management.ContrailManagerImpl.lookupPublicNetworkModel(ContrailManagerImpl.java:781)
> at org.apache.cloudstack.network.contrail.management.ServerDBSyncImpl.equalFloatingIp(ServerDBSyncImpl.java:910)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:622)
> at org.apache.cloudstack.network.contrail.management.DBSyncGeneric.equal(DBSyncGeneric.java:153)
> at org.apache.cloudstack.network.contrail.management.DBSyncGeneric.syncCollections(DBSyncGeneric.java:254)
> at org.apache.cloudstack.network.contrail.management.DBSyncGeneric.syncGeneric(DBSyncGeneric.java:297)
> at org.apache.cloudstack.network.contrail.management.ServerDBSyncImpl.syncFloatingIp(ServerDBSyncImpl.java:849)
> at sun.reflect.GeneratedMethodAccessor308.invoke(Unknown Source)
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:622)
> at org.apache.cloudstack.network.contrail.management.DBSyncGeneric.sync(DBSyncGeneric.java:112)
> at org.apache.cloudstack.network.contrail.management.ServerDBSyncImpl.syncAll(ServerDBSyncImpl.java:139)
> at org.apache.cloudstack.network.contrail.management.ContrailManagerImpl.syncNetworkDB(ContrailManagerImpl.java:429)
> at org.apache.cloudstack.network.contrail.management.ContrailManagerImpl$DBSyncTask.run(ContrailManagerImpl.java:446)
> at java.util.TimerThread.mainLoop(Timer.java:534)
> at java.util.TimerThread.run(Timer.java:484)
> 2014-02-10 12:23:31,207 DEBUG [o.a.c.n.c.m.DBSyncGeneric] (DBSyncTimer:null) Sync state checking stats<FloatingIp>: create: 0, delete: 0, equal: 1, diff:0
> 2014-02-10 12:23:31,207 DEBUG [o.a.c.n.c.m.DBSyncGeneric] (DBSyncTimer:null) DB and VNC objects are in sync : FloatingIp
> 2014-02-10 12:23:31,207 DEBUG [o.a.c.n.c.m.ServerDBSync] (DBSyncTimer:null) sync check finish: FloatingIp
> 2014-02-10 12:23:31,207 INFO [o.a.c.n.c.m.ContrailManager] (DBSyncTimer:null) # Cloudstack DB & VNC are out of sync #
> 2014-02-10 12:23:32,017 DEBUG [c.c.s.d.DownloadListener] (Timer-15:ctx-3a77dbcf) Scheduling timeout at 30000 ms, TEMPLATE: 205 at host 4
> 2014-02-10 12:23:32,226 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-10:null) SeqA 3-44297: Processing Seq 3-44297: { Cmd , MgmtId: -1, via: 3, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":1,"_loadInfo":"{\n \"connections\": []\n}","wait":0}}] }
> 2014-02-10 12:23:32,230 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-10:null) SeqA 3-44297: Sending Seq 3-44297: { Ans: , MgmtId: 214151488957798, via: 3, Ver: v1, Flags: 100010, [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
> 2014-02-10 12:23:33,203 DEBUG [c.c.s.StatsCollector] (StatsCollector-2:ctx-9edee364) VmStatsCollector is running...
> 2014-02-10 12:23:33,218 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-218:ctx-b85ef38d) Seq 1-91431898: Executing request
> Catalina
> INFO [n.j.c.a.ApiConnector] (DBSyncTimer:null) >> Request: POST, /virtual-machine-interfaces, {"virtual-machine-interface":{"virtual_machine_interface_mac_addresses":{"mac_address":["06:e4:2a:00:00:35"]},"virtual_network_refs":[{"to":["default-domain","default-project","__default_Public__"],"attr":null,"href":null,"uuid":null}],"name":"s-11-VM-2","uuid":"07c6bdac-7be4-4d88-83ef-6f5ffe2f8b19","fq_name":["s-11-VM","s-11-VM-2"],"parent_type":"virtual-machine"}}
> INFO [n.j.c.a.ApiConnector] (DBSyncTimer:null) << Response Status: HTTP/1.1 400 Bad Request
> ERROR [n.j.c.a.ApiConnector] (DBSyncTimer:null) create api request failed: Bad Request
> ERROR [n.j.c.a.ApiConnector] (DBSyncTimer:null) Failure message:
> <!DOCTYPE HTML PUBLIC "-//IETF//DTD HTML 2.0//EN">
> <html>
> <head>
> <title>Error: 400 Bad Request</title>
> <style type="text/css">
> html {background-color: #eee; font-family: sans;}
> body {background-color: #fff; border: 1px solid #ddd;
> padding: 15px; margin: 15px;}
> pre {background-color: #eee; border: 1px solid #ddd; padding: 5px;}
> </style>
> </head>
> <body>
> <h1>Error: 400 Bad Request</h1>
> <p>Sorry, the requested URL <tt>'http://10.223.58.3:8082/virtual-machine-interfaces'</tt>
> caused an error:</p>
> <pre>Parent [u's-11-VM'] type virtual-machine does not exist</pre>
> </body>
> </html>
> WARN [o.a.c.n.c.m.ContrailManager] (DBSyncTimer:null) virtual-network update:
> com.cloud.exception.InternalErrorException: Unable to create virtual-machine-interface 07c6bdac-7be4-4d88-83ef-6f5ffe2f8b19
> at org.apache.cloudstack.network.contrail.model.VMInterfaceModel.update(VMInterfaceModel.java:229)
> at org.apache.cloudstack.network.contrail.model.VirtualNetworkModel.update(VirtualNetworkModel.java:305)
> at org.apache.cloudstack.network.contrail.management.ContrailManagerImpl.lookupPublicNetworkModel(ContrailManagerImpl.java:781)
> at org.apache.cloudstack.network.contrail.management.ServerDBSyncImpl.equalFloatingIp(ServerDBSyncImpl.java:910)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:622)
> at org.apache.cloudstack.network.contrail.management.DBSyncGeneric.equal(DBSyncGeneric.java:153)
> at org.apache.cloudstack.network.contrail.management.DBSyncGeneric.syncCollections(DBSyncGeneric.java:254)
> at org.apache.cloudstack.network.contrail.management.DBSyncGeneric.syncGeneric(DBSyncGeneric.java:297)
> at org.apache.cloudstack.network.contrail.management.ServerDBSyncImpl.syncFloatingIp(ServerDBSyncImpl.java:849)
> at sun.reflect.GeneratedMethodAccessor308.invoke(Unknown Source)
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:622)
> at org.apache.cloudstack.network.contrail.management.DBSyncGeneric.sync(DBSyncGeneric.java:112)
> at org.apache.cloudstack.network.contrail.management.ServerDBSyncImpl.syncAll(ServerDBSyncImpl.java:139)
> at org.apache.cloudstack.network.contrail.management.ContrailManagerImpl.syncNetworkDB(ContrailManagerImpl.java:429)
> at org.apache.cloudstack.network.contrail.management.ContrailManagerImpl$DBSyncTask.run(ContrailManagerImpl.java:446)
> at java.util.TimerThread.mainLoop(Timer.java:534)
> at java.util.TimerThread.run(Timer.java:484)
> INFO [o.a.c.n.c.m.ContrailManager] (DBSyncTimer:null) # Cloudstack DB & VNC are out of sync #
> (END)
--
This message was sent by Atlassian JIRA
(v6.1.5#6160)