[ 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)