[
https://issues.apache.org/jira/browse/CLOUDSTACK-7237?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=14086517#comment-14086517
]
Alena Prokharchyk commented on CLOUDSTACK-7237:
-----------------------------------------------
Shweta, can you please attach management server log file to the bug?
> template sync unable to find already downloaded template after restarting MS
> -----------------------------------------------------------------------------
>
> Key: CLOUDSTACK-7237
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-7237
> Project: CloudStack
> Issue Type: Bug
> Security Level: Public(Anyone can view this level - this is the
> default.)
> Components: Template
> Affects Versions: 4.5.0
> Reporter: shweta agarwal
> Assignee: Kishan Kavala
> Priority: Blocker
> Fix For: 4.5.0
>
>
> Repro steps:
> 1. created an advance zone
> 2. Register a template
> 3. Wait till download is complete and template in ready state
> 4. Restart MS
> 5. wait for template sync to happen
> Bug:
> template sync says Template Sync did not find
> 206-2-832d2ef8-9355-3958-9331-0847b502ad2a on image store 1, may request
> download based on available hypervisor types
> MS log to show template 206-2-832d2ef8-9355-3958-9331-0847b502ad2a was
> downloaded successfully before restarting MS
> 2014-08-04 08:02:50,676 DEBUG [o.a.c.s.i.TemplateDataFactoryImpl]
> (Work-Job-Executor-1:ctx-2494f7c7 job-121/job-122 ctx-7f2fe1fe) template 206
> is already in store:1, type:Primary
> 2014-08-04 08:02:50,677 DEBUG [o.a.c.s.v.VolumeServiceImpl]
> (Work-Job-Executor-1:ctx-2494f7c7 job-121/job-122 ctx-7f2fe1fe) Found
> template 206-2-832d2ef8-9355-3958-9331-0847b502ad2a in storage pool 1 with
> VMTemplateStoragePool id: 7
> 2014-08-04 08:02:50,686 DEBUG [o.a.c.s.v.VolumeServiceImpl]
> (Work-Job-Executor-1:ctx-2494f7c7 job-121/job-122 ctx-7f2fe1fe) Acquire lock
> on VMTemplateStoragePool 7 with timeout 3600 seconds
> 2014-08-04 08:02:50,688 INFO [o.a.c.s.v.VolumeServiceImpl]
> (Work-Job-Executor-1:ctx-2494f7c7 job-121/job-122 ctx-7f2fe1fe) lock is
> acquired for VMTemplateStoragePool 7
> 2014-08-04 08:02:50,776 DEBUG [o.a.c.s.m.AncientDataMotionStrategy]
> (Work-Job-Executor-1:ctx-2494f7c7 job-121/job-122 ctx-7f2fe1fe) copyAsync
> inspecting src type TEMPLATE copyAsync inspecting dest type TEMPLATE
> 2014-08-04 08:02:50,790 DEBUG [c.c.a.t.Request]
> (Work-Job-Executor-1:ctx-2494f7c7 job-121/job-122 ctx-7f2fe1fe) Seq
> 1-7372392590005502037: Sending { Cmd , MgmtId: 233845177509765, via:
> 1(Rack1Pod1Host23), Ver: v1, Flags: 100011,
> [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/2/206/2dd5a66f-96a8-3871-bce4-748777aa6529.tar","origUrl":"http://10.147.28.7/templates/lxc-templates/debian.tar.gz","uuid":"005df498-a1fe-4f69-ac27-53a762891389","id":206,"format":"TAR","accountId":2,"checksum":"6d2578f600440f302425839978220b32","hvm":false,"displayText":"deb-final","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.147.28.7/export/home/shweta/goleta.lxc.secondary","_role":"Image"}},"name":"206-2-832d2ef8-9355-3958-9331-0847b502ad2a","hypervisorType":"LXC"}},"destTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"origUrl":"http://10.147.28.7/templates/lxc-templates/debian.tar.gz","uuid":"005df498-a1fe-4f69-ac27-53a762891389","id":206,"format":"TAR","accountId":2,"checksum":"6d2578f600440f302425839978220b32","hvm":false,"displayText":"deb-final","imageDataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"dfa2ec3c-d133-3284-8583-0a0845aa4424","id":1,"poolType":"NetworkFilesystem","host":"10.147.28.7","path":"/export/home/shweta/goleta.lxc.primary","port":2049,"url":"NetworkFilesystem://10.147.28.7/export/home/shweta/goleta.lxc.primary/?ROLE=Primary&STOREUUID=dfa2ec3c-d133-3284-8583-0a0845aa4424"}},"name":"206-2-832d2ef8-9355-3958-9331-0847b502ad2a","hypervisorType":"LXC"}},"executeInSequence":false,"options":{},"wait":10800}}]
> }
> 2014-08-04 08:02:51,092 DEBUG [c.c.a.t.Request] (AgentManager-Handler-1:null)
> Seq 1-7372392590005502037: Processing: { Ans: , MgmtId: 233845177509765,
> via: 1, Ver: v1, Flags: 10,
> [{"org.apache.cloudstack.storage.command.CopyCmdAnswer":{"newData":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"005df498-a1fe-4f69-ac27
> .
> .
> .
> .
> .
> after restart MS log shows
> 2014-08-04 08:07:20,606 INFO [o.a.c.s.i.TemplateServiceImpl]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Template Sync did not find routing-8 on
> image store 1, may request download based on available hypervisor types
> 2014-08-04 08:07:20,606 INFO [o.a.c.s.i.TemplateServiceImpl]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Template Sync did not find routing-9 on
> image store 1, may request download based on available hypervisor types
> 2014-08-04 08:07:20,607 INFO [o.a.c.s.i.TemplateServiceImpl]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Template Sync found routing-10 already
> in the image store
> 2014-08-04 08:07:20,694 INFO [o.a.c.s.i.TemplateServiceImpl]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Template Sync did not find
> 206-2-832d2ef8-9355-3958-9331-0847b502ad2a on image store 1, may request
> download based on available hypervisor types
> 2014-08-04 08:07:20,695 INFO [o.a.c.s.i.TemplateServiceImpl]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Removing leftover template
> 206-2-832d2ef8-9355-3958-9331-0847b502ad2a entry from template store table
> 2014-08-04 08:07:20,744 INFO [o.a.c.s.i.TemplateServiceImpl]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Skip downloading template centos64-x64
> since current data center does not have hypervisor Hyperv
> 2014-08-04 08:07:20,744 INFO [o.a.c.s.i.TemplateServiceImpl]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Skip sync downloading private template
> 206-2-832d2ef8-9355-3958-9331-0847b502ad2a to a new image store
> 2014-08-04 08:07:20,744 INFO [o.a.c.s.i.TemplateServiceImpl]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Skip downloading template routing-9
> since current data center does not have hypervisor Hyperv
> 2014-08-04 08:07:20,744 INFO [o.a.c.s.i.TemplateServiceImpl]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Skip downloading template routing-8
> since current data center does not have hypervisor VMware
> 2014-08-04 08:07:20,745 INFO [o.a.c.s.i.TemplateServiceImpl]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Skip downloading template routing-3
> since current data center does not have hypervisor KVM
> 2014-08-04 08:07:20,745 INFO [o.a.c.s.i.TemplateServiceImpl]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Skip downloading template centos53-x64
> since current data center does not have hypervisor VMware
> 2014-08-04 08:07:20,745 INFO [o.a.c.s.i.TemplateServiceImpl]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Skip downloading template routing-1
> since current data center does not have hypervisor XenServer
> 2014-08-04 08:07:20,745 INFO [o.a.c.s.i.TemplateServiceImpl]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Skip downloading template
> centos56-x86_64-xen since current data center does not have hypervisor
> XenServer
> 2014-08-04 08:07:20,745 INFO [o.a.c.s.i.TemplateServiceImpl]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Skip downloading template
> centos55-x86_64 since current data center does not have hypervisor KVM
> 2014-08-04 08:07:20,745 DEBUG [c.c.a.m.AgentManagerImpl]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Sending Connect to listener:
> SshKeysDistriMonitor
> 2014-08-04 08:07:20,745 DEBUG [c.c.a.m.AgentManagerImpl]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Sending Connect to listener:
> VpcVirtualNetworkApplianceManagerImpl
> 2014-08-04 08:07:20,747 DEBUG [c.c.a.m.AgentManagerImpl]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Sending Connect to listener:
> SshKeysDistriMonitor
> 2014-08-04 08:07:20,747 DEBUG [c.c.a.m.AgentManagerImpl]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Sending Connect to listener:
> VirtualNetworkApplianceManagerImpl
> 2014-08-04 08:07:20,755 DEBUG [c.c.a.t.Request]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Seq 3-847802629852495879: Sending {
> Cmd , MgmtId: 233845177509765, via: 3(s-2-VM), Ver: v1, Flags: 100111,
> [{"com.cloud.agent.api.ReadyCommand":{"dcId":1,"hostId":3,"wait":0}}] }
> 2014-08-04 08:07:20,762 DEBUG [c.c.a.t.Request]
> (AgentManager-Handler-11:null) Seq 3-847802629852495879: Processing: { Ans:
> , MgmtId: 233845177509765, via: 3, Ver: v1, Flags: 110,
> [{"com.cloud.agent.api.ReadyAnswer":{"result":true,"wait":0}}] }
> 2014-08-04 08:07:20,762 DEBUG [c.c.a.m.AgentAttache]
> (AgentManager-Handler-11:null) Seq 3-847802629852495879: No more commands
> found
> 2014-08-04 08:07:20,762 DEBUG [c.c.a.t.Request]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Seq 3-847802629852495879: Received: {
> Ans: , MgmtId: 233845177509765, via: 3, Ver: v1, Flags: 110, { ReadyAnswer } }
> 2014-08-04 08:07:20,762 DEBUG [c.c.h.Status]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Transition:[Resource state = Enabled,
> Agent event = Ready, Host id = 3, name = s-2-VM]
> 2014-08-04 08:07:20,817 DEBUG [c.c.a.t.Request]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Seq 3-847802629852495880: Sending {
> Cmd , MgmtId: 233845177509765, via: 3(s-2-VM), Ver: v1, Flags: 100111,
> [{"com.cloud.agent.api.ReadyCommand":{"dcId":1,"hostId":3,"wait":0}}] }
> 2014-08-04 08:07:20,866 DEBUG [c.c.a.t.Request]
> (AgentManager-Handler-13:null) Seq 3-847802629852495880: Processing: { Ans:
> , MgmtId: 233845177509765, via: 3, Ver: v1, Flags: 110,
> [{"com.cloud.agent.api.ReadyAnswer":{"result":true,"wait":0}}] }
> 2014-08-04 08:07:20,866 DEBUG [c.c.a.m.AgentAttache]
> (AgentManager-Handler-13:null) Seq 3-847802629852495880: No more commands
> found
> 2014-08-04 08:07:20,866 DEBUG [c.c.a.t.Request]
> (AgentConnectTaskPool-6:ctx-5d641ba5) Seq 3-847802629852495880: Received: {
> Ans: , MgmtId: 2338
--
This message was sent by Atlassian JIRA
(v6.2#6252)
