Attachment 917878 Details for Bug 1119325 – engine.log

engine.log

engine.log (text/plain), 30.70 KB, created by Moti Asayag on 2014-07-14 14:13:28 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Moti Asayag

Created: 2014-07-14 14:13:28 UTC

Size: 30.70 KB

patch

obsolete

>	at org.ovirt.engine.core.bll.GetImagesListQueryBase.getUserRequestForStorageDomainRepoFileList(GetImagesListQueryBase.java:28) [bll.jar:]
>	at org.ovirt.engine.core.bll.GetImagesListQueryBase.executeQueryCommand(GetImagesListQueryBase.java:19) [bll.jar:]
>	at org.ovirt.engine.core.bll.QueriesCommandBase.executeCommand(QueriesCommandBase.java:73) [bll.jar:]
>	at org.ovirt.engine.core.dal.VdcCommandBase.execute(VdcCommandBase.java:31) [dal.jar:]
>	at org.ovirt.engine.core.bll.Backend.runQueryImpl(Backend.java:492) [bll.jar:]
>	at org.ovirt.engine.core.bll.Backend.runQuery(Backend.java:466) [bll.jar:]
>	at sun.reflect.GeneratedMethodAccessor26.invoke(Unknown Source) [:1.7.0_60]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_60]
>	at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_60]
>	at org.jboss.as.ee.component.ManagedReferenceMethodInterceptorFactory$ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptorFactory.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:374) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.delegateInterception(Jsr299BindingsInterceptor.java:114) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final]
>	at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:125) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final]
>	at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:135) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final]
>	at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:374) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.ovirt.engine.core.bll.interceptors.ThreadLocalSessionCleanerInterceptor.injectWebContextToThreadLocal(ThreadLocalSessionCleanerInterceptor.java:13) [bll.jar:]
>	at sun.reflect.GeneratedMethodAccessor25.invoke(Unknown Source) [:1.7.0_60]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_60]
>	at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_60]
>	at org.jboss.as.ee.component.ManagedReferenceLifecycleMethodInterceptorFactory$ManagedReferenceLifecycleMethodInterceptor.processInvocation(ManagedReferenceLifecycleMethodInterceptorFactory.java:123) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:82) [jboss-as-weld-7.1.1.Final.jar:7.1.1.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:211) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
>	at org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:363) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
>	at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:194) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.jboss.as.ee.component.TCCLInterceptor.processInvocation(TCCLInterceptor.java:45) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:165) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
>	at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:173) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
>	at org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
>	at org.ovirt.engine.core.common.interfaces.BackendLocal$$$view8.runQuery(Unknown Source) [common.jar:]
>	at org.ovirt.engine.ui.frontend.server.gwt.GenericApiGWTServiceImpl.runQuery(GenericApiGWTServiceImpl.java:56)
>	at sun.reflect.GeneratedMethodAccessor68.invoke(Unknown Source) [:1.7.0_60]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_60]
>	at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_60]
>	at com.google.gwt.rpc.server.RPC.invokeAndStreamResponse(RPC.java:196)
>	at com.google.gwt.rpc.server.RpcServlet.processCall(RpcServlet.java:172)
>	at com.google.gwt.rpc.server.RpcServlet.processPost(RpcServlet.java:233)
>	at com.google.gwt.user.server.rpc.AbstractRemoteServiceServlet.doPost(AbstractRemoteServiceServlet.java:62)
>	at javax.servlet.http.HttpServlet.service(HttpServlet.java:754) [jboss-servlet-api_3.0_spec-1.0.0.Final.jar:1.0.0.Final]
>	at javax.servlet.http.HttpServlet.service(HttpServlet.java:847) [jboss-servlet-api_3.0_spec-1.0.0.Final.jar:1.0.0.Final]
>	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:329)
>	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
>	at org.ovirt.engine.core.utils.servlet.HeaderFilter.doFilter(HeaderFilter.java:94) [utils.jar:]
>	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
>	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
>	at org.ovirt.engine.ui.frontend.server.gwt.GwtCachingFilter.doFilter(GwtCachingFilter.java:132)
>	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
>	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
>	at org.ovirt.engine.core.branding.BrandingFilter.doFilter(BrandingFilter.java:72)
>	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
>	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
>	at org.ovirt.engine.core.utils.servlet.LocaleFilter.doFilter(LocaleFilter.java:64) [utils.jar:]
>	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
>	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
>	at org.ovirt.engine.core.aaa.filters.SessionMgmtFilter.doFilter(SessionMgmtFilter.java:31) [aaa.jar:]
>	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
>	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
>	at org.ovirt.engine.core.aaa.filters.LoginFilter.doFilter(LoginFilter.java:73) [aaa.jar:]
>	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
>	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
>	at org.ovirt.engine.core.aaa.filters.NegotiationFilter.doFilter(NegotiationFilter.java:104) [aaa.jar:]
>	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
>	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
>	at org.ovirt.engine.core.aaa.filters.BasicAuthenticationFilter.doFilter(BasicAuthenticationFilter.java:75) [aaa.jar:]
>	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
>	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
>	at org.ovirt.engine.core.aaa.filters.SessionValidationFilter.doFilter(SessionValidationFilter.java:63) [aaa.jar:]
>	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
>	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
>	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:275)
>	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:161)
>	at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:489)
>	at org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:153)
>	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:155)
>	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
>	at org.jboss.web.rewrite.RewriteValve.invoke(RewriteValve.java:466)
>	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
>	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:368)
>	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:877)
>	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:671)
>	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:930)
>	at java.lang.Thread.run(Thread.java:745) [rt.jar:1.7.0_60]
>Caused by: java.net.NoRouteToHostException: No route to host
>	at java.net.PlainSocketImpl.socketConnect(Native Method) [rt.jar:1.7.0_60]
>	at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:339) [rt.jar:1.7.0_60]
>	at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:200) [rt.jar:1.7.0_60]
>	at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:182) [rt.jar:1.7.0_60]
>	at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) [rt.jar:1.7.0_60]
>	at java.net.Socket.connect(Socket.java:579) [rt.jar:1.7.0_60]
>	at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:123) [httpclient-4.1.2.jar:4.1.2]
>	at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:148) [httpclient-4.1.2.jar:4.1.2]
>	at org.apache.http.impl.conn.AbstractPoolEntry.open(AbstractPoolEntry.java:149) [httpclient-4.1.2.jar:4.1.2]
>	at org.apache.http.impl.conn.AbstractPooledConnAdapter.open(AbstractPooledConnAdapter.java:121) [httpclient-4.1.2.jar:4.1.2]
>	at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:573) [httpclient-4.1.2.jar:4.1.2]
>	at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:425) [httpclient-4.1.2.jar:4.1.2]
>	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:820) [httpclient-4.1.2.jar:4.1.2]
>	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:754) [httpclient-4.1.2.jar:4.1.2]
>	at org.jboss.resteasy.client.core.executors.ApacheHttpClient4Executor.execute(ApacheHttpClient4Executor.java:109) [resteasy-jaxrs-2.3.2.Final.jar:]
>	at org.jboss.resteasy.client.ClientRequest.execute(ClientRequest.java:437) [resteasy-jaxrs-2.3.2.Final.jar:]
>	at org.jboss.resteasy.client.ClientRequest.httpMethod(ClientRequest.java:674) [resteasy-jaxrs-2.3.2.Final.jar:]
>	at org.jboss.resteasy.client.ClientRequest.httpMethod(ClientRequest.java:680) [resteasy-jaxrs-2.3.2.Final.jar:]
>	at com.woorea.openstack.connector.RESTEasyConnector.request(RESTEasyConnector.java:103) [resteasy-connector.jar:]
>	... 123 more
>
>2014-07-14 16:23:53,784 INFO  [org.ovirt.engine.core.bll.provider.TestProviderConnectivityCommand] (http--0.0.0.0-8080-2) [248e8cd4] Running command: TestProviderConnectivityCommand internal: false. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>2014-07-14 16:23:55,973 INFO  [org.ovirt.engine.core.bll.provider.UpdateProviderCommand] (http--0.0.0.0-8080-1) [28357c89] Running command: UpdateProviderCommand internal: false. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>2014-07-14 16:23:56,011 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) [28357c89] Correlation ID: 28357c89, Call Stack: null, Custom Event ID: -1, Message: Provider ovirt-image-repository was updated. (User: admin)
>2014-07-14 16:24:06,686 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-2) Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Refresh image list succeeded for domain(s): ovirt-image-repository (All file type)
>2014-07-14 16:24:32,858 INFO  [org.ovirt.engine.core.bll.ImportRepoImageCommand] (org.ovirt.thread.pool-8-thread-33) [2f7a21ab] Running command: ImportRepoImageCommand Task handler: ImportRepoImageCreateTaskHandler internal: false. Entities affected :  ID: 53dd5513-d11a-4883-ba64-bc02f9aa43c6 Type: Storage,  ID: 072fbaa1-08f3-4a40-9f34-a5ca22dd1d74 Type: Storage
>2014-07-14 16:24:32,969 INFO  [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (org.ovirt.thread.pool-8-thread-33) [602f65b9] Running command: AddImageFromScratchCommand internal: true. Entities affected :  ID: 53dd5513-d11a-4883-ba64-bc02f9aa43c6 Type: Storage
>2014-07-14 16:24:33,046 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (org.ovirt.thread.pool-8-thread-33) [602f65b9] START, CreateImageVDSCommand( storagePoolId = 5849b030-626e-47cb-ad90-3ce782d831b3, ignoreFailoverLimit = false, storageDomainId = 53dd5513-d11a-4883-ba64-bc02f9aa43c6, imageGroupId = 1550f106-df28-4687-82fc-4499a6eb7dcf, imageSizeInBytes = 10737418240, volumeFormat = COW, newImageId = 5d5fbece-7c39-4cc7-a09e-ccd53fe19543, newImageDescription = Neutron Appliance (CentOS 7.0) - IceHouse-2014.1.1-3 (74cffa3)), log id: 42924c7
>2014-07-14 16:24:33,049 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (org.ovirt.thread.pool-8-thread-33) [602f65b9] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID
>2014-07-14 16:24:33,372 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (org.ovirt.thread.pool-8-thread-33) [602f65b9] FINISH, CreateImageVDSCommand, return: 5d5fbece-7c39-4cc7-a09e-ccd53fe19543, log id: 42924c7
>2014-07-14 16:24:33,456 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-33) [602f65b9] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command c15afcf5-5e50-4c39-9f3a-4f7fc0f694c5
>2014-07-14 16:24:33,457 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (org.ovirt.thread.pool-8-thread-33) [602f65b9] CommandMultiAsyncTasks::AttachTask: Attaching task f34564b8-ee74-45ba-8ed2-fb0c1bea70f2 to command c15afcf5-5e50-4c39-9f3a-4f7fc0f694c5.
>2014-07-14 16:24:33,479 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (org.ovirt.thread.pool-8-thread-33) [602f65b9] Adding task f34564b8-ee74-45ba-8ed2-fb0c1bea70f2 (Parent Command ImportRepoImage, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2014-07-14 16:24:33,525 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-33) [602f65b9] Correlation ID: 2f7a21ab, Job ID: 0a0edee7-9c85-413c-a188-8510cc1e0396, Call Stack: null, Custom Event ID: -1, Message: User admin importing image GlanceDisk-74cffa3 to domain ovirt-data2.
>2014-07-14 16:24:33,526 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-33) [602f65b9] BaseAsyncTask::startPollingTask: Starting to poll task f34564b8-ee74-45ba-8ed2-fb0c1bea70f2.
>2014-07-14 16:24:41,914 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-13) Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now
>2014-07-14 16:24:41,930 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-13) SPMAsyncTask::PollTask: Polling task f34564b8-ee74-45ba-8ed2-fb0c1bea70f2 (Parent Command ImportRepoImage, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
>2014-07-14 16:24:41,933 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-13) BaseAsyncTask::OnTaskEndSuccess: Task f34564b8-ee74-45ba-8ed2-fb0c1bea70f2 (Parent Command ImportRepoImage, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
>2014-07-14 16:24:41,935 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-13) CommandAsyncTask::EndActionIfNecessary: All tasks of command c15afcf5-5e50-4c39-9f3a-4f7fc0f694c5 has ended -> executing endAction
>2014-07-14 16:24:41,937 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-13) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: c15afcf5-5e50-4c39-9f3a-4f7fc0f694c5): calling endAction .
>2014-07-14 16:24:41,940 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-4) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction ImportRepoImage, executionIndex: 0
>2014-07-14 16:24:41,943 INFO  [org.ovirt.engine.core.bll.ImportRepoImageCommand] (org.ovirt.thread.pool-8-thread-4) Ending command successfully: org.ovirt.engine.core.bll.ImportRepoImageCommand
>2014-07-14 16:24:41,944 INFO  [org.ovirt.engine.core.bll.ImportRepoImageCommand] (org.ovirt.thread.pool-8-thread-4) Running command: ImportRepoImageCommand Task handler: ImportRepoImageCopyTaskHandler internal: false. Entities affected :  ID: 53dd5513-d11a-4883-ba64-bc02f9aa43c6 Type: Storage,  ID: 072fbaa1-08f3-4a40-9f34-a5ca22dd1d74 Type: Storage
>2014-07-14 16:24:41,975 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DownloadImageVDSCommand] (org.ovirt.thread.pool-8-thread-4) START, DownloadImageVDSCommand( storagePoolId = 5849b030-626e-47cb-ad90-3ce782d831b3, ignoreFailoverLimit = false, storageDomainId = 53dd5513-d11a-4883-ba64-bc02f9aa43c6, imageGroupId = 1550f106-df28-4687-82fc-4499a6eb7dcf, imageId = 5d5fbece-7c39-4cc7-a09e-ccd53fe19543), log id: 4bb2253b
>2014-07-14 16:24:41,977 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DownloadImageVDSCommand] (org.ovirt.thread.pool-8-thread-4) -- executeIrsBrokerCommand: calling 'downloadImage' 
>2014-07-14 16:24:41,978 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DownloadImageVDSCommand] (org.ovirt.thread.pool-8-thread-4) -- downloadImage parameters:
>                dstSpUUID=5849b030-626e-47cb-ad90-3ce782d831b3
>                dstSdUUID=53dd5513-d11a-4883-ba64-bc02f9aa43c6
>                dstImageGUID=1550f106-df28-4687-82fc-4499a6eb7dcf
>                dstVolUUID=5d5fbece-7c39-4cc7-a09e-ccd53fe19543
>                importLocation=HttpLocationInfo [url=http://glance.ovirt.org:9292/v1/images/74cffa3c-8f7c-4ec2-a068-67ff8b504746, headers={}]
>
>2014-07-14 16:24:42,268 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DownloadImageVDSCommand] (org.ovirt.thread.pool-8-thread-4) FINISH, DownloadImageVDSCommand, return: 00000000-0000-0000-0000-000000000000, log id: 4bb2253b
>2014-07-14 16:24:42,289 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (org.ovirt.thread.pool-8-thread-4) CommandMultiAsyncTasks::AttachTask: Attaching task 7d453036-0e55-4f5b-bd86-1ba7b873ccee to command c15afcf5-5e50-4c39-9f3a-4f7fc0f694c5.
>2014-07-14 16:24:42,305 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (org.ovirt.thread.pool-8-thread-4) Adding task 7d453036-0e55-4f5b-bd86-1ba7b873ccee (Parent Command ImportRepoImage, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2014-07-14 16:24:42,306 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-4) BaseAsyncTask::startPollingTask: Starting to poll task 7d453036-0e55-4f5b-bd86-1ba7b873ccee.
>2014-07-14 16:24:42,307 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-4) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ImportRepoImage completed, handling the result.
>2014-07-14 16:24:42,308 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-4) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ImportRepoImage succeeded, clearing tasks.
>2014-07-14 16:24:42,308 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-4) SPMAsyncTask::ClearAsyncTask: Attempting to clear task f34564b8-ee74-45ba-8ed2-fb0c1bea70f2
>2014-07-14 16:24:42,310 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-4) START, SPMClearTaskVDSCommand( storagePoolId = 5849b030-626e-47cb-ad90-3ce782d831b3, ignoreFailoverLimit = false, taskId = f34564b8-ee74-45ba-8ed2-fb0c1bea70f2), log id: 6196ba9
>2014-07-14 16:24:42,313 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-4) START, HSMClearTaskVDSCommand(HostName = masayag-f20-h1.usersys.redhat.com, HostId = 638ab850-698a-4b35-b038-a2f6b1d63ddd, taskId=f34564b8-ee74-45ba-8ed2-fb0c1bea70f2), log id: 3aef3eee
>2014-07-14 16:24:42,475 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-4) FINISH, HSMClearTaskVDSCommand, log id: 3aef3eee
>2014-07-14 16:24:42,476 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-4) FINISH, SPMClearTaskVDSCommand, log id: 6196ba9
>2014-07-14 16:24:42,488 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-4) BaseAsyncTask::RemoveTaskFromDB: Removed task f34564b8-ee74-45ba-8ed2-fb0c1bea70f2 from DataBase
>2014-07-14 16:24:42,489 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (org.ovirt.thread.pool-8-thread-4) [within thread]: Some of the tasks related to command id c15afcf5-5e50-4c39-9f3a-4f7fc0f694c5 were not cleared yet (Task id 7d453036-0e55-4f5b-bd86-1ba7b873ccee is in state Polling).
>2014-07-14 16:24:51,940 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-52) Polling and updating Async Tasks: 2 tasks, 1 tasks to poll now
>2014-07-14 16:24:51,990 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-52) SPMAsyncTask::PollTask: Polling task 7d453036-0e55-4f5b-bd86-1ba7b873ccee (Parent Command ImportRepoImage, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status running.
>2014-07-14 16:24:51,992 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-52) Finished polling Tasks, will poll again in 10 seconds.
>2014-07-14 16:26:11,914 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-13) Setting new tasks map. The map contains now 1 tasks
>2014-07-14 16:26:12,160 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-25) Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now
>2014-07-14 16:26:12,169 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-25) Finished polling Tasks, will poll again in 10 seconds.
>2014-07-14 16:40:17,700 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-90) SPMAsyncTask::PollTask: Polling task 7d453036-0e55-4f5b-bd86-1ba7b873ccee (Parent Command ImportRepoImage, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
>2014-07-14 16:40:17,704 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-90) BaseAsyncTask::OnTaskEndSuccess: Task 7d453036-0e55-4f5b-bd86-1ba7b873ccee (Parent Command ImportRepoImage, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
>2014-07-14 16:40:17,707 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-90) CommandAsyncTask::EndActionIfNecessary: All tasks of command c15afcf5-5e50-4c39-9f3a-4f7fc0f694c5 has ended -> executing endAction
>2014-07-14 16:40:17,709 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-90) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: c15afcf5-5e50-4c39-9f3a-4f7fc0f694c5): calling endAction .
>2014-07-14 16:40:17,712 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-38) CommandAsyncTask::EndCommandAction [within thread] context: Attempting to endAction ImportRepoImage, executionIndex: 1
>2014-07-14 16:40:17,729 INFO  [org.ovirt.engine.core.bll.ImportRepoImageCommand] (org.ovirt.thread.pool-8-thread-38) Ending command successfully: org.ovirt.engine.core.bll.ImportRepoImageCommand
>2014-07-14 16:40:17,840 INFO  [org.ovirt.engine.core.bll.AddVmTemplateCommand] (org.ovirt.thread.pool-8-thread-38) [49e07a5b] Running command: AddVmTemplateCommand internal: true. Entities affected :  ID: 5849b030-626e-47cb-ad90-3ce782d831b3 Type: StoragePool
>2014-07-14 16:40:17,964 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-38) [49e07a5b] Correlation ID: 49e07a5b, Call Stack: null, Custom Event ID: -1, Message: Template GlanceTemplate-c8dc3e0 was created successfully.
>2014-07-14 16:40:18,005 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-38) [49e07a5b] Correlation ID: 2f7a21ab, Call Stack: null, Custom Event ID: -1, Message: User admin successfully imported image GlanceDisk-74cffa3 to domain ovirt-data2.
>2014-07-14 16:40:18,007 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-38) [49e07a5b] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ImportRepoImage completed, handling the result.
>2014-07-14 16:40:18,009 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-38) [49e07a5b] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ImportRepoImage succeeded, clearing tasks.
>2014-07-14 16:40:18,011 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-38) [49e07a5b] SPMAsyncTask::ClearAsyncTask: Attempting to clear task 7d453036-0e55-4f5b-bd86-1ba7b873ccee
>2014-07-14 16:40:18,012 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-38) [49e07a5b] START, SPMClearTaskVDSCommand( storagePoolId = 5849b030-626e-47cb-ad90-3ce782d831b3, ignoreFailoverLimit = false, taskId = 7d453036-0e55-4f5b-bd86-1ba7b873ccee), log id: 4a119e79
>2014-07-14 16:40:18,016 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-38) [49e07a5b] START, HSMClearTaskVDSCommand(HostName = masayag-f20-h1.usersys.redhat.com, HostId = 638ab850-698a-4b35-b038-a2f6b1d63ddd, taskId=7d453036-0e55-4f5b-bd86-1ba7b873ccee), log id: 4b61c548
>2014-07-14 16:40:18,067 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-38) [49e07a5b] FINISH, HSMClearTaskVDSCommand, log id: 4b61c548
>2014-07-14 16:40:18,069 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-38) [49e07a5b] FINISH, SPMClearTaskVDSCommand, log id: 4a119e79
>2014-07-14 16:40:18,093 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-38) [49e07a5b] BaseAsyncTask::RemoveTaskFromDB: Removed task 7d453036-0e55-4f5b-bd86-1ba7b873ccee from DataBase
>2014-07-14 16:40:18,094 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-38) [49e07a5b] CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity c15afcf5-5e50-4c39-9f3a-4f7fc0f694c5
>2014-07-14 16:41:41,927 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-63) Setting new tasks map. The map contains now 0 tasks
>2014-07-14 16:41:41,928 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-63) Cleared all tasks of pool 5849b030-626e-47cb-ad90-3ce782d831b3.
>2014-07-14 16:42:08,629 WARN  [org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil] (org.ovirt.thread.pool-8-thread-5) Executing a command: java.util.concurrent.FutureTask , but note that there are 0 tasks in the queue.

Actions: View

Attachments on bug 1119325: 917878