Return-Path: X-Original-To: apmail-cloudstack-issues-archive@www.apache.org Delivered-To: apmail-cloudstack-issues-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 5F7091066F for ; Tue, 17 Sep 2013 09:54:57 +0000 (UTC) Received: (qmail 31865 invoked by uid 500); 17 Sep 2013 09:54:56 -0000 Delivered-To: apmail-cloudstack-issues-archive@cloudstack.apache.org Received: (qmail 31848 invoked by uid 500); 17 Sep 2013 09:54:56 -0000 Mailing-List: contact issues-help@cloudstack.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@cloudstack.apache.org Delivered-To: mailing list issues@cloudstack.apache.org Received: (qmail 31832 invoked by uid 500); 17 Sep 2013 09:54:53 -0000 Delivered-To: apmail-incubator-cloudstack-issues@incubator.apache.org Received: (qmail 31815 invoked by uid 99); 17 Sep 2013 09:54:51 -0000 Received: from arcas.apache.org (HELO arcas.apache.org) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 17 Sep 2013 09:54:51 +0000 Date: Tue, 17 Sep 2013 09:54:51 +0000 (UTC) From: "prashant kumar mishra (JIRA)" To: cloudstack-issues@incubator.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Commented] (CLOUDSTACK-4641) Create volume form snapshot command times out exactly after 1 hour in case of KVM hosts. MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 7bit X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 [ https://issues.apache.org/jira/browse/CLOUDSTACK-4641?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13769377#comment-13769377 ] prashant kumar mishra commented on CLOUDSTACK-4641: --------------------------------------------------- Tested on top of commit 5e52a86a2ac8783141c99e8ab34a96fb21b8826a Log ------ 2013-09-17 07:28:39,087 DEBUG [cloud.api.ApiServlet] (catalina-exec-10:null) ===START=== 10.252.192.86 -- GET command=createVolume&response=json&sessionkey=fP7mNpuqEmQhAS2IgT8UbBXHOwI%3D&snapshotid=40cf4a09-ff00-4caa-87ab-0df36e2fbea9&name=timeout&_=1379397971935 2013-09-17 07:28:39,473 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-10:null) submit async job-53 = [ 620f6a05-14f0-4906-b113-91e881029cdc ], details: AsyncJobVO {id:53, userId: 2, accountId: 2, sessionKey: null, instanceType: Volume, instanceId: 27, cmd: org.apache.cloudstack.api.command.user.volume.CreateVolumeCmd, cmdOriginator: null, cmdInfo: {"id":"27","response":"json","sessionkey":"fP7mNpuqEmQhAS2IgT8UbBXHOwI\u003d","cmdEventType":"VOLUME.CREATE","ctxUserId":"2","snapshotid":"40cf4a09-ff00-4caa-87ab-0df36e2fbea9","name":"timeout","httpmethod":"GET","_":"1379397971935","ctxAccountId":"2","ctxStartEventId":"221"}, cmdVersion: 0, callbackType: 0, callbackAddress: null, status: 0, processStatus: 0, resultCode: 0, result: null, initMsid: 6895939092538, completeMsid: null, lastUpdated: null, lastPolled: null, created: null} 2013-09-17 07:28:39,478 DEBUG [cloud.api.ApiServlet] (catalina-exec-10:null) ===END=== 10.252.192.86 -- GET command=createVolume&response=json&sessionkey=fP7mNpuqEmQhAS2IgT8UbBXHOwI%3D&snapshotid=40cf4a09-ff00-4caa-87ab-0df36e2fbea9&name=timeout&_=1379397971935 2013-09-17 07:28:39,481 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-15:job-53 = [ 620f6a05-14f0-4906-b113-91e881029cdc ]) Executing org.apache.cloudstack.api.command.user.volume.CreateVolumeCmd for job-53 = [ 620f6a05-14f0-4906-b113-91e881029cdc ] 2013-09-17 07:28:39,714 DEBUG [storage.allocator.LocalStoragePoolAllocator] (Job-Executor-15:job-53 = [ 620f6a05-14f0-4906-b113-91e881029cdc ]) LocalStoragePoolAllocator trying to find storage pool to fit the vm 2013-09-17 07:28:39,719 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (Job-Executor-15:job-53 = [ 620f6a05-14f0-4906-b113-91e881029cdc ]) Checking if storage pool is suitable, name: null ,poolId: 2 2013-09-17 07:28:39,724 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-15:job-53 = [ 620f6a05-14f0-4906-b113-91e881029cdc ]) Checking pool 2 for storage, totalSize: 52844687360, usedBytes: 8709701632, usedPct: 0.16481697720465047, disable threshold: 1.0 2013-09-17 07:28:39,734 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-15:job-53 = [ 620f6a05-14f0-4906-b113-91e881029cdc ]) Checking pool: 2 for volume allocation [Vol[27|vm=null|DATADISK]], maxSize : 52844687360, totalAllocatedSize : 8589934592, askingSize : 322122547200, allocated disable threshold: 1.0 2013-09-17 07:28:39,735 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-15:job-53 = [ 620f6a05-14f0-4906-b113-91e881029cdc ]) Insufficient un-allocated capacity on: 2 for volume allocation: [Vol[27|vm=null|DATADISK]] since its allocated percentage: 6.258197338533748 has crossed the allocated pool.storage.allocated.capacity.disablethreshold: 1.0, skipping this pool 2013-09-17 07:28:39,737 DEBUG [storage.allocator.LocalStoragePoolAllocator] (Job-Executor-15:job-53 = [ 620f6a05-14f0-4906-b113-91e881029cdc ]) LocalStoragePoolAllocator returning 0 suitable storage pools 2013-09-17 07:28:39,737 DEBUG [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-15:job-53 = [ 620f6a05-14f0-4906-b113-91e881029cdc ]) ClusterScopeStoragePoolAllocator looking for storage pool 2013-09-17 07:28:39,737 DEBUG [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-15:job-53 = [ 620f6a05-14f0-4906-b113-91e881029cdc ]) Looking for pools in dc: 1 pod:1 cluster:null 2013-09-17 07:28:39,741 DEBUG [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-15:job-53 = [ 620f6a05-14f0-4906-b113-91e881029cdc ]) No storage pools available for local volume allocation, returning 2013-09-17 07:28:39,742 DEBUG [storage.allocator.ZoneWideStoragePoolAllocator] (Job-Executor-15:job-53 = [ 620f6a05-14f0-4906-b113-91e881029cdc ]) ZoneWideStoragePoolAllocator to find storage pool 2013-09-17 10:28:40,842 DEBUG [agent.transport.Request] (Job-Executor-15:job-53 = [ 620f6a05-14f0-4906-b113-91e881029cdc ]) Seq 1-1699480600: Received: { Ans: , MgmtId: 6895939092538, via: 1, Ver: v1, Flags: 110, { CopyCmdAnswer } } 2013-09-17 10:28:40,847 DEBUG [agent.manager.AgentAttache] (AgentManager-Handler-12:null) Seq 1-1699480600: No more commands found 2013-09-17 10:28:40,933 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-15:job-53 = [ 620f6a05-14f0-4906-b113-91e881029cdc ]) Complete async job-53 = [ 620f6a05-14f0-4906-b113-91e881029cdc ], jobStatus: 1, resultCode: 0, result: org.apache.cloudstack.api.response.VolumeResponse@503345a2 2013-09-17 10:28:40,997 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-15:job-53 = [ 620f6a05-14f0-4906-b113-91e881029cdc ]) Done executing org.apache.cloudstack.api.command.user.volume.CreateVolumeCmd for job-53 = [ 620f6a05-14f0-4906-b113-91e881029cdc ] 2013-09-17 10:28:41,019 DEBUG [cloud.api.ApiServlet] (catalina-exec-21:null) ===START=== 10.252.192.86 -- GET command=queryAsyncJobResult&jobId=620f6a05-14f0-4906-b113-91e881029cdc&response=json&sessionkey=fP7mNpuqEmQhAS2IgT8UbBXHOwI%3D&_=1379408774322 2013-09-17 10:28:41,037 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-21:null) Async job-53 = [ 620f6a05-14f0-4906-b113-91e881029cdc ] completed > Create volume form snapshot command times out exactly after 1 hour in case of KVM hosts. > ---------------------------------------------------------------------------------------- > > Key: CLOUDSTACK-4641 > URL: https://issues.apache.org/jira/browse/CLOUDSTACK-4641 > Project: CloudStack > Issue Type: Bug > Security Level: Public(Anyone can view this level - this is the default.) > Components: KVM > Affects Versions: 4.2.0 > Reporter: edison su > Assignee: edison su > Priority: Critical > > KVMStoragePoolManager->copyPhysicalDisk doesn't use timeout parameter passed from mgt server during the copy physical disk -- This message is automatically generated by JIRA. If you think it was sent incorrectly, please contact your JIRA administrators For more information on JIRA, see: http://www.atlassian.com/software/jira