Community
 
 
 

CloudPortal Business Manager 2.x

324 seguidores
 
Avatar
Pankaj Paliwal

CPBM 2.2.0 Failing to provision VMs

Avatar

CPBM 2.2.0 Failing to provision VMs

I have since found a problem when creating a VM, after the clicking the 'Subscribe and Provision' no VM is actually created in CCP. I receive a 'Provision Resource Failed' email:

 

Resource Provision for the following subscription has been failed.

  • Product Bundle : VM 2GB Linux

  • Service Resource Type: VirtualMachine

  • Service Instance: Cloud

Please go to your subscription and try to provision your resource.

 

The CCP management log shows this:

 

2014-05-13 04:12:49,591 INFO  [cloud.api.ApiServer] (catalina-exec-15:null)  172.20.1.139 -- GET null 401 unable to verify user credentials and/or request signature

2014-05-13 04:12:49,850 INFO  [cloud.api.ApiServer] (ApiServer-9:null) (userId=1 accountId=1 sessionId=null) /172.20.1.139 -- GET / HTTP/1.0 432 Invalid request, no command sent

When creating a VM

 

Any suggestions?

 

Thanks

 


TARIQ IQBAL MEMBERS
2 comentários
0

iniciar sessão para comentar.

 
 

Previous 2 comentários

Avatar
Pankaj Paliwal
Avatar

CPBM 2.2.0 Failing to provision VMs

Some further details for this issue - 

 

I can add/edit a Volume resource in an account in CPBM without issue. However, when purchasing a VM and the 'Subscribe and Provision' is clicked, I get the message Subscription is successful, but when I look at the Resources the VM does not exist; the Subscriptions screen shows the subscription with a State: NEW (Error) and Error Details: 'Error listing zones 401 unable to verify user credentials and/or request signature'.

 

Any suggestions please?

 

Here is the cpbm-server.log output at the moment the 'Subscribe and Provision' is clicked when purchasing a VM:

 

[2014-05-14 05:03:25.492] INFO  catalina-exec-22             com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:03:25.493] INFO  catalina-exec-22             com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:03:30.444] INFO  catalina-exec-4              com.vmops.web.interceptors.UserContextInterceptor                 Before handling the request
[2014-05-14 05:03:30.469] INFO  catalina-exec-4              com.vmops.web.interceptors.UserContextInterceptor                 Inside UCI Call...C
[2014-05-14 05:03:30.502] INFO  catalina-exec-4              com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:03:30.502] INFO  catalina-exec-4              com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:03:35.446] INFO  catalina-exec-3              com.vmops.web.interceptors.UserContextInterceptor                 Before handling the request
[2014-05-14 05:03:35.469] INFO  catalina-exec-3              com.vmops.web.interceptors.UserContextInterceptor                 Inside UCI Call...C
[2014-05-14 05:03:35.496] INFO  catalina-exec-3              com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:03:35.497] INFO  catalina-exec-3              com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:03:40.444] INFO  catalina-exec-18             com.vmops.web.interceptors.UserContextInterceptor                 Before handling the request
[2014-05-14 05:03:40.469] INFO  catalina-exec-18             com.vmops.web.interceptors.UserContextInterceptor                 Inside UCI Call...C
[2014-05-14 05:03:40.492] INFO  catalina-exec-18             com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:03:40.493] INFO  catalina-exec-18             com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:03:44.408] INFO  catalina-exec-15             System.out                                                        [Wed May 14 05:03:44 COT 2014] [Info] CsrfGuard analyzing request /portal/portal/subscription/subscribe_resource
[2014-05-14 05:03:44.412] INFO  catalina-exec-15             com.vmops.web.interceptors.UserContextInterceptor                 Before handling the request
[2014-05-14 05:03:45.444] INFO  catalina-exec-11             com.vmops.web.interceptors.UserContextInterceptor                 Before handling the request
[2014-05-14 05:03:45.467] INFO  catalina-exec-11             com.vmops.web.interceptors.UserContextInterceptor                 Inside UCI Call...C
[2014-05-14 05:03:45.476] INFO  catalina-exec-11             com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:03:45.476] INFO  catalina-exec-11             com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:03:46.591] INFO  catalina-exec-15             com.vmops.event.listeners.EventListenerJmsProducer                Queued event com.vmops.event.SubscriptionCreation source = Subscription [createdAt=2014-05-14, derivedFrom=null, id=5125, productBundle=173, state=NEW, terminationDate=null, vmId=null]
[2014-05-14 05:03:46.602] INFO  catalina-exec-15             com.vmops.event.listeners.EventListenerJmsProducer                Queued event com.citrix.cpbm.core.workflow.event.TriggerTransaction source = BusinessTransaction [id=360, uuid=ef834fb0-aa52-4f80-8f1e-d7a5e122159c, workflowId=null, startDate=Wed May 14 05:03:46 COT 2014, endDate=null, state=NEW, scope=USER, tenant=Tenant [accountId=AA014015, name=ShapeBlue], memo=null] :SubscriptionStateChangeTransaction [subscriptionId=5125, subscriptionIntialState=NEW, subscriptionTargetState=ACTIVE]
[2014-05-14 05:03:46.642] INFO  catalina-exec-15             com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated , UserName: geoff.higginbottom@shapeblue.com
[2014-05-14 05:03:46.643] INFO  catalina-exec-15             com.vmops.event.listeners.EventListenerJmsProducer                Sending event com.vmops.event.SubscriptionCreation source = Subscription [createdAt=2014-05-14, derivedFrom=null, id=5125, productBundle=173, state=NEW, terminationDate=null, vmId=null]
[2014-05-14 05:03:46.653] INFO  catalina-exec-15             com.vmops.event.listeners.EventListenerJmsProducer                Sending event com.citrix.cpbm.core.workflow.event.TriggerTransaction source = BusinessTransaction [id=360, uuid=ef834fb0-aa52-4f80-8f1e-d7a5e122159c, workflowId=null, startDate=Wed May 14 05:03:46 COT 2014, endDate=null, state=NEW, scope=USER, tenant=Tenant [accountId=AA014015, name=ShapeBlue], memo=null] :SubscriptionStateChangeTransaction [subscriptionId=5125, subscriptionIntialState=NEW, subscriptionTargetState=ACTIVE]
[2014-05-14 05:03:46.663] INFO  catalina-exec-15             com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated , UserName: geoff.higginbottom@shapeblue.com
[2014-05-14 05:03:46.874] INFO  QueueListener-1              com.vmops.admin.event.listeners.EventListenerJmsConsumer          ### Received SUBSCRIPTIONCREATION id5125
[2014-05-14 05:03:46.883] INFO  QueueListener-3              com.vmops.admin.event.listeners.EventListenerJmsConsumer          received workflowevent with payloadTriggerTransaction [businessTransactionId=360]
[2014-05-14 05:03:47.426] INFO  QueueListener-3              com.citrix.cpbm.workflow.resolver.impl.WorkflowResolverImpl       Incoming request to resolve WFD for the type : class com.citrix.cpbm.core.workflow.model.SubscriptionActivationTransaction
[2014-05-14 05:03:47.429] INFO  QueueListener-3              c.c.cpbm.workflow.service.impl.BusinessTransactionServiceImpl     #Entered here to complete the transaction : 360 of workflow : null
[2014-05-14 05:03:47.430] INFO  QueueListener-3              com.vmops.internal.service.impl.SubscriptionServiceImpl           ###SPI Calling SubscriptionLifecycleHandler().provision(subscription) for instance:0e4f8f06-a203-11e3-98cc-fe8608d78b16 subscription:76ab30b5-cdf1-4c51-b834-c7fa7ff07a1c
[2014-05-14 05:03:47.491] ERROR QueueListener-3              com.citrix.cloudstack.client.impl.DefaultCloudServiceImpl         Error during listZones 401 unable to verify user credentials and/or request signature
[2014-05-14 05:03:47.505] WARN  QueueListener-3              c.c.cpbm.workflow.service.impl.BusinessTransactionServiceImpl     Got Exception while provisioning a resource : com.vmops.service.exceptions.CloudServiceException: Error listing zones 401 unable to verify user credentials and/or request signature
[2014-05-14 05:03:47.506] INFO  QueueListener-3              com.vmops.event.listeners.EventListenerJmsProducer                Queued event com.vmops.event.ProvisionResourceFailedEvent source = Subscription [createdAt=2014-05-14, derivedFrom=null, id=5125, productBundle=173, state=NEW, terminationDate=null, vmId=null]
[2014-05-14 05:03:47.508] INFO  QueueListener-3              c.c.cpbm.workflow.service.impl.BusinessTransactionServiceImpl     #Exiting after completion of the transaction : 360 of workflow : null with the state : ERROR
[2014-05-14 05:03:47.509] INFO  QueueListener-3              com.vmops.event.listeners.EventListenerJmsProducer                Sending event com.vmops.event.ProvisionResourceFailedEvent source = Subscription [createdAt=2014-05-14, derivedFrom=null, id=5125, productBundle=173, state=NEW, terminationDate=null, vmId=null]
[2014-05-14 05:03:47.749] INFO  QueueListener-2              com.vmops.admin.event.listeners.EventListenerJmsConsumer          ### Received ProvisionResourceFailedEvent 5125
[2014-05-14 05:03:50.443] INFO  catalina-exec-6              com.vmops.web.interceptors.UserContextInterceptor                 Before handling the request
[2014-05-14 05:03:50.463] INFO  catalina-exec-6              com.vmops.web.interceptors.UserContextInterceptor                 Inside UCI Call...C
[2014-05-14 05:03:50.486] INFO  catalina-exec-6              com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:03:50.487] INFO  catalina-exec-6              com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:03:55.446] INFO  catalina-exec-20             com.vmops.web.interceptors.UserContextInterceptor                 Before handling the request
[2014-05-14 05:03:55.466] INFO  catalina-exec-20             com.vmops.web.interceptors.UserContextInterceptor                 Inside UCI Call...C
[2014-05-14 05:03:55.488] INFO  catalina-exec-20             com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:03:55.489] INFO  catalina-exec-20             com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:04:00.443] INFO  catalina-exec-21             com.vmops.web.interceptors.UserContextInterceptor                 Before handling the request
[2014-05-14 05:04:00.466] INFO  catalina-exec-21             com.vmops.web.interceptors.UserContextInterceptor                 Inside UCI Call...C
[2014-05-14 05:04:00.475] INFO  catalina-exec-21             com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:04:00.476] INFO  catalina-exec-21             com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:04:05.445] INFO  catalina-exec-9              com.vmops.web.interceptors.UserContextInterceptor                 Before handling the request
[2014-05-14 05:04:05.468] INFO  catalina-exec-9              com.vmops.web.interceptors.UserContextInterceptor                 Inside UCI Call...C
[2014-05-14 05:04:05.492] INFO  catalina-exec-9              com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:04:05.492] INFO  catalina-exec-9              com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:04:10.443] INFO  catalina-exec-5              com.vmops.web.interceptors.UserContextInterceptor                 Before handling the request
[2014-05-14 05:04:10.465] INFO  catalina-exec-5              com.vmops.web.interceptors.UserContextInterceptor                 Inside UCI Call...C
[2014-05-14 05:04:10.474] INFO  catalina-exec-5              com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:04:10.476] INFO  catalina-exec-5              com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:04:15.445] INFO  catalina-exec-23             com.vmops.web.interceptors.UserContextInterceptor                 Before handling the request
[2014-05-14 05:04:15.469] INFO  catalina-exec-23             com.vmops.web.interceptors.UserContextInterceptor                 Inside UCI Call...C
[2014-05-14 05:04:15.492] INFO  catalina-exec-23             com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:04:15.495] INFO  catalina-exec-23             com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:04:20.443] INFO  catalina-exec-1              com.vmops.web.interceptors.UserContextInterceptor                 Before handling the request
[2014-05-14 05:04:20.466] INFO  catalina-exec-1              com.vmops.web.interceptors.UserContextInterceptor                 Inside UCI Call...C
[2014-05-14 05:04:20.475] INFO  catalina-exec-1              com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
[2014-05-14 05:04:20.476] INFO  catalina-exec-1              com.vmops.web.listeners.RequestHandledListener                    Request handled: success... flushing events generated
^C

TARIQ IQBAL MEMBERS
Ações de comentário Permalink
Avatar
Pankaj Paliwal
Avatar

Hi Tariq,

 

Have you tried creating the VM directly in CCP under same account?

And can you also provide more CCP logs before you get "2014-05-13 04:12:49,591 INFO  [cloud.api.ApiServer] (catalina-exec-15:null) 172.20.1.139 -- GET null 401 unable to verify user credentials and/or request signature".

 

Thanks,

Manish


Manish Agarwal CITRIX EMPLOYEES
Ações de comentário Permalink

Top Contributors