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 22:55:20 UTC

[jira] [Created] (CLOUDSTACK-6071) Contrail:MS:DB: DBSync exceptions in MS log

Parth Jagirdar created CLOUDSTACK-6071:
------------------------------------------

             Summary: 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>&#039;http://10.223.58.3:8082/virtual-machine-interfaces&#039;</tt>
               caused an error:</p>
            <pre>Parent [u&#039;s-11-VM&#039;] 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>&#039;http://10.223.58.3:8082/virtual-machine-interfaces&#039;</tt>
               caused an error:</p>
            <pre>Parent [u&#039;s-11-VM&#039;] 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)