cloudstack-users-cn mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From snpaulqi <snpau...@gmail.com>
Subject 回复: 上传模板失败--模板名称(wintest)
Date Sat, 21 Jun 2014 03:58:46 GMT
还是这个问题,补充一下。通过控制台查看ssvm  发现抛出以下日志
 
发件人: 谢福平
发送时间: 2014-06-20 17:31
收件人: users-cn
主题: 上传模板失败--模板名称(wintest)
每次传模板时,cloudstack界面就会显示“Storage  agent or storage VM disconnect”,下面是部分日志
 
2014-06-20 16:11:04,944 DEBUG [storage.download.DownloadListener] (Timer-6:null) Scheduling timeout at 30000 ms, template=wintest at host nfs://10.11.18.7/export/d2secondary
2014-06-20 16:11:05,014 DEBUG [agent.transport.Request] (Timer-6:null) Seq 31-1338376205: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:11:05,058 DEBUG [agent.transport.Request] (AgentManager-Handler-14:null) Seq 31-1338376205: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","downloadPct":0,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a5fd862f-5d3e-3c41-b912-3918446c341a/template/tmpl/2/264/dnld7559459646201869481tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:11:05,075 DEBUG [storage.download.DownloadListener] (Timer-7:null) Scheduling timeout at 30000 ms, template=wintest at host nfs://10.11.18.7/export/d3secondary
2014-06-20 16:11:05,097 DEBUG [cloud.api.ApiServlet] (catalina-exec-16:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251487481
2014-06-20 16:11:05,281 DEBUG [cloud.api.ApiServlet] (catalina-exec-16:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251487481
2014-06-20 16:11:05,336 DEBUG [cloud.api.ApiServlet] (catalina-exec-12:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251487720
2014-06-20 16:11:05,355 DEBUG [cloud.api.ApiServlet] (catalina-exec-18:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251487740
2014-06-20 16:11:05,379 DEBUG [cloud.api.ApiServlet] (catalina-exec-18:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251487740
2014-06-20 16:11:05,599 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251487983
2014-06-20 16:11:05,605 DEBUG [cloud.api.ApiServlet] (catalina-exec-6:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251486471
2014-06-20 16:11:05,762 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251487983
2014-06-20 16:11:05,791 DEBUG [cloud.api.ApiServlet] (catalina-exec-2:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251488176
2014-06-20 16:11:05,807 DEBUG [cloud.api.ApiServlet] (catalina-exec-19:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251488193
2014-06-20 16:11:05,826 DEBUG [cloud.api.ApiServlet] (catalina-exec-19:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251488193
2014-06-20 16:11:06,053 DEBUG [cloud.api.ApiServlet] (catalina-exec-21:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251488438
2014-06-20 16:11:06,252 DEBUG [cloud.api.ApiServlet] (catalina-exec-21:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251488438
2014-06-20 16:11:06,302 DEBUG [cloud.api.ApiServlet] (catalina-exec-20:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251488688
2014-06-20 16:11:06,322 DEBUG [cloud.api.ApiServlet] (catalina-exec-11:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251488708
2014-06-20 16:11:06,344 DEBUG [cloud.api.ApiServlet] (catalina-exec-11:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251488708
2014-06-20 16:11:06,543 DEBUG [cloud.api.ApiServlet] (catalina-exec-15:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251488928
2014-06-20 16:11:06,801 DEBUG [cloud.api.ApiServlet] (catalina-exec-15:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251488928
2014-06-20 16:11:06,836 DEBUG [cloud.api.ApiServlet] (catalina-exec-17:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251489220
2014-06-20 16:11:06,855 DEBUG [cloud.api.ApiServlet] (catalina-exec-1:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251489239
2014-06-20 16:11:06,891 DEBUG [cloud.api.ApiServlet] (catalina-exec-1:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251489239
2014-06-20 16:11:06,903 DEBUG [cloud.api.ApiServlet] (catalina-exec-3:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251489271
2014-06-20 16:11:07,174 DEBUG [cloud.api.ApiServlet] (catalina-exec-3:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251489271
2014-06-20 16:11:07,204 DEBUG [cloud.api.ApiServlet] (catalina-exec-23:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251489589
2014-06-20 16:11:07,303 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251487115
2014-06-20 16:11:07,385 DEBUG [cloud.api.ApiServlet] (catalina-exec-22:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251489607
2014-06-20 16:11:07,420 DEBUG [cloud.api.ApiServlet] (catalina-exec-22:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251489607
2014-06-20 16:11:07,427 DEBUG [cloud.api.ApiServlet] (catalina-exec-4:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251489742
2014-06-20 16:11:07,734 DEBUG [cloud.api.ApiServlet] (catalina-exec-4:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251489742
2014-06-20 16:11:07,767 DEBUG [cloud.api.ApiServlet] (catalina-exec-25:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251490152
2014-06-20 16:11:08,512 DEBUG [cloud.api.ApiServlet] (catalina-exec-12:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251487720
2014-06-20 16:11:08,521 DEBUG [cloud.api.ApiServlet] (catalina-exec-24:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251490168
2014-06-20 16:11:08,545 DEBUG [cloud.api.ApiServlet] (catalina-exec-24:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251490168
2014-06-20 16:11:08,551 DEBUG [cloud.api.ApiServlet] (catalina-exec-7:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251490344
2014-06-20 16:11:08,836 DEBUG [cloud.api.ApiServlet] (catalina-exec-7:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251490344
2014-06-20 16:11:08,901 DEBUG [cloud.api.ApiServlet] (catalina-exec-8:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251490903
2014-06-20 16:11:09,245 DEBUG [cloud.api.ApiServlet] (catalina-exec-8:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251490903
2014-06-20 16:11:09,250 DEBUG [cloud.api.ApiServlet] (catalina-exec-10:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251491251
2014-06-20 16:11:09,472 DEBUG [cloud.api.ApiServlet] (catalina-exec-2:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251488176
2014-06-20 16:11:09,484 DEBUG [cloud.api.ApiServlet] (catalina-exec-14:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251491266
2014-06-20 16:11:09,531 DEBUG [cloud.api.ApiServlet] (catalina-exec-14:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251491266
2014-06-20 16:11:09,536 DEBUG [cloud.api.ApiServlet] (catalina-exec-13:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251491664
2014-06-20 16:11:10,211 DEBUG [cloud.api.ApiServlet] (catalina-exec-20:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251488688
2014-06-20 16:11:10,230 DEBUG [cloud.api.ApiServlet] (catalina-exec-16:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251491676
2014-06-20 16:11:10,293 DEBUG [cloud.api.ApiServlet] (catalina-exec-16:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251491676
2014-06-20 16:11:10,301 DEBUG [cloud.api.ApiServlet] (catalina-exec-18:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251491778
2014-06-20 16:11:10,665 DEBUG [cloud.api.ApiServlet] (catalina-exec-18:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251491778
2014-06-20 16:11:10,697 DEBUG [cloud.api.ApiServlet] (catalina-exec-6:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251493083
2014-06-20 16:11:10,966 DEBUG [cloud.api.ApiServlet] (catalina-exec-17:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251489220
2014-06-20 16:11:10,984 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251493103
2014-06-20 16:11:11,029 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251493103
2014-06-20 16:11:11,230 DEBUG [cloud.api.ApiServlet] (catalina-exec-19:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251493614
2014-06-20 16:11:11,301 DEBUG [cloud.api.ApiServlet] (catalina-exec-23:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251489589
2014-06-20 16:11:11,496 DEBUG [cloud.api.ApiServlet] (catalina-exec-19:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251493614
2014-06-20 16:11:11,525 DEBUG [cloud.api.ApiServlet] (catalina-exec-21:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251493910
2014-06-20 16:11:11,544 DEBUG [cloud.api.ApiServlet] (catalina-exec-11:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251493928
2014-06-20 16:11:11,579 DEBUG [cloud.api.ApiServlet] (catalina-exec-11:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251493928
2014-06-20 16:11:11,670 DEBUG [cloud.api.ApiServlet] (catalina-exec-25:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251490152
2014-06-20 16:11:12,781 DEBUG [cloud.api.ApiServlet] (catalina-exec-10:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251491251
2014-06-20 16:11:12,963 DEBUG [cloud.api.ApiServlet] (catalina-exec-13:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251491664
2014-06-20 16:11:12,996 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is ready to launch secondary storage VM
2014-06-20 16:11:13,070 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 2 is ready to launch secondary storage VM
2014-06-20 16:11:13,280 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is ready to launch console proxy
2014-06-20 16:11:13,316 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 2 is ready to launch console proxy
2014-06-20 16:11:13,502 DEBUG [cloud.api.ApiServlet] (catalina-exec-6:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251493083
2014-06-20 16:11:13,691 DEBUG [cloud.api.ApiServlet] (catalina-exec-21:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251493910
2014-06-20 16:11:14,451 DEBUG [cloud.api.ApiServlet] (catalina-exec-15:null) ===START===  10.11.12.250 -- GET  command=listTemplates&page=1&pagesize=20&templatefilter=all&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251496836
2014-06-20 16:11:14,659 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860426: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:11:14,970 DEBUG [cloud.api.ApiServlet] (catalina-exec-15:null) ===END===  10.11.12.250 -- GET  command=listTemplates&page=1&pagesize=20&templatefilter=all&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251496836
2014-06-20 16:11:15,065 DEBUG [agent.transport.Request] (Timer-6:null) Seq 31-1338376206: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:11:15,100 DEBUG [agent.transport.Request] (AgentManager-Handler-12:null) Seq 33-668860426: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":0,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:11:15,125 DEBUG [agent.transport.Request] (AgentManager-Handler-5:null) Seq 31-1338376206: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","downloadPct":0,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a5fd862f-5d3e-3c41-b912-3918446c341a/template/tmpl/2/264/dnld7559459646201869481tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:11:15,400 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 routers. 
2014-06-20 16:11:17,010 DEBUG [cloud.api.ApiServlet] (catalina-exec-1:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251499396
2014-06-20 16:11:17,098 DEBUG [cloud.api.ApiServlet] (catalina-exec-1:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251499396
2014-06-20 16:11:17,126 DEBUG [cloud.api.ApiServlet] (catalina-exec-3:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251499511
2014-06-20 16:11:17,145 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251499527
2014-06-20 16:11:17,159 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251499527
2014-06-20 16:11:18,009 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-15:null) Ping from 27
2014-06-20 16:11:18,150 DEBUG [cloud.api.ApiServlet] (catalina-exec-3:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251499511
2014-06-20 16:11:18,494 DEBUG [cloud.server.StatsCollector] (StatsCollector-2:null) HostStatsCollector is running...
2014-06-20 16:11:18,529 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-7:null) Ping from 18
2014-06-20 16:11:18,672 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-6:null) Ping from 17
2014-06-20 16:11:19,139 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-8:null) Ping from 14
2014-06-20 16:11:19,139 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 1-2021720086: Received:  { Ans: , MgmtId: 73548179740843, via: 1, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:11:19,305 DEBUG [cloud.api.ApiServlet] (catalina-exec-22:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251501688
2014-06-20 16:11:19,399 DEBUG [cloud.api.ApiServlet] (catalina-exec-22:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251501688
2014-06-20 16:11:19,426 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-3:null) Ping from 1
2014-06-20 16:11:19,436 DEBUG [cloud.api.ApiServlet] (catalina-exec-4:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251501814
2014-06-20 16:11:19,447 DEBUG [cloud.api.ApiServlet] (catalina-exec-12:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251501831
2014-06-20 16:11:19,479 DEBUG [cloud.api.ApiServlet] (catalina-exec-12:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251501831
2014-06-20 16:11:19,599 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-10:null) Ping from 15
2014-06-20 16:11:19,766 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-4:null) Ping from 5
2014-06-20 16:11:19,797 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 5-1122631704: Received:  { Ans: , MgmtId: 73548179740843, via: 5, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:11:20,071 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-11:null) Ping from 16
2014-06-20 16:11:20,090 DEBUG [cloud.api.ApiServlet] (catalina-exec-24:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251502474
2014-06-20 16:11:20,137 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-14:null) Ping from 6
2014-06-20 16:11:20,202 DEBUG [cloud.api.ApiServlet] (catalina-exec-24:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251502474
2014-06-20 16:11:20,230 DEBUG [cloud.api.ApiServlet] (catalina-exec-7:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251502615
2014-06-20 16:11:20,245 DEBUG [cloud.api.ApiServlet] (catalina-exec-8:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251502631
2014-06-20 16:11:20,261 DEBUG [cloud.api.ApiServlet] (catalina-exec-8:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251502631
2014-06-20 16:11:20,306 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-12:null) Ping from 10
2014-06-20 16:11:20,406 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 6-1362821142: Received:  { Ans: , MgmtId: 73548179740843, via: 6, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:11:20,521 DEBUG [cloud.api.ApiServlet] (catalina-exec-4:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251501814
2014-06-20 16:11:20,551 DEBUG [cloud.api.ApiServlet] (catalina-exec-2:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251502935
2014-06-20 16:11:20,554 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-15:null) Ping from 23
2014-06-20 16:11:20,661 DEBUG [cloud.api.ApiServlet] (catalina-exec-2:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251502935
2014-06-20 16:11:20,688 DEBUG [cloud.api.ApiServlet] (catalina-exec-14:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251503073
2014-06-20 16:11:20,707 DEBUG [cloud.api.ApiServlet] (catalina-exec-20:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251503092
2014-06-20 16:11:20,733 DEBUG [cloud.api.ApiServlet] (catalina-exec-20:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251503092
2014-06-20 16:11:21,015 DEBUG [cloud.api.ApiServlet] (catalina-exec-16:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251503400
2014-06-20 16:11:21,040 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 7-143786013: Received:  { Ans: , MgmtId: 73548179740843, via: 7, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:11:21,150 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-6:null) Ping from 9
2014-06-20 16:11:21,198 DEBUG [cloud.api.ApiServlet] (catalina-exec-16:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251503400
2014-06-20 16:11:21,216 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-1:null) Ping from 7
2014-06-20 16:11:21,225 DEBUG [cloud.api.ApiServlet] (catalina-exec-18:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251503611
2014-06-20 16:11:21,242 DEBUG [cloud.api.ApiServlet] (catalina-exec-17:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251503628
2014-06-20 16:11:21,260 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251503645
2014-06-20 16:11:21,272 DEBUG [cloud.api.ApiServlet] (catalina-exec-17:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251503628
2014-06-20 16:11:21,413 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251503645
2014-06-20 16:11:21,447 DEBUG [cloud.api.ApiServlet] (catalina-exec-23:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251503831
2014-06-20 16:11:21,474 DEBUG [cloud.api.ApiServlet] (catalina-exec-19:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251503859
2014-06-20 16:11:21,493 DEBUG [cloud.api.ApiServlet] (catalina-exec-11:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251503878
2014-06-20 16:11:21,506 DEBUG [cloud.api.ApiServlet] (catalina-exec-19:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251503859
2014-06-20 16:11:21,754 DEBUG [cloud.api.ApiServlet] (catalina-exec-11:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251503878
2014-06-20 16:11:21,780 DEBUG [cloud.api.ApiServlet] (catalina-exec-25:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251504165
2014-06-20 16:11:21,797 DEBUG [cloud.api.ApiServlet] (catalina-exec-10:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251504182
2014-06-20 16:11:21,867 DEBUG [cloud.api.ApiServlet] (catalina-exec-10:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251504182
2014-06-20 16:11:22,243 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-8:null) Ping from 8
2014-06-20 16:11:22,427 DEBUG [cloud.api.ApiServlet] (catalina-exec-7:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251502615
2014-06-20 16:11:22,626 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 8-1224933398: Received:  { Ans: , MgmtId: 73548179740843, via: 8, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:11:23,333 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 9-1976500241: Received:  { Ans: , MgmtId: 73548179740843, via: 9, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:11:23,434 DEBUG [cloud.api.ApiServlet] (catalina-exec-14:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251503073
2014-06-20 16:11:23,558 DEBUG [cloud.api.ApiServlet] (catalina-exec-13:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251505943
2014-06-20 16:11:23,774 DEBUG [cloud.api.ApiServlet] (catalina-exec-13:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251505943
2014-06-20 16:11:23,801 DEBUG [cloud.api.ApiServlet] (catalina-exec-6:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251506186
2014-06-20 16:11:23,818 DEBUG [cloud.api.ApiServlet] (catalina-exec-21:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251506201
2014-06-20 16:11:23,863 DEBUG [cloud.api.ApiServlet] (catalina-exec-21:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251506201
2014-06-20 16:11:23,960 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 10-1734803473: Received:  { Ans: , MgmtId: 73548179740843, via: 10, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:11:24,238 DEBUG [cloud.api.ApiServlet] (catalina-exec-18:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251503611
2014-06-20 16:11:24,456 DEBUG [cloud.api.ApiServlet] (catalina-exec-23:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251503831
2014-06-20 16:11:24,574 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 14-1005387797: Received:  { Ans: , MgmtId: 73548179740843, via: 14, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:11:24,612 DEBUG [cloud.api.ApiServlet] (catalina-exec-25:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251504165
2014-06-20 16:11:25,105 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860427: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:11:25,131 DEBUG [agent.transport.Request] (Timer-6:null) Seq 31-1338376207: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:11:25,168 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 15-710017042: Received:  { Ans: , MgmtId: 73548179740843, via: 15, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:11:25,212 DEBUG [agent.transport.Request] (AgentManager-Handler-11:null) Seq 31-1338376207: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","downloadPct":0,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a5fd862f-5d3e-3c41-b912-3918446c341a/template/tmpl/2/264/dnld7559459646201869481tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:11:25,239 DEBUG [cloud.api.ApiServlet] (catalina-exec-6:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251506186
2014-06-20 16:11:25,306 DEBUG [agent.transport.Request] (AgentManager-Handler-5:null) Seq 33-668860427: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":0,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:11:25,368 DEBUG [cloud.api.ApiServlet] (catalina-exec-15:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251507750
2014-06-20 16:11:25,450 DEBUG [cloud.api.ApiServlet] (catalina-exec-15:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251507750
2014-06-20 16:11:25,478 DEBUG [cloud.api.ApiServlet] (catalina-exec-1:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251507863
2014-06-20 16:11:25,496 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251507880
2014-06-20 16:11:25,513 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251507880
2014-06-20 16:11:25,777 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 16-136380443: Received:  { Ans: , MgmtId: 73548179740843, via: 16, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:11:26,368 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 17-129499154: Received:  { Ans: , MgmtId: 73548179740843, via: 17, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:11:26,479 DEBUG [cloud.api.ApiServlet] (catalina-exec-1:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251507863
2014-06-20 16:11:26,970 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 18-29884432: Received:  { Ans: , MgmtId: 73548179740843, via: 18, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:11:27,198 DEBUG [cloud.api.ApiServlet] (catalina-exec-3:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251509583
2014-06-20 16:11:27,277 DEBUG [cloud.api.ApiServlet] (catalina-exec-3:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251509583
2014-06-20 16:11:27,312 DEBUG [cloud.api.ApiServlet] (catalina-exec-22:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251509697
2014-06-20 16:11:27,331 DEBUG [cloud.api.ApiServlet] (catalina-exec-12:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251509715
2014-06-20 16:11:27,345 DEBUG [cloud.api.ApiServlet] (catalina-exec-12:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251509715
2014-06-20 16:11:27,735 DEBUG [cloud.api.ApiServlet] (catalina-exec-24:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251510120
2014-06-20 16:11:27,846 DEBUG [cloud.api.ApiServlet] (catalina-exec-24:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251510120
2014-06-20 16:11:27,880 DEBUG [cloud.api.ApiServlet] (catalina-exec-8:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251510265
2014-06-20 16:11:27,898 DEBUG [cloud.api.ApiServlet] (catalina-exec-4:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251510283
2014-06-20 16:11:27,917 DEBUG [cloud.api.ApiServlet] (catalina-exec-4:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251510283
2014-06-20 16:11:27,990 DEBUG [cloud.api.ApiServlet] (catalina-exec-2:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251510375
2014-06-20 16:11:28,128 DEBUG [cloud.api.ApiServlet] (catalina-exec-2:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251510375
2014-06-20 16:11:28,159 DEBUG [cloud.api.ApiServlet] (catalina-exec-20:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251510544
2014-06-20 16:11:28,177 DEBUG [cloud.api.ApiServlet] (catalina-exec-16:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251510562
2014-06-20 16:11:28,230 DEBUG [cloud.api.ApiServlet] (catalina-exec-16:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251510562
2014-06-20 16:11:28,857 DEBUG [cloud.api.ApiServlet] (catalina-exec-22:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251509697
2014-06-20 16:11:29,550 DEBUG [cloud.api.ApiServlet] (catalina-exec-8:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251510265
2014-06-20 16:11:29,719 DEBUG [cloud.api.ApiServlet] (catalina-exec-20:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251510544
2014-06-20 16:11:34,944 DEBUG [storage.download.DownloadListener] (Timer-6:null) Scheduling timeout at 30000 ms, template=wintest at host nfs://10.11.18.7/export/d2secondary
2014-06-20 16:11:35,075 DEBUG [storage.download.DownloadListener] (Timer-7:null) Scheduling timeout at 30000 ms, template=wintest at host nfs://10.11.18.7/export/d3secondary
2014-06-20 16:11:35,219 DEBUG [agent.transport.Request] (Timer-6:null) Seq 31-1338376208: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:11:35,226 DEBUG [agent.transport.Request] (AgentManager-Handler-6:null) Seq 31-1338376208: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","downloadPct":0,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a5fd862f-5d3e-3c41-b912-3918446c341a/template/tmpl/2/264/dnld7559459646201869481tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:11:35,313 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860428: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:11:35,552 DEBUG [cloud.api.ApiServlet] (catalina-exec-17:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251517935
2014-06-20 16:11:35,635 DEBUG [cloud.api.ApiServlet] (catalina-exec-17:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251517935
2014-06-20 16:11:35,640 DEBUG [agent.transport.Request] (AgentManager-Handler-1:null) Seq 33-668860428: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":0,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:11:35,666 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251518050
2014-06-20 16:11:35,684 DEBUG [cloud.api.ApiServlet] (catalina-exec-19:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251518067
2014-06-20 16:11:35,702 DEBUG [cloud.api.ApiServlet] (catalina-exec-19:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251518067
2014-06-20 16:11:36,376 DEBUG [cloud.api.ApiServlet] (catalina-exec-11:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251518761
2014-06-20 16:11:36,483 DEBUG [cloud.api.ApiServlet] (catalina-exec-11:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251518761
2014-06-20 16:11:36,514 DEBUG [cloud.api.ApiServlet] (catalina-exec-10:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251518898
2014-06-20 16:11:36,528 DEBUG [cloud.api.ApiServlet] (catalina-exec-7:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251518913
2014-06-20 16:11:36,552 DEBUG [cloud.api.ApiServlet] (catalina-exec-7:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251518913
2014-06-20 16:11:36,833 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251518050
2014-06-20 16:11:37,579 DEBUG [cloud.api.ApiServlet] (catalina-exec-10:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251518898
2014-06-20 16:11:38,339 DEBUG [cloud.api.ApiServlet] (catalina-exec-14:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251520719
2014-06-20 16:11:38,423 DEBUG [cloud.api.ApiServlet] (catalina-exec-14:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251520719
2014-06-20 16:11:38,453 DEBUG [cloud.api.ApiServlet] (catalina-exec-13:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251520837
2014-06-20 16:11:38,473 DEBUG [cloud.api.ApiServlet] (catalina-exec-21:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251520857
2014-06-20 16:11:38,488 DEBUG [cloud.api.ApiServlet] (catalina-exec-21:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251520857
2014-06-20 16:11:39,433 DEBUG [cloud.api.ApiServlet] (catalina-exec-13:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251520837
2014-06-20 16:11:39,624 DEBUG [cloud.api.ApiServlet] (catalina-exec-18:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251522008
2014-06-20 16:11:39,708 DEBUG [cloud.api.ApiServlet] (catalina-exec-18:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251522008
2014-06-20 16:11:39,738 DEBUG [cloud.api.ApiServlet] (catalina-exec-23:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251522123
2014-06-20 16:11:39,756 DEBUG [cloud.api.ApiServlet] (catalina-exec-25:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251522140
2014-06-20 16:11:39,770 DEBUG [cloud.api.ApiServlet] (catalina-exec-25:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251522140
2014-06-20 16:11:40,739 DEBUG [cloud.api.ApiServlet] (catalina-exec-23:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251522123
2014-06-20 16:11:42,284 DEBUG [cloud.server.StatsCollector] (StatsCollector-2:null) VmStatsCollector is running...
2014-06-20 16:11:42,411 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 1-2021720087: Received:  { Ans: , MgmtId: 73548179740843, via: 1, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2014-06-20 16:11:42,560 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 5-1122631705: Received:  { Ans: , MgmtId: 73548179740843, via: 5, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2014-06-20 16:11:42,974 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is ready to launch secondary storage VM
2014-06-20 16:11:43,013 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 2 is ready to launch secondary storage VM
2014-06-20 16:11:43,126 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 6-1362821143: Received:  { Ans: , MgmtId: 73548179740843, via: 6, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2014-06-20 16:11:43,257 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is ready to launch console proxy
2014-06-20 16:11:43,275 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 2 is ready to launch console proxy
2014-06-20 16:11:43,669 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 7-143786014: Received:  { Ans: , MgmtId: 73548179740843, via: 7, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2014-06-20 16:11:44,248 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 8-1224933399: Received:  { Ans: , MgmtId: 73548179740843, via: 8, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2014-06-20 16:11:44,343 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 16-136380444: Received:  { Ans: , MgmtId: 73548179740843, via: 16, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2014-06-20 16:11:45,233 DEBUG [agent.transport.Request] (Timer-6:null) Seq 31-1338376209: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:11:45,279 DEBUG [agent.transport.Request] (AgentManager-Handler-11:null) Seq 31-1338376209: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","downloadPct":0,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a5fd862f-5d3e-3c41-b912-3918446c341a/template/tmpl/2/264/dnld7559459646201869481tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:11:45,400 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 routers. 
2014-06-20 16:11:45,647 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860429: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:11:45,656 DEBUG [agent.transport.Request] (AgentManager-Handler-5:null) Seq 33-668860429: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":0,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:11:48,585 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-12:null) Ping from 33
2014-06-20 16:11:50,528 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-15:null) Ping from 31
2014-06-20 16:11:55,286 DEBUG [agent.transport.Request] (Timer-6:null) Seq 31-1338376210: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:11:55,332 DEBUG [agent.transport.Request] (AgentManager-Handler-7:null) Seq 31-1338376210: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","downloadPct":0,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a5fd862f-5d3e-3c41-b912-3918446c341a/template/tmpl/2/264/dnld7559459646201869481tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:11:55,663 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860430: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:11:55,709 DEBUG [agent.transport.Request] (AgentManager-Handler-6:null) Seq 33-668860430: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":0,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:11:58,239 DEBUG [cloud.api.ApiServlet] (catalina-exec-6:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251540621
2014-06-20 16:11:58,323 DEBUG [cloud.api.ApiServlet] (catalina-exec-6:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251540621
2014-06-20 16:11:58,349 DEBUG [cloud.api.ApiServlet] (catalina-exec-15:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251540734
2014-06-20 16:11:58,371 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251540755
2014-06-20 16:11:58,390 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251540755
2014-06-20 16:11:59,061 DEBUG [cloud.api.ApiServlet] (catalina-exec-1:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251541445
2014-06-20 16:11:59,177 DEBUG [cloud.api.ApiServlet] (catalina-exec-1:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251541445
2014-06-20 16:11:59,210 DEBUG [cloud.api.ApiServlet] (catalina-exec-3:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251541592
2014-06-20 16:11:59,234 DEBUG [cloud.api.ApiServlet] (catalina-exec-12:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251541608
2014-06-20 16:11:59,255 DEBUG [cloud.api.ApiServlet] (catalina-exec-12:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251541608
2014-06-20 16:11:59,567 DEBUG [cloud.api.ApiServlet] (catalina-exec-15:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251540734
2014-06-20 16:12:00,108 DEBUG [cloud.server.StatsCollector] (StatsCollector-2:null) StorageCollector is running...
2014-06-20 16:12:00,351 DEBUG [cloud.api.ApiServlet] (catalina-exec-3:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251541592
2014-06-20 16:12:00,946 DEBUG [cloud.api.ApiServlet] (catalina-exec-24:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251543330
2014-06-20 16:12:01,033 DEBUG [cloud.api.ApiServlet] (catalina-exec-24:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251543330
2014-06-20 16:12:01,064 DEBUG [cloud.api.ApiServlet] (catalina-exec-4:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251543448
2014-06-20 16:12:01,082 DEBUG [cloud.api.ApiServlet] (catalina-exec-2:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251543464
2014-06-20 16:12:01,097 DEBUG [cloud.api.ApiServlet] (catalina-exec-2:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251543464
2014-06-20 16:12:01,135 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 31-1338376211: Received:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:12:02,148 DEBUG [cloud.api.ApiServlet] (catalina-exec-4:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251543448
2014-06-20 16:12:04,944 DEBUG [storage.download.DownloadListener] (Timer-6:null) Scheduling timeout at 30000 ms, template=wintest at host nfs://10.11.18.7/export/d2secondary
2014-06-20 16:12:05,075 DEBUG [storage.download.DownloadListener] (Timer-7:null) Scheduling timeout at 30000 ms, template=wintest at host nfs://10.11.18.7/export/d3secondary
2014-06-20 16:12:05,339 DEBUG [agent.transport.Request] (Timer-6:null) Seq 31-1338376212: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:12:05,347 DEBUG [agent.transport.Request] (AgentManager-Handler-8:null) Seq 31-1338376212: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","downloadPct":1,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a5fd862f-5d3e-3c41-b912-3918446c341a/template/tmpl/2/264/dnld7559459646201869481tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:12:05,717 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860432: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:12:05,782 DEBUG [agent.transport.Request] (AgentManager-Handler-3:null) Seq 33-668860432: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":0,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:12:07,028 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 33-668860431: Received:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:12:07,256 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 6-1362821144: Received:  { Ans: , MgmtId: 73548179740843, via: 6, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:12:07,386 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 9-1976500242: Received:  { Ans: , MgmtId: 73548179740843, via: 9, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:12:07,540 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 18-29884433: Received:  { Ans: , MgmtId: 73548179740843, via: 18, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:12:07,697 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 18-29884434: Received:  { Ans: , MgmtId: 73548179740843, via: 18, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:12:07,900 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 16-136380445: Received:  { Ans: , MgmtId: 73548179740843, via: 16, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:12:08,053 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 1-2021720088: Received:  { Ans: , MgmtId: 73548179740843, via: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:12:12,973 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is ready to launch secondary storage VM
2014-06-20 16:12:13,012 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 2 is ready to launch secondary storage VM
2014-06-20 16:12:13,257 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is ready to launch console proxy
2014-06-20 16:12:13,277 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 2 is ready to launch console proxy
2014-06-20 16:12:14,518 DEBUG [cloud.api.ApiServlet] (catalina-exec-16:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251556901
2014-06-20 16:12:14,604 DEBUG [cloud.api.ApiServlet] (catalina-exec-16:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251556901
2014-06-20 16:12:14,636 DEBUG [cloud.api.ApiServlet] (catalina-exec-22:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251557020
2014-06-20 16:12:14,656 DEBUG [cloud.api.ApiServlet] (catalina-exec-8:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251557038
2014-06-20 16:12:14,671 DEBUG [cloud.api.ApiServlet] (catalina-exec-8:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251557038
2014-06-20 16:12:15,222 DEBUG [cloud.api.ApiServlet] (catalina-exec-20:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251557606
2014-06-20 16:12:15,344 DEBUG [cloud.api.ApiServlet] (catalina-exec-20:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251557606
2014-06-20 16:12:15,352 DEBUG [agent.transport.Request] (Timer-6:null) Seq 31-1338376213: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:12:15,376 DEBUG [cloud.api.ApiServlet] (catalina-exec-17:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251557760
2014-06-20 16:12:15,394 DEBUG [cloud.api.ApiServlet] (catalina-exec-19:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251557778
2014-06-20 16:12:15,399 DEBUG [agent.transport.Request] (AgentManager-Handler-15:null) Seq 31-1338376213: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","downloadPct":1,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a5fd862f-5d3e-3c41-b912-3918446c341a/template/tmpl/2/264/dnld7559459646201869481tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:12:15,400 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 routers. 
2014-06-20 16:12:15,421 DEBUG [cloud.api.ApiServlet] (catalina-exec-19:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251557778
2014-06-20 16:12:15,790 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860433: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:12:15,862 DEBUG [cloud.api.ApiServlet] (catalina-exec-22:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251557020
2014-06-20 16:12:15,864 DEBUG [agent.transport.Request] (AgentManager-Handler-7:null) Seq 33-668860433: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":0,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:12:16,559 DEBUG [cloud.api.ApiServlet] (catalina-exec-17:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251557760
2014-06-20 16:12:17,122 DEBUG [cloud.api.ApiServlet] (catalina-exec-11:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251559506
2014-06-20 16:12:17,207 DEBUG [cloud.api.ApiServlet] (catalina-exec-11:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251559506
2014-06-20 16:12:17,242 DEBUG [cloud.api.ApiServlet] (catalina-exec-7:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251559626
2014-06-20 16:12:17,261 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251559644
2014-06-20 16:12:17,275 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251559644
2014-06-20 16:12:18,010 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-6:null) Ping from 27
2014-06-20 16:12:18,261 DEBUG [cloud.api.ApiServlet] (catalina-exec-7:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251559626
2014-06-20 16:12:18,529 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-1:null) Ping from 18
2014-06-20 16:12:18,530 DEBUG [cloud.api.ApiServlet] (catalina-exec-10:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251560864
2014-06-20 16:12:18,612 DEBUG [cloud.api.ApiServlet] (catalina-exec-10:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251560864
2014-06-20 16:12:18,650 DEBUG [cloud.api.ApiServlet] (catalina-exec-14:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251561023
2014-06-20 16:12:18,656 DEBUG [cloud.api.ApiServlet] (catalina-exec-21:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251561038
2014-06-20 16:12:18,667 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-8:null) Ping from 17
2014-06-20 16:12:18,693 DEBUG [cloud.api.ApiServlet] (catalina-exec-21:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251561038
2014-06-20 16:12:19,141 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-3:null) Ping from 14
2014-06-20 16:12:19,434 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-10:null) Ping from 1
2014-06-20 16:12:19,596 DEBUG [cloud.api.ApiServlet] (catalina-exec-14:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251561023
2014-06-20 16:12:19,600 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-13:null) Ping from 15
2014-06-20 16:12:19,769 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-4:null) Ping from 5
2014-06-20 16:12:20,075 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-14:null) Ping from 16
2014-06-20 16:12:20,215 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-11:null) Ping from 6
2014-06-20 16:12:20,316 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-5:null) Ping from 10
2014-06-20 16:12:20,554 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-12:null) Ping from 23
2014-06-20 16:12:21,173 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-15:null) Ping from 9
2014-06-20 16:12:21,240 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-7:null) Ping from 7
2014-06-20 16:12:22,252 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-6:null) Ping from 8
2014-06-20 16:12:25,406 DEBUG [agent.transport.Request] (Timer-6:null) Seq 31-1338376214: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:12:25,451 DEBUG [agent.transport.Request] (AgentManager-Handler-1:null) Seq 31-1338376214: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","downloadPct":1,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a5fd862f-5d3e-3c41-b912-3918446c341a/template/tmpl/2/264/dnld7559459646201869481tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:12:25,871 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860434: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:12:26,141 DEBUG [cloud.api.ApiServlet] (catalina-exec-13:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251568524
2014-06-20 16:12:26,231 DEBUG [cloud.api.ApiServlet] (catalina-exec-13:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251568524
2014-06-20 16:12:26,268 DEBUG [cloud.api.ApiServlet] (catalina-exec-18:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251568650
2014-06-20 16:12:26,277 DEBUG [agent.transport.Request] (AgentManager-Handler-8:null) Seq 33-668860434: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":0,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:12:26,286 DEBUG [cloud.api.ApiServlet] (catalina-exec-25:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251568669
2014-06-20 16:12:26,301 DEBUG [cloud.api.ApiServlet] (catalina-exec-25:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251568669
2014-06-20 16:12:26,971 DEBUG [cloud.server.StatsCollector] (StatsCollector-2:null) HostStatsCollector is running...
2014-06-20 16:12:27,362 DEBUG [cloud.api.ApiServlet] (catalina-exec-18:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251568650
2014-06-20 16:12:27,618 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 1-2021720089: Received:  { Ans: , MgmtId: 73548179740843, via: 1, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:12:28,258 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 5-1122631706: Received:  { Ans: , MgmtId: 73548179740843, via: 5, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:12:28,874 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 6-1362821145: Received:  { Ans: , MgmtId: 73548179740843, via: 6, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:12:29,501 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 7-143786015: Received:  { Ans: , MgmtId: 73548179740843, via: 7, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:12:31,051 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 8-1224933400: Received:  { Ans: , MgmtId: 73548179740843, via: 8, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:12:31,746 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 9-1976500243: Received:  { Ans: , MgmtId: 73548179740843, via: 9, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:12:32,388 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 10-1734803474: Received:  { Ans: , MgmtId: 73548179740843, via: 10, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:12:32,992 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 14-1005387798: Received:  { Ans: , MgmtId: 73548179740843, via: 14, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:12:33,583 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 15-710017043: Received:  { Ans: , MgmtId: 73548179740843, via: 15, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:12:34,190 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 16-136380446: Received:  { Ans: , MgmtId: 73548179740843, via: 16, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:12:34,789 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 17-129499155: Received:  { Ans: , MgmtId: 73548179740843, via: 17, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:12:34,944 DEBUG [storage.download.DownloadListener] (Timer-6:null) Scheduling timeout at 30000 ms, template=wintest at host nfs://10.11.18.7/export/d2secondary
2014-06-20 16:12:35,075 DEBUG [storage.download.DownloadListener] (Timer-7:null) Scheduling timeout at 30000 ms, template=wintest at host nfs://10.11.18.7/export/d3secondary
2014-06-20 16:12:35,394 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 18-29884435: Received:  { Ans: , MgmtId: 73548179740843, via: 18, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:12:35,458 DEBUG [agent.transport.Request] (Timer-6:null) Seq 31-1338376215: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:12:35,503 DEBUG [agent.transport.Request] (AgentManager-Handler-8:null) Seq 31-1338376215: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","downloadPct":1,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a5fd862f-5d3e-3c41-b912-3918446c341a/template/tmpl/2/264/dnld7559459646201869481tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:12:36,284 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860435: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:12:36,576 DEBUG [agent.transport.Request] (AgentManager-Handler-3:null) Seq 33-668860435: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":1,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:12:42,787 DEBUG [cloud.api.ApiServlet] (catalina-exec-23:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251585169
2014-06-20 16:12:42,873 DEBUG [cloud.api.ApiServlet] (catalina-exec-23:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251585169
2014-06-20 16:12:42,903 DEBUG [cloud.api.ApiServlet] (catalina-exec-6:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251585286
2014-06-20 16:12:42,920 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251585303
2014-06-20 16:12:42,935 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251585303
2014-06-20 16:12:42,971 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is ready to launch secondary storage VM
2014-06-20 16:12:43,023 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 2 is ready to launch secondary storage VM
2014-06-20 16:12:43,268 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is ready to launch console proxy
2014-06-20 16:12:43,293 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 2 is ready to launch console proxy
2014-06-20 16:12:43,467 DEBUG [cloud.api.ApiServlet] (catalina-exec-1:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251585848
2014-06-20 16:12:43,579 DEBUG [cloud.api.ApiServlet] (catalina-exec-1:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251585848
2014-06-20 16:12:43,619 DEBUG [cloud.api.ApiServlet] (catalina-exec-12:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251586002
2014-06-20 16:12:43,651 DEBUG [cloud.api.ApiServlet] (catalina-exec-15:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251586020
2014-06-20 16:12:43,672 DEBUG [cloud.api.ApiServlet] (catalina-exec-15:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251586020
2014-06-20 16:12:44,271 DEBUG [cloud.api.ApiServlet] (catalina-exec-6:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251585286
2014-06-20 16:12:44,353 DEBUG [cloud.server.StatsCollector] (StatsCollector-2:null) VmStatsCollector is running...
2014-06-20 16:12:44,497 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 1-2021720090: Received:  { Ans: , MgmtId: 73548179740843, via: 1, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2014-06-20 16:12:44,674 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 5-1122631707: Received:  { Ans: , MgmtId: 73548179740843, via: 5, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2014-06-20 16:12:44,908 DEBUG [cloud.api.ApiServlet] (catalina-exec-12:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251586002
2014-06-20 16:12:45,254 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 6-1362821146: Received:  { Ans: , MgmtId: 73548179740843, via: 6, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2014-06-20 16:12:45,293 DEBUG [cloud.api.ApiServlet] (catalina-exec-3:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251587676
2014-06-20 16:12:45,384 DEBUG [cloud.api.ApiServlet] (catalina-exec-3:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251587676
2014-06-20 16:12:45,399 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 routers. 
2014-06-20 16:12:45,419 DEBUG [cloud.api.ApiServlet] (catalina-exec-24:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251587801
2014-06-20 16:12:45,433 DEBUG [cloud.api.ApiServlet] (catalina-exec-2:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251587817
2014-06-20 16:12:45,449 DEBUG [cloud.api.ApiServlet] (catalina-exec-2:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251587817
2014-06-20 16:12:45,508 DEBUG [agent.transport.Request] (Timer-6:null) Seq 31-1338376216: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:12:45,551 DEBUG [agent.transport.Request] (AgentManager-Handler-14:null) Seq 31-1338376216: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","downloadPct":1,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a5fd862f-5d3e-3c41-b912-3918446c341a/template/tmpl/2/264/dnld7559459646201869481tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:12:45,788 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 7-143786016: Received:  { Ans: , MgmtId: 73548179740843, via: 7, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2014-06-20 16:12:46,449 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 8-1224933401: Received:  { Ans: , MgmtId: 73548179740843, via: 8, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2014-06-20 16:12:46,479 DEBUG [cloud.api.ApiServlet] (catalina-exec-24:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251587801
2014-06-20 16:12:46,574 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 16-136380447: Received:  { Ans: , MgmtId: 73548179740843, via: 16, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2014-06-20 16:12:46,583 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860436: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:12:46,677 DEBUG [agent.transport.Request] (AgentManager-Handler-15:null) Seq 33-668860436: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":1,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:12:48,723 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-7:null) Ping from 33
2014-06-20 16:12:50,528 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-6:null) Ping from 31
2014-06-20 16:12:55,558 DEBUG [agent.transport.Request] (Timer-6:null) Seq 31-1338376217: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:12:55,604 DEBUG [agent.transport.Request] (AgentManager-Handler-1:null) Seq 31-1338376217: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","downloadPct":1,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a5fd862f-5d3e-3c41-b912-3918446c341a/template/tmpl/2/264/dnld7559459646201869481tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:12:56,684 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860437: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:12:56,764 DEBUG [agent.transport.Request] (AgentManager-Handler-8:null) Seq 33-668860437: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":1,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:13:03,399 DEBUG [cloud.api.ApiServlet] (catalina-exec-4:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251605781
2014-06-20 16:13:03,487 DEBUG [cloud.api.ApiServlet] (catalina-exec-4:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251605781
2014-06-20 16:13:03,517 DEBUG [cloud.api.ApiServlet] (catalina-exec-16:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251605901
2014-06-20 16:13:03,539 DEBUG [cloud.api.ApiServlet] (catalina-exec-8:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251605919
2014-06-20 16:13:03,557 DEBUG [cloud.api.ApiServlet] (catalina-exec-8:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251605919
2014-06-20 16:13:04,080 DEBUG [cloud.api.ApiServlet] (catalina-exec-20:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251606463
2014-06-20 16:13:04,192 DEBUG [cloud.api.ApiServlet] (catalina-exec-20:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251606463
2014-06-20 16:13:04,229 DEBUG [cloud.api.ApiServlet] (catalina-exec-19:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251606612
2014-06-20 16:13:04,251 DEBUG [cloud.api.ApiServlet] (catalina-exec-22:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251606630
2014-06-20 16:13:04,274 DEBUG [cloud.api.ApiServlet] (catalina-exec-22:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251606630
2014-06-20 16:13:04,584 DEBUG [cloud.api.ApiServlet] (catalina-exec-17:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251606966
2014-06-20 16:13:04,742 DEBUG [cloud.api.ApiServlet] (catalina-exec-17:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251606966
2014-06-20 16:13:04,783 DEBUG [cloud.api.ApiServlet] (catalina-exec-11:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251607152
2014-06-20 16:13:04,796 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251607167
2014-06-20 16:13:04,832 DEBUG [cloud.api.ApiServlet] (catalina-exec-16:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251605901
2014-06-20 16:13:04,834 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251607167
2014-06-20 16:13:04,945 DEBUG [storage.download.DownloadListener] (Timer-6:null) Scheduling timeout at 30000 ms, template=wintest at host nfs://10.11.18.7/export/d2secondary
2014-06-20 16:13:05,075 DEBUG [storage.download.DownloadListener] (Timer-7:null) Scheduling timeout at 30000 ms, template=wintest at host nfs://10.11.18.7/export/d3secondary
2014-06-20 16:13:05,609 DEBUG [agent.transport.Request] (Timer-6:null) Seq 31-1338376218: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:13:05,655 DEBUG [agent.transport.Request] (AgentManager-Handler-3:null) Seq 31-1338376218: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","downloadPct":1,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a5fd862f-5d3e-3c41-b912-3918446c341a/template/tmpl/2/264/dnld7559459646201869481tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:13:05,763 DEBUG [cloud.api.ApiServlet] (catalina-exec-19:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251606612
2014-06-20 16:13:06,086 DEBUG [cloud.api.ApiServlet] (catalina-exec-11:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251607152
2014-06-20 16:13:06,185 DEBUG [cloud.api.ApiServlet] (catalina-exec-7:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251608568
2014-06-20 16:13:06,273 DEBUG [cloud.api.ApiServlet] (catalina-exec-7:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251608568
2014-06-20 16:13:06,303 DEBUG [cloud.api.ApiServlet] (catalina-exec-10:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251608686
2014-06-20 16:13:06,318 DEBUG [cloud.api.ApiServlet] (catalina-exec-21:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251608702
2014-06-20 16:13:06,335 DEBUG [cloud.api.ApiServlet] (catalina-exec-21:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251608702
2014-06-20 16:13:06,769 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860438: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:13:06,939 DEBUG [agent.transport.Request] (AgentManager-Handler-10:null) Seq 33-668860438: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":1,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:13:07,347 DEBUG [cloud.api.ApiServlet] (catalina-exec-10:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251608686
2014-06-20 16:13:08,054 DEBUG [cloud.server.StatsCollector] (StatsCollector-2:null) StorageCollector is running...
2014-06-20 16:13:09,769 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 31-1338376219: Received:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:13:12,971 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is ready to launch secondary storage VM
2014-06-20 16:13:13,037 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 2 is ready to launch secondary storage VM
2014-06-20 16:13:13,264 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is ready to launch console proxy
2014-06-20 16:13:13,284 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 2 is ready to launch console proxy
2014-06-20 16:13:14,033 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 33-668860439: Received:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:13:14,248 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 6-1362821147: Received:  { Ans: , MgmtId: 73548179740843, via: 6, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:13:14,455 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 9-1976500244: Received:  { Ans: , MgmtId: 73548179740843, via: 9, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:13:14,637 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 10-1734803475: Received:  { Ans: , MgmtId: 73548179740843, via: 10, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:13:14,671 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 15-710017044: Received:  { Ans: , MgmtId: 73548179740843, via: 15, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:13:14,755 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 17-129499156: Received:  { Ans: , MgmtId: 73548179740843, via: 17, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:13:14,896 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 1-2021720091: Received:  { Ans: , MgmtId: 73548179740843, via: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:13:15,400 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 routers. 
2014-06-20 16:13:15,662 DEBUG [agent.transport.Request] (Timer-6:null) Seq 31-1338376220: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:13:15,669 DEBUG [agent.transport.Request] (AgentManager-Handler-6:null) Seq 31-1338376220: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","downloadPct":2,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a5fd862f-5d3e-3c41-b912-3918446c341a/template/tmpl/2/264/dnld7559459646201869481tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:13:16,946 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860440: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:13:16,951 DEBUG [agent.transport.Request] (AgentManager-Handler-1:null) Seq 33-668860440: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":1,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:13:18,010 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-8:null) Ping from 27
2014-06-20 16:13:18,528 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-3:null) Ping from 18
2014-06-20 16:13:18,669 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-10:null) Ping from 17
2014-06-20 16:13:19,141 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-13:null) Ping from 14
2014-06-20 16:13:19,432 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-4:null) Ping from 1
2014-06-20 16:13:19,600 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-14:null) Ping from 15
2014-06-20 16:13:19,775 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-11:null) Ping from 5
2014-06-20 16:13:20,079 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-5:null) Ping from 16
2014-06-20 16:13:20,179 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-12:null) Ping from 6
2014-06-20 16:13:20,308 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-15:null) Ping from 10
2014-06-20 16:13:20,554 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-7:null) Ping from 23
2014-06-20 16:13:21,156 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-6:null) Ping from 9
2014-06-20 16:13:21,222 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-1:null) Ping from 7
2014-06-20 16:13:22,250 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-8:null) Ping from 8
2014-06-20 16:13:25,503 DEBUG [cloud.api.ApiServlet] (catalina-exec-14:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251627885
2014-06-20 16:13:25,592 DEBUG [cloud.api.ApiServlet] (catalina-exec-14:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251627885
2014-06-20 16:13:25,622 DEBUG [cloud.api.ApiServlet] (catalina-exec-13:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251628005
2014-06-20 16:13:25,640 DEBUG [cloud.api.ApiServlet] (catalina-exec-25:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251628023
2014-06-20 16:13:25,651 DEBUG [cloud.api.ApiServlet] (catalina-exec-25:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251628023
2014-06-20 16:13:25,675 DEBUG [agent.transport.Request] (Timer-6:null) Seq 31-1338376221: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:13:25,719 DEBUG [agent.transport.Request] (AgentManager-Handler-3:null) Seq 31-1338376221: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","downloadPct":2,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a5fd862f-5d3e-3c41-b912-3918446c341a/template/tmpl/2/264/dnld7559459646201869481tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:13:26,265 DEBUG [cloud.api.ApiServlet] (catalina-exec-18:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251628647
2014-06-20 16:13:26,379 DEBUG [cloud.api.ApiServlet] (catalina-exec-18:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251628647
2014-06-20 16:13:26,412 DEBUG [cloud.api.ApiServlet] (catalina-exec-23:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251628793
2014-06-20 16:13:26,441 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251628817
2014-06-20 16:13:26,463 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251628817
2014-06-20 16:13:26,792 DEBUG [cloud.api.ApiServlet] (catalina-exec-13:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251628005
2014-06-20 16:13:26,904 DEBUG [cloud.api.ApiServlet] (catalina-exec-1:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251629285
2014-06-20 16:13:26,957 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860441: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:13:27,025 DEBUG [cloud.api.ApiServlet] (catalina-exec-1:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251629285
2014-06-20 16:13:27,053 DEBUG [cloud.api.ApiServlet] (catalina-exec-15:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251629435
2014-06-20 16:13:27,073 DEBUG [cloud.api.ApiServlet] (catalina-exec-6:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251629455
2014-06-20 16:13:27,096 DEBUG [cloud.api.ApiServlet] (catalina-exec-6:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251629455
2014-06-20 16:13:27,189 DEBUG [agent.transport.Request] (AgentManager-Handler-10:null) Seq 33-668860441: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":1,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:13:27,535 DEBUG [cloud.api.ApiServlet] (catalina-exec-12:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251629917
2014-06-20 16:13:27,697 DEBUG [cloud.api.ApiServlet] (catalina-exec-12:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251629917
2014-06-20 16:13:27,735 DEBUG [cloud.api.ApiServlet] (catalina-exec-3:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251630117
2014-06-20 16:13:27,767 DEBUG [cloud.api.ApiServlet] (catalina-exec-2:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251630135
2014-06-20 16:13:27,796 DEBUG [cloud.api.ApiServlet] (catalina-exec-2:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251630135
2014-06-20 16:13:28,029 DEBUG [cloud.api.ApiServlet] (catalina-exec-23:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251628793
2014-06-20 16:13:28,675 DEBUG [cloud.api.ApiServlet] (catalina-exec-15:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251629435
2014-06-20 16:13:29,094 DEBUG [cloud.api.ApiServlet] (catalina-exec-3:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251630117
2014-06-20 16:13:30,923 DEBUG [cloud.api.ApiServlet] (catalina-exec-24:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251633305
2014-06-20 16:13:30,998 DEBUG [cloud.api.ApiServlet] (catalina-exec-24:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251633305
2014-06-20 16:13:31,032 DEBUG [cloud.api.ApiServlet] (catalina-exec-4:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251633413
2014-06-20 16:13:31,046 DEBUG [cloud.api.ApiServlet] (catalina-exec-8:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251633428
2014-06-20 16:13:31,067 DEBUG [cloud.api.ApiServlet] (catalina-exec-8:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251633428
2014-06-20 16:13:32,096 DEBUG [cloud.api.ApiServlet] (catalina-exec-4:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251633413
2014-06-20 16:13:32,298 DEBUG [cloud.api.ApiServlet] (catalina-exec-20:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251634671
2014-06-20 16:13:32,385 DEBUG [cloud.api.ApiServlet] (catalina-exec-20:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251634671
2014-06-20 16:13:32,414 DEBUG [cloud.api.ApiServlet] (catalina-exec-22:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251634793
2014-06-20 16:13:32,424 DEBUG [cloud.api.ApiServlet] (catalina-exec-17:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251634807
2014-06-20 16:13:32,447 DEBUG [cloud.api.ApiServlet] (catalina-exec-17:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251634807
2014-06-20 16:13:33,470 DEBUG [cloud.api.ApiServlet] (catalina-exec-22:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251634793
2014-06-20 16:13:34,944 DEBUG [storage.download.DownloadListener] (Timer-6:null) Scheduling timeout at 30000 ms, template=wintest at host nfs://10.11.18.7/export/d2secondary
2014-06-20 16:13:35,075 DEBUG [storage.download.DownloadListener] (Timer-7:null) Scheduling timeout at 30000 ms, template=wintest at host nfs://10.11.18.7/export/d3secondary
2014-06-20 16:13:35,394 DEBUG [cloud.server.StatsCollector] (StatsCollector-2:null) HostStatsCollector is running...
2014-06-20 16:13:35,725 DEBUG [agent.transport.Request] (Timer-6:null) Seq 31-1338376222: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:13:35,772 DEBUG [agent.transport.Request] (AgentManager-Handler-13:null) Seq 31-1338376222: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","downloadPct":2,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a5fd862f-5d3e-3c41-b912-3918446c341a/template/tmpl/2/264/dnld7559459646201869481tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:13:36,031 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 1-2021720092: Received:  { Ans: , MgmtId: 73548179740843, via: 1, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:13:36,673 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 5-1122631708: Received:  { Ans: , MgmtId: 73548179740843, via: 5, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:13:37,196 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860442: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:13:37,277 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 6-1362821148: Received:  { Ans: , MgmtId: 73548179740843, via: 6, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:13:37,375 DEBUG [agent.transport.Request] (AgentManager-Handler-5:null) Seq 33-668860442: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":1,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:13:37,903 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 7-143786017: Received:  { Ans: , MgmtId: 73548179740843, via: 7, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:13:39,441 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 8-1224933402: Received:  { Ans: , MgmtId: 73548179740843, via: 8, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:13:40,132 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 9-1976500245: Received:  { Ans: , MgmtId: 73548179740843, via: 9, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:13:40,739 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 10-1734803476: Received:  { Ans: , MgmtId: 73548179740843, via: 10, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:13:41,342 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 14-1005387799: Received:  { Ans: , MgmtId: 73548179740843, via: 14, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:13:41,958 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 15-710017045: Received:  { Ans: , MgmtId: 73548179740843, via: 15, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:13:42,567 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 16-136380448: Received:  { Ans: , MgmtId: 73548179740843, via: 16, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:13:42,972 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is ready to launch secondary storage VM
2014-06-20 16:13:43,012 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 2 is ready to launch secondary storage VM
2014-06-20 16:13:43,157 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 17-129499157: Received:  { Ans: , MgmtId: 73548179740843, via: 17, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:13:43,257 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is ready to launch console proxy
2014-06-20 16:13:43,276 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 2 is ready to launch console proxy
2014-06-20 16:13:43,757 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 18-29884436: Received:  { Ans: , MgmtId: 73548179740843, via: 18, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-06-20 16:13:45,401 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 routers. 
2014-06-20 16:13:45,779 DEBUG [agent.transport.Request] (Timer-6:null) Seq 31-1338376223: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:13:45,824 DEBUG [agent.transport.Request] (AgentManager-Handler-4:null) Seq 31-1338376223: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","downloadPct":2,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a5fd862f-5d3e-3c41-b912-3918446c341a/template/tmpl/2/264/dnld7559459646201869481tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:13:46,587 DEBUG [cloud.server.StatsCollector] (StatsCollector-2:null) VmStatsCollector is running...
2014-06-20 16:13:46,713 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 1-2021720093: Received:  { Ans: , MgmtId: 73548179740843, via: 1, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2014-06-20 16:13:46,868 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 5-1122631709: Received:  { Ans: , MgmtId: 73548179740843, via: 5, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2014-06-20 16:13:47,382 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860443: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:13:47,398 DEBUG [agent.transport.Request] (AgentManager-Handler-5:null) Seq 33-668860443: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":1,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:13:47,411 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 6-1362821149: Received:  { Ans: , MgmtId: 73548179740843, via: 6, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2014-06-20 16:13:47,938 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 7-143786018: Received:  { Ans: , MgmtId: 73548179740843, via: 7, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2014-06-20 16:13:48,226 DEBUG [cloud.api.ApiServlet] (catalina-exec-16:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251650608
2014-06-20 16:13:48,315 DEBUG [cloud.api.ApiServlet] (catalina-exec-16:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251650608
2014-06-20 16:13:48,347 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251650729
2014-06-20 16:13:48,363 DEBUG [cloud.api.ApiServlet] (catalina-exec-19:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251650745
2014-06-20 16:13:48,377 DEBUG [cloud.api.ApiServlet] (catalina-exec-19:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251650745
2014-06-20 16:13:48,535 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 8-1224933403: Received:  { Ans: , MgmtId: 73548179740843, via: 8, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2014-06-20 16:13:48,641 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 16-136380449: Received:  { Ans: , MgmtId: 73548179740843, via: 16, Ver: v1, Flags: 10, { GetVmStatsAnswer } }
2014-06-20 16:13:48,660 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-1:null) Ping from 33
2014-06-20 16:13:48,871 DEBUG [cloud.api.ApiServlet] (catalina-exec-11:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251651253
2014-06-20 16:13:48,986 DEBUG [cloud.api.ApiServlet] (catalina-exec-11:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=0564e5f8-f02b-4443-b710-db53d72d6264&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251651253
2014-06-20 16:13:49,017 DEBUG [cloud.api.ApiServlet] (catalina-exec-7:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251651400
2014-06-20 16:13:49,036 DEBUG [cloud.api.ApiServlet] (catalina-exec-21:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251651416
2014-06-20 16:13:49,053 DEBUG [cloud.api.ApiServlet] (catalina-exec-21:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251651416
2014-06-20 16:13:49,644 DEBUG [cloud.api.ApiServlet] (catalina-exec-9:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251650729
2014-06-20 16:13:50,209 DEBUG [cloud.api.ApiServlet] (catalina-exec-7:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251651400
2014-06-20 16:13:50,529 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-8:null) Ping from 31
2014-06-20 16:13:50,553 DEBUG [cloud.api.ApiServlet] (catalina-exec-10:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251652935
2014-06-20 16:13:50,620 DEBUG [cloud.api.ApiServlet] (catalina-exec-10:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251652935
2014-06-20 16:13:50,647 DEBUG [cloud.api.ApiServlet] (catalina-exec-14:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251653030
2014-06-20 16:13:50,661 DEBUG [cloud.api.ApiServlet] (catalina-exec-25:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251653044
2014-06-20 16:13:50,677 DEBUG [cloud.api.ApiServlet] (catalina-exec-25:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251653044
2014-06-20 16:13:51,689 DEBUG [cloud.api.ApiServlet] (catalina-exec-14:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251653030
2014-06-20 16:13:51,823 DEBUG [cloud.api.ApiServlet] (catalina-exec-18:null) ===START===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251654206
2014-06-20 16:13:51,910 DEBUG [cloud.api.ApiServlet] (catalina-exec-18:null) ===END===  10.11.12.250 -- GET  command=listTemplates&templatefilter=self&id=dafba763-b0fd-46b9-96f1-0cd16e7a924d&zoneid=32e0e2af-2637-4e93-8874-909f9f4636c1&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251654206
2014-06-20 16:13:51,939 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:null) ===START===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251654321
2014-06-20 16:13:51,955 DEBUG [cloud.api.ApiServlet] (catalina-exec-13:null) ===START===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251654337
2014-06-20 16:13:51,969 DEBUG [cloud.api.ApiServlet] (catalina-exec-13:null) ===END===  10.11.12.250 -- GET  command=listTags&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&resourceId=dafba763-b0fd-46b9-96f1-0cd16e7a924d&resourceType=Template&listAll=true&_=1403251654337
2014-06-20 16:13:52,968 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:null) ===END===  10.11.12.250 -- GET  command=listOsTypes&response=json&sessionkey=4ywv0lPZ4TAW4vVaq11ZOLHJGi0%3D&_=1403251654321
2014-06-20 16:13:55,830 DEBUG [agent.transport.Request] (Timer-6:null) Seq 31-1338376224: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:13:55,876 DEBUG [agent.transport.Request] (AgentManager-Handler-3:null) Seq 31-1338376224: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","downloadPct":2,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a5fd862f-5d3e-3c41-b912-3918446c341a/template/tmpl/2/264/dnld7559459646201869481tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:13:57,405 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860444: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:13:57,524 DEBUG [agent.transport.Request] (AgentManager-Handler-10:null) Seq 33-668860444: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":1,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:14:01,851 DEBUG [agent.transport.Request] (AgentManager-Handler-13:null) Seq 31-1338376196: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 110, [{"storage.ListTemplateAnswer":{"secUrl":"nfs://10.11.18.7/export/d2secondary","templateInfos":{"221-2-3706787c-d305-39e0-9680-9f84359df07a":{"templateName":"221-2-3706787c-d305-39e0-9680-9f84359df07a","installPath":"template/tmpl/2/221//221-2-3706787c-d305-39e0-9680-9f84359df07a.iso","size":45762560,"physicalSize":45762560,"id":221,"isPublic":true,"isCorrupted":false},"206-2-33e4a593-fa36-3fb6-b68e-a587a8b1af80":{"templateName":"206-2-33e4a593-fa36-3fb6-b68e-a587a8b1af80","installPath":"template/tmpl/2/206//206-2-33e4a593-fa36-3fb6-b68e-a587a8b1af80.iso","size":3548971164,"physicalSize":3548971164,"id":206,"isPublic":true,"isCorrupted":false},"a1e5cdd4-b892-45a0-aeb4-a337cc96b442":{"templateName":"a1e5cdd4-b892-45a0-aeb4-a337cc96b442","installPath":"template/tmpl/2/209//a1e5cdd4-b892-45a0-aeb4-a337cc96b442.qcow2","size":107374182400,"physicalSize":15100084224,"id":1,"isPublic":true,"isCorrupted":false},"202-2-c87fba71-77d0-3076-80cc-4116d7963807":{"templateName":"202-2-c87fba71-77d0-3076-80cc-4116d7963807","installPath":"template/tmpl/2/202//202-2-c87fba71-77d0-3076-80cc-4116d7963807.iso","size":3847520256,"physicalSize":3847520256,"id":202,"isPublic":true,"isCorrupted":false},"routing-3":{"templateName":"routing-3","installPath":"template/tmpl/1/3//3e38f707-5006-4d73-92a5-5be21b6f178f.qcow2","size":725811200,"physicalSize":725811200,"id":3,"isPublic":true,"isCorrupted":false},"5deebbfd-e603-4d09-912d-de5848d7118e":{"templateName":"5deebbfd-e603-4d09-912d-de5848d7118e","installPath":"template/tmpl/2/208//5deebbfd-e603-4d09-912d-de5848d7118e.qcow2","size":107374182400,"physicalSize":4641128448,"id":1,"isPublic":true,"isCorrupted":false},"205-2-5ffdab64-da2e-3afc-b166-061397e0de7c":{"templateName":"205-2-5ffdab64-da2e-3afc-b166-061397e0de7c","installPath":"template/tmpl/2/205//205-2-5ffdab64-da2e-3afc-b166-061397e0de7c.iso","size":4239566848,"physicalSize":4239566848,"id":205,"isPublic":true,"isCorrupted":false}},"result":true,"details":"success","wait":0}}] }
2014-06-20 16:14:01,851 DEBUG [agent.manager.AgentAttache] (AgentManager-Handler-13:null) Seq 31-1338376196: No more commands found
2014-06-20 16:14:01,852 DEBUG [agent.transport.Request] (AgentManager-Handler-9:null) Seq 31-1338376196: Received:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 110, { ListTemplateAnswer } }
2014-06-20 16:14:01,852 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Details from executing class com.cloud.agent.api.storage.ListTemplateCommand: success
2014-06-20 16:14:01,878 INFO  [storage.download.DownloadMonitorImpl] (AgentManager-Handler-9:null) Template Sync found routing-3 already in the template host table
2014-06-20 16:14:01,942 INFO  [storage.download.DownloadMonitorImpl] (AgentManager-Handler-9:null) Template Sync did not find centos55-x86_64 ready on server 2, will request download to start/resume shortly
2014-06-20 16:14:01,946 INFO  [storage.download.DownloadMonitorImpl] (AgentManager-Handler-9:null) Template Sync did not find centos56-x86_64-xen ready on server 2, will request download to start/resume shortly
2014-06-20 16:14:01,950 INFO  [storage.download.DownloadMonitorImpl] (AgentManager-Handler-9:null) Template Sync did not find centos53-x64 ready on server 2, will request download to start/resume shortly
2014-06-20 16:14:01,960 INFO  [storage.download.DownloadMonitorImpl] (AgentManager-Handler-9:null) Template Sync found 202-2-c87fba71-77d0-3076-80cc-4116d7963807 already in the template host table
2014-06-20 16:14:02,009 INFO  [storage.download.DownloadMonitorImpl] (AgentManager-Handler-9:null) Template Sync found 205-2-5ffdab64-da2e-3afc-b166-061397e0de7c already in the template host table
2014-06-20 16:14:02,063 INFO  [storage.download.DownloadMonitorImpl] (AgentManager-Handler-9:null) Template Sync found 206-2-33e4a593-fa36-3fb6-b68e-a587a8b1af80 already in the template host table
2014-06-20 16:14:02,125 INFO  [storage.download.DownloadMonitorImpl] (AgentManager-Handler-9:null) Template Sync found 5deebbfd-e603-4d09-912d-de5848d7118e already in the template host table
2014-06-20 16:14:02,173 INFO  [storage.download.DownloadMonitorImpl] (AgentManager-Handler-9:null) Template Sync found a1e5cdd4-b892-45a0-aeb4-a337cc96b442 already in the template host table
2014-06-20 16:14:02,221 INFO  [storage.download.DownloadMonitorImpl] (AgentManager-Handler-9:null) Template Sync found 221-2-3706787c-d305-39e0-9680-9f84359df07a already in the template host table
2014-06-20 16:14:02,269 INFO  [storage.download.DownloadMonitorImpl] (AgentManager-Handler-9:null) Template Sync did not find 264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438 ready on server 2, will request download to start/resume shortly
2014-06-20 16:14:02,283 DEBUG [storage.download.DownloadMonitorImpl] (AgentManager-Handler-9:null) Template CentOS 5.5(64-bit) no GUI (KVM) needs to be downloaded to nfs://10.11.18.7/export/d2secondary
2014-06-20 16:14:02,347 DEBUG [agent.transport.Request] (AgentManager-Handler-9:null) Seq 31-1338376225: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"946d111e-f311-4a44-9212-7cd29afae376","request":"GET_OR_RESTART","hvm":false,"description":"CentOS 5.5(64-bit) no GUI (KVM)","checksum":"ed0e788280ff2912ea40f7f91ca7a249","maxDownloadSizeInBytes":108447924224,"id":4,"resourceType":"TEMPLATE","url":"http://download.cloud.com/releases/2.2.0/eec2209b-9875-3c8d-92be-c001bd8a0faf.qcow2.bz2","format":"QCOW2","accountId":1,"name":"centos55-x86_64","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:14:02,352 DEBUG [storage.download.DownloadMonitorImpl] (AgentManager-Handler-9:null) Template wintest needs to be downloaded to nfs://10.11.18.7/export/d2secondary
2014-06-20 16:14:02,458 DEBUG [agent.transport.Request] (AgentManager-Handler-9:null) Seq 31-1338376226: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","request":"ABORT","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:14:02,463 DEBUG [agent.transport.Request] (AgentManager-Handler-9:null) Seq 31-1338376227: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","request":"GET_OR_RESTART","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:14:02,468 DEBUG [agent.transport.Request] (AgentManager-Handler-4:null) Seq 31-1338376227: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","downloadPct":2,"errorString":" ","downloadStatus":"NOT_DOWNLOADED","downloadPath":"/mnt/SecStorage/a5fd862f-5d3e-3c41-b912-3918446c341a/template/tmpl/2/264/dnld7559459646201869481tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:14:02,482 DEBUG [agent.transport.Request] (AgentManager-Handler-9:null) Seq 31-1338376228: Sending  { Cmd , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 100111, [{"storage.ListVolumeCommand":{"secUrl":"nfs://10.11.18.7/export/d2secondary","wait":0}}] }
2014-06-20 16:14:05,075 DEBUG [storage.download.DownloadListener] (Timer-7:null) Scheduling timeout at 30000 ms, template=wintest at host nfs://10.11.18.7/export/d3secondary
2014-06-20 16:14:05,505 DEBUG [agent.transport.Request] (AgentManager-Handler-14:null) Seq 31-1338376226: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"d4e853b4-9fc3-46a5-9a21-c8c7a7e8d095","downloadPct":2,"errorString":"Downloaded 287833050 bytes (incomplete download)","downloadStatus":"NOT_DOWNLOADED","downloadPath":"/mnt/SecStorage/a5fd862f-5d3e-3c41-b912-3918446c341a/template/tmpl/2/264/dnld7559459646201869481tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":"Downloaded 287833050 bytes (incomplete download)","wait":0}}] }
2014-06-20 16:14:05,731 DEBUG [agent.transport.Request] (AgentManager-Handler-11:null) Seq 31-1338376228: Processing:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 110, [{"storage.ListVolumeAnswer":{"secUrl":"nfs://10.11.18.7/export/d2secondary","templateInfos":{},"result":true,"details":"success","wait":0}}] }
2014-06-20 16:14:05,731 DEBUG [agent.manager.AgentAttache] (AgentManager-Handler-11:null) Seq 31-1338376228: No more commands found
2014-06-20 16:14:05,731 DEBUG [agent.transport.Request] (AgentManager-Handler-9:null) Seq 31-1338376228: Received:  { Ans: , MgmtId: 73548179740843, via: 31, Ver: v1, Flags: 110, { ListVolumeAnswer } }
2014-06-20 16:14:05,732 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Details from executing class com.cloud.agent.api.storage.ListVolumeCommand: success
2014-06-20 16:14:05,743 ERROR [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Monitor DownloadListener says there is an error in the connect process for 31 due to null
java.lang.NullPointerException
 at com.cloud.storage.download.DownloadMonitorImpl.handleVolumeSync(DownloadMonitorImpl.java:704)
 at com.cloud.storage.download.DownloadMonitorImpl.handleSync(DownloadMonitorImpl.java:630)
 at com.cloud.storage.download.DownloadListener.processConnect(DownloadListener.java:385)
 at com.cloud.agent.manager.AgentManagerImpl.notifyMonitorsOfConnection(AgentManagerImpl.java:605)
 at com.cloud.agent.manager.AgentManagerImpl.handleConnectedAgent(AgentManagerImpl.java:1157)
 at com.cloud.agent.manager.AgentManagerImpl.access$100(AgentManagerImpl.java:142)
 at com.cloud.agent.manager.AgentManagerImpl$AgentHandler.processRequest(AgentManagerImpl.java:1235)
 at com.cloud.agent.manager.AgentManagerImpl$AgentHandler.doTask(AgentManagerImpl.java:1374)
 at com.cloud.agent.manager.ClusteredAgentManagerImpl$ClusteredAgentHandler.doTask(ClusteredAgentManagerImpl.java:618)
 at com.cloud.utils.nio.Task.run(Task.java:83)
 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
 at java.lang.Thread.run(Thread.java:679)
2014-06-20 16:14:05,744 INFO  [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Host 31 is disconnecting with event AgentDisconnected
2014-06-20 16:14:05,748 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) The next status of agent 31is Alert, current status is Connecting
2014-06-20 16:14:05,749 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Deregistering link for 31 with state Alert
2014-06-20 16:14:05,749 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Remove Agent : 31
2014-06-20 16:14:05,749 DEBUG [agent.manager.ConnectedAgentAttache] (AgentManager-Handler-9:null) Processing Disconnect.
2014-06-20 16:14:05,751 DEBUG [agent.manager.AgentAttache] (AgentManager-Handler-9:null) Seq 31-1338376225: Sending disconnect to class com.cloud.storage.download.DownloadListener
2014-06-20 16:14:05,751 WARN  [storage.download.DownloadListener] (AgentManager-Handler-9:null) Unable to monitor download progress of CentOS 5.5(64-bit) no GUI (KVM) at host nfs://10.11.18.7/export/d2secondary
2014-06-20 16:14:05,793 WARN  [storage.download.DownloadListener] (AgentManager-Handler-9:null) Entering download error state because the storage host disconnected, template=CentOS 5.5(64-bit) no GUI (KVM) at host nfs://10.11.18.7/export/d2secondary
2014-06-20 16:14:05,799 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Sending Disconnect to listener: com.cloud.hypervisor.xen.discoverer.XcpServerDiscoverer$$EnhancerByCGLIB$$14360d27
2014-06-20 16:14:05,799 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Sending Disconnect to listener: com.cloud.hypervisor.vmware.manager.VmwareManagerImpl$$EnhancerByCGLIB$$46130a12
2014-06-20 16:14:05,800 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Sending Disconnect to listener: com.cloud.vm.ClusteredVirtualMachineManagerImpl$$EnhancerByCGLIB$$10fe97cc
2014-06-20 16:14:05,800 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Sending Disconnect to listener: com.cloud.network.security.SecurityGroupListener
2014-06-20 16:14:05,800 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Sending Disconnect to listener: com.cloud.storage.listener.StoragePoolMonitor
2014-06-20 16:14:05,801 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Sending Disconnect to listener: com.cloud.storage.secondary.SecondaryStorageListener
2014-06-20 16:14:05,801 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Sending Disconnect to listener: com.cloud.network.NetworkManagerImpl$$EnhancerByCGLIB$$babe76e8
2014-06-20 16:14:05,801 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Sending Disconnect to listener: com.cloud.agent.manager.AgentMonitor$$EnhancerByCGLIB$$2b5ef948
2014-06-20 16:14:05,801 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Sending Disconnect to listener: com.cloud.storage.download.DownloadListener
2014-06-20 16:14:05,802 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Sending Disconnect to listener: com.cloud.storage.upload.UploadListener
2014-06-20 16:14:05,803 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Sending Disconnect to listener: com.cloud.storage.LocalStoragePoolListener$$EnhancerByCGLIB$$bb4d1f7c
2014-06-20 16:14:05,803 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Sending Disconnect to listener: com.cloud.capacity.StorageCapacityListener
2014-06-20 16:14:05,803 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Sending Disconnect to listener: com.cloud.capacity.ComputeCapacityListener
2014-06-20 16:14:05,803 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Sending Disconnect to listener: com.cloud.consoleproxy.ConsoleProxyListener
2014-06-20 16:14:05,808 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Sending Disconnect to listener: com.cloud.network.SshKeysDistriMonitor
2014-06-20 16:14:05,808 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Sending Disconnect to listener: com.cloud.network.router.VpcVirtualNetworkApplianceManagerImpl$$EnhancerByCGLIB$$bdb79e70
2014-06-20 16:14:05,808 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Sending Disconnect to listener: com.cloud.network.NetworkUsageManagerImpl$DirectNetworkStatsListener
2014-06-20 16:14:05,808 DEBUG [cloud.network.NetworkUsageManagerImpl] (AgentManager-Handler-9:null) Disconnected called on 31 with status Alert
2014-06-20 16:14:05,809 DEBUG [cloud.host.Status] (AgentManager-Handler-9:null) Transition:[Resource state = Enabled, Agent event = AgentDisconnected, Host id = 31, name = s-797-VM]
2014-06-20 16:14:05,859 DEBUG [cloud.host.Status] (AgentManager-Handler-9:null) Agent status update: [id = 31; name = s-797-VM; old status = Connecting; event = AgentDisconnected; new status = Alert; old update count = 4218; new update count = 4219]
2014-06-20 16:14:05,859 DEBUG [agent.manager.ClusteredAgentManagerImpl] (AgentManager-Handler-9:null) Notifying other nodes of to disconnect
2014-06-20 16:14:05,869 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Can not send command com.cloud.agent.api.ReadyCommand due to Host 31 is not up
2014-06-20 16:14:05,870 WARN  [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Unable to create attache for agent: Seq 31-1334:  { Cmd , MgmtId: -1, via: 31, Ver: v1, Flags: 101, [{"StartupSecondaryStorageCommand":{"type":"SecondaryStorage","dataCenter":"1","pod":"1","guid":"s-797-VM-NfsSecondaryStorageResource","name":"s-797-VM","id":31,"version":"2.2.20130913151423","iqn":"NoIqn","publicIpAddress":"10.11.19.21","publicNetmask":"255.255.255.0","publicMacAddress":"06:0d:ca:00:00:29","privateIpAddress":"10.11.18.55","privateMacAddress":"06:b7:bc:00:00:05","privateNetmask":"255.255.255.0","storageIpAddress":"10.11.18.55","storageNetmask":"255.255.255.0","storageMacAddress":"06:b7:bc:00:00:05","resourceName":"NfsSecondaryStorageResource","wait":0}}] }
2014-06-20 16:14:07,531 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860445: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:14:07,876 DEBUG [agent.transport.Request] (AgentManager-Handler-12:null) Seq 33-668860445: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":1,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:14:12,528 DEBUG [storage.download.DownloadListener] (Timer-9:null) Send command failed
com.cloud.exception.AgentUnavailableException: Resource [Host:31] is unreachable: Host 31: Host with specified id is not in the right state: Alert
 at com.cloud.agent.manager.ClusteredAgentManagerImpl.getAttache(ClusteredAgentManagerImpl.java:498)
 at com.cloud.agent.manager.AgentManagerImpl.send(AgentManagerImpl.java:545)
 at com.cloud.storage.download.DownloadMonitorImpl.send(DownloadMonitorImpl.java:179)
 at com.cloud.storage.download.DownloadListener.sendCommand(DownloadListener.java:202)
 at com.cloud.storage.download.DownloadListener$StatusTask.run(DownloadListener.java:80)
 at java.util.TimerThread.mainLoop(Timer.java:534)
 at java.util.TimerThread.run(Timer.java:484)
2014-06-20 16:14:12,547 WARN  [storage.download.DownloadListener] (Timer-9:null) Unable to monitor download progress of wintest at host nfs://10.11.18.7/export/d2secondary
2014-06-20 16:14:12,595 WARN  [storage.download.DownloadListener] (Timer-9:null) Entering download error state because the storage host disconnected, template=wintest at host nfs://10.11.18.7/export/d2secondary
2014-06-20 16:14:12,972 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is ready to launch secondary storage VM
2014-06-20 16:14:13,010 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 2 is ready to launch secondary storage VM
2014-06-20 16:14:13,258 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is ready to launch console proxy
2014-06-20 16:14:13,277 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 2 is ready to launch console proxy
2014-06-20 16:14:13,941 DEBUG [storage.snapshot.SnapshotSchedulerImpl] (SnapshotPollTask:null) Snapshot scheduler.poll is being called at 2014-06-20 08:14:13 GMT
2014-06-20 16:14:13,945 DEBUG [storage.snapshot.SnapshotSchedulerImpl] (SnapshotPollTask:null) Got 0 snapshots to be executed at 2014-06-20 08:14:13 GMT
2014-06-20 16:14:14,897 DEBUG [cloud.server.StatsCollector] (StatsCollector-2:null) StorageCollector is running...
2014-06-20 16:14:14,910 DEBUG [cloud.server.StatsCollector] (StatsCollector-2:null) There is no secondary storage VM for secondary storage host nfs://10.11.18.7/export/d2secondary
2014-06-20 16:14:15,267 DEBUG [cloud.network.ExternalLoadBalancerUsageManagerImpl] (ExternalNetworkMonitor-1:null) External load balancer devices stats collector is running...
2014-06-20 16:14:15,394 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterMonitor-1:null) Found 0 running routers. 
2014-06-20 16:14:15,399 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 routers. 
2014-06-20 16:14:16,112 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 33-668860446: Received:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:14:16,290 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 1-2021720094: Received:  { Ans: , MgmtId: 73548179740843, via: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:14:16,497 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 8-1224933404: Received:  { Ans: , MgmtId: 73548179740843, via: 8, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:14:16,613 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 14-1005387800: Received:  { Ans: , MgmtId: 73548179740843, via: 14, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:14:16,648 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 15-710017046: Received:  { Ans: , MgmtId: 73548179740843, via: 15, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:14:16,814 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 16-136380450: Received:  { Ans: , MgmtId: 73548179740843, via: 16, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:14:16,976 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 1-2021720095: Received:  { Ans: , MgmtId: 73548179740843, via: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-06-20 16:14:17,883 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860447: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:14:18,011 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-10:null) Ping from 27
2014-06-20 16:14:18,178 DEBUG [agent.transport.Request] (AgentManager-Handler-13:null) Seq 33-668860447: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":2,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:14:18,529 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-4:null) Ping from 18
2014-06-20 16:14:18,669 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-14:null) Ping from 17
2014-06-20 16:14:19,142 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-11:null) Ping from 14
2014-06-20 16:14:19,433 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) Ping from 1
2014-06-20 16:14:19,601 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-12:null) Ping from 15
2014-06-20 16:14:19,769 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-5:null) Ping from 5
2014-06-20 16:14:20,070 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-15:null) Ping from 16
2014-06-20 16:14:20,186 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-7:null) Ping from 6
2014-06-20 16:14:20,322 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-6:null) Ping from 10
2014-06-20 16:14:20,554 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-1:null) Ping from 23
2014-06-20 16:14:21,166 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-8:null) Ping from 9
2014-06-20 16:14:21,245 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-3:null) Ping from 7
2014-06-20 16:14:22,242 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-10:null) Ping from 8
2014-06-20 16:14:28,185 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860448: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:14:28,331 DEBUG [agent.transport.Request] (AgentManager-Handler-13:null) Seq 33-668860448: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":2,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:14:35,075 DEBUG [storage.download.DownloadListener] (Timer-7:null) Scheduling timeout at 30000 ms, template=wintest at host nfs://10.11.18.7/export/d3secondary
2014-06-20 16:14:38,338 DEBUG [agent.transport.Request] (Timer-7:null) Seq 33-668860449: Sending  { Cmd , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 100011, [{"storage.DownloadProgressCommand":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","request":"GET_STATUS","hvm":true,"description":"wintest","maxDownloadSizeInBytes":108447924224,"id":264,"resourceType":"TEMPLATE","url":"http://10.11.18.212/WIN_ZHEDMKYA8EZ.qcow2","format":"QCOW2","accountId":2,"name":"264-2-4fc4c711-7104-34ec-b45d-0b7cc6246438","secUrl":"nfs://10.11.18.7/export/d3secondary","wait":0}}] }
2014-06-20 16:14:38,344 DEBUG [agent.transport.Request] (AgentManager-Handler-4:null) Seq 33-668860449: Processing:  { Ans: , MgmtId: 73548179740843, via: 33, Ver: v1, Flags: 10, [{"storage.DownloadAnswer":{"jobId":"b658ea5e-88fa-483c-b6a7-26505c00c64f","downloadPct":2,"errorString":" ","downloadStatus":"DOWNLOAD_IN_PROGRESS","downloadPath":"/mnt/SecStorage/a35b9ab9-d061-3ed7-bbe7-82022eec78e4/template/tmpl/2/264/dnld2364495501466107451tmp_","templateSize":0,"templatePhySicalSize":0,"result":false,"details":" ","wait":0}}] }
2014-06-20 16:14:42,973 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is ready to launch secondary storage VM
2014-06-20 16:14:43,013 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 2 is ready to launch secondary storage VM
2014-06-20 16:14:43,257 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is ready to launch console proxy
2014-06-20 16:14:43,276 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 2 is ready to launch console proxy
2014-06-20 16:14:43,757 DEBUG [cloud.server.StatsCollector] (StatsCollector-2:null) HostStatsCollector is running...
2014-06-20 16:14:43,912 DEBUG [cloud.alert.AlertManagerImpl] (CapacityChecker:null) Running Capacity Checker ... 
2014-06-20 16:14:43,912 DEBUG [cloud.alert.AlertManagerImpl] (CapacityChecker:null) recalculating system capacity
2014-06-20 16:14:43,912 DEBUG [cloud.alert.AlertManagerImpl] (CapacityChecker:null) Executing cpu/ram capacity update
2014-06-20 16:14:43,935 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) Found 5 VMs on host 1
2014-06-20 16:14:43,941 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) Found 1 VM, not running on host 1
2014-06-20 16:14:43,949 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) No need to calibrate cpu capacity, host:1 usedCpu: 43200 reservedCpu: 0
2014-06-20 16:14:43,950 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) No need to calibrate memory capacity, host:1 usedMem: 21474836480 reservedMem: 0
2014-06-20 16:14:43,968 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) Found 7 VMs on host 5
2014-06-20 16:14:43,975 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) Found 2 VM, not running on host 5
2014-06-20 16:14:43,983 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) No need to calibrate cpu capacity, host:5 usedCpu: 67200 reservedCpu: 0
2014-06-20 16:14:43,983 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) No need to calibrate memory capacity, host:5 usedMem: 34359738368 reservedMem: 0
2014-06-20 16:14:44,000 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) Found 6 VMs on host 6
2014-06-20 16:14:44,006 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) Found 1 VM, not running on host 6
2014-06-20 16:14:44,013 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) No need to calibrate cpu capacity, host:6 usedCpu: 67200 reservedCpu: 0
2014-06-20 16:14:44,013 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) No need to calibrate memory capacity, host:6 usedMem: 47244640256 reservedMem: 0
2014-06-20 16:14:44,030 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) Found 6 VMs on host 7
2014-06-20 16:14:44,037 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) Found 2 VM, not running on host 7
2014-06-20 16:14:44,045 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) No need to calibrate cpu capacity, host:7 usedCpu: 56000 reservedCpu: 0
2014-06-20 16:14:44,045 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) No need to calibrate memory capacity, host:7 usedMem: 30064771072 reservedMem: 0
2014-06-20 16:14:44,065 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) Found 9 VMs on host 8
2014-06-20 16:14:44,070 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) Found 0 VM, not running on host 8
2014-06-20 16:14:44,077 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) No need to calibrate cpu capacity, host:8 usedCpu: 57000 reservedCpu: 0
2014-06-20 16:14:44,078 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) No need to calibrate memory capacity, host:8 usedMem: 31272730624 reservedMem: 0
2014-06-20 16:14:44,089 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) Found 1 VMs on host 9
2014-06-20 16:14:44,092 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) Found 0 VM, not running on host 9
2014-06-20 16:14:44,099 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) No need to calibrate cpu capacity, host:9 usedCpu: 500 reservedCpu: 0
2014-06-20 16:14:44,100 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) No need to calibrate memory capacity, host:9 usedMem: 268435456 reservedMem: 0
2014-06-20 16:14:44,111 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) Found 1 VMs on host 10
2014-06-20 16:14:44,114 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) Found 0 VM, not running on host 10
2014-06-20 16:14:44,121 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) No need to calibrate cpu capacity, host:10 usedCpu: 500 reservedCpu: 0
2014-06-20 16:14:44,121 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) No need to calibrate memory capacity, host:10 usedMem: 268435456 reservedMem: 0
2014-06-20 16:14:44,131 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) Found 0 VMs on host 14
2014-06-20 16:14:44,134 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) Found 0 VM, not running on host 14
2014-06-20 16:14:44,141 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) No need to calibrate cpu capacity, host:14 usedCpu: 0 reservedCpu: 0
2014-06-20 16:14:44,141 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) No need to calibrate memory capacity, host:14 usedMem: 0 reservedMem: 0
2014-06-20 16:14:44,151 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) Found 0 VMs on host 15
2014-06-20 16:14:44,154 DEBUG [cloud.capacity.CapacityManagerImpl] (CapacityChecker:null) Found 0 VM, not running on host 15
Mime
  • Unnamed multipart/related (inline, None, 0 bytes)
View raw message