cloudstack-issues mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "ASF GitHub Bot (JIRA)" <j...@apache.org>
Subject [jira] [Commented] (CLOUDSTACK-8830) [VMware] VM snapshot fails for 12 min after instance creation
Date Tue, 01 Nov 2016 19:21:58 GMT

    [ https://issues.apache.org/jira/browse/CLOUDSTACK-8830?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15626409#comment-15626409
] 

ASF GitHub Bot commented on CLOUDSTACK-8830:
--------------------------------------------

Github user blueorangutan commented on the issue:

    https://github.com/apache/cloudstack/pull/1677
  
    <b>Trillian test result (tid-236)</b>
    Environment: vmware-55u3 (x2), Advanced Networking with Mgmt server 7
    Total time taken: 36091 seconds
    Marvin logs: https://github.com/blueorangutan/acs-prs/releases/download/trillian/pr1677-t236-vmware-55u3.zip
    Test completed. 46 look ok, 2 have error(s)
    
    
    Test | Result | Time (s) | Test File
    --- | --- | --- | ---
    test_01_vpc_site2site_vpn | `Error` | 481.00 | test_vpc_vpn.py
    ContextSuite context=TestRVPCSite2SiteVpn>:setup | `Error` | 0.00 | test_vpc_vpn.py
    ContextSuite context=TestVPCRedundancy>:teardown | `Error` | 717.29 | test_vpc_redundant.py
    test_oobm_enabledisable_across_clusterzones | `Error` | 56.84 | test_outofbandmanagement.py
    test_01_vpc_remote_access_vpn | Success | 151.25 | test_vpc_vpn.py
    test_02_VPC_default_routes | Success | 333.57 | test_vpc_router_nics.py
    test_01_VPC_nics_after_destroy | Success | 730.36 | test_vpc_router_nics.py
    test_05_rvpc_multi_tiers | Success | 712.21 | test_vpc_redundant.py
    test_04_rvpc_network_garbage_collector_nics | Success | 1528.60 | test_vpc_redundant.py
    test_03_create_redundant_VPC_1tier_2VMs_2IPs_2PF_ACL_reboot_routers | Success | 658.39
| test_vpc_redundant.py
    test_02_redundant_VPC_default_routes | Success | 643.21 | test_vpc_redundant.py
    test_01_create_redundant_VPC_2tiers_4VMs_4IPs_4PF_ACL | Success | 1407.78 | test_vpc_redundant.py
    test_09_delete_detached_volume | Success | 30.97 | test_volumes.py
    test_06_download_detached_volume | Success | 65.59 | test_volumes.py
    test_05_detach_volume | Success | 100.18 | test_volumes.py
    test_04_delete_attached_volume | Success | 10.14 | test_volumes.py
    test_03_download_attached_volume | Success | 20.24 | test_volumes.py
    test_02_attach_volume | Success | 56.05 | test_volumes.py
    test_01_create_volume | Success | 455.21 | test_volumes.py
    test_03_delete_vm_snapshots | Success | 275.20 | test_vm_snapshots.py
    test_02_revert_vm_snapshots | Success | 226.96 | test_vm_snapshots.py
    test_01_test_vm_volume_snapshot | Success | 186.36 | test_vm_snapshots.py
    test_01_create_vm_snapshots | Success | 161.59 | test_vm_snapshots.py
    test_deploy_vm_multiple | Success | 222.44 | test_vm_life_cycle.py
    test_deploy_vm | Success | 0.02 | test_vm_life_cycle.py
    test_advZoneVirtualRouter | Success | 0.02 | test_vm_life_cycle.py
    test_10_attachAndDetach_iso | Success | 27.19 | test_vm_life_cycle.py
    test_09_expunge_vm | Success | 125.14 | test_vm_life_cycle.py
    test_08_migrate_vm | Success | 86.33 | test_vm_life_cycle.py
    test_07_restore_vm | Success | 0.12 | test_vm_life_cycle.py
    test_06_destroy_vm | Success | 10.12 | test_vm_life_cycle.py
    test_03_reboot_vm | Success | 5.12 | test_vm_life_cycle.py
    test_02_start_vm | Success | 20.20 | test_vm_life_cycle.py
    test_01_stop_vm | Success | 5.09 | test_vm_life_cycle.py
    test_CreateTemplateWithDuplicateName | Success | 271.50 | test_templates.py
    test_08_list_system_templates | Success | 0.03 | test_templates.py
    test_07_list_public_templates | Success | 0.03 | test_templates.py
    test_05_template_permissions | Success | 0.05 | test_templates.py
    test_04_extract_template | Success | 20.34 | test_templates.py
    test_03_delete_template | Success | 5.08 | test_templates.py
    test_02_edit_template | Success | 90.15 | test_templates.py
    test_01_create_template | Success | 145.84 | test_templates.py
    test_10_destroy_cpvm | Success | 241.57 | test_ssvm.py
    test_09_destroy_ssvm | Success | 263.29 | test_ssvm.py
    test_08_reboot_cpvm | Success | 156.45 | test_ssvm.py
    test_07_reboot_ssvm | Success | 158.72 | test_ssvm.py
    test_06_stop_cpvm | Success | 201.66 | test_ssvm.py
    test_05_stop_ssvm | Success | 203.35 | test_ssvm.py
    test_04_cpvm_internals | Success | 1.04 | test_ssvm.py
    test_03_ssvm_internals | Success | 3.31 | test_ssvm.py
    test_02_list_cpvm_vm | Success | 0.10 | test_ssvm.py
    test_01_list_sec_storage_vm | Success | 0.12 | test_ssvm.py
    test_01_snapshot_root_disk | Success | 66.22 | test_snapshots.py
    test_04_change_offering_small | Success | 96.75 | test_service_offerings.py
    test_03_delete_service_offering | Success | 0.04 | test_service_offerings.py
    test_02_edit_service_offering | Success | 0.09 | test_service_offerings.py
    test_01_create_service_offering | Success | 0.09 | test_service_offerings.py
    test_02_sys_template_ready | Success | 0.10 | test_secondary_storage.py
    test_01_sys_vm_start | Success | 0.13 | test_secondary_storage.py
    test_09_reboot_router | Success | 180.95 | test_routers.py
    test_08_start_router | Success | 150.90 | test_routers.py
    test_07_stop_router | Success | 25.20 | test_routers.py
    test_06_router_advanced | Success | 0.04 | test_routers.py
    test_05_router_basic | Success | 0.03 | test_routers.py
    test_04_restart_network_wo_cleanup | Success | 5.55 | test_routers.py
    test_03_restart_network_cleanup | Success | 160.89 | test_routers.py
    test_02_router_internal_adv | Success | 0.85 | test_routers.py
    test_01_router_internal_basic | Success | 0.45 | test_routers.py
    test_router_dns_guestipquery | Success | 76.63 | test_router_dns.py
    test_router_dns_externalipquery | Success | 0.07 | test_router_dns.py
    test_router_dhcphosts | Success | 162.74 | test_router_dhcphosts.py
    test_01_updatevolumedetail | Success | 0.07 | test_resource_detail.py
    test_01_reset_vm_on_reboot | Success | 40.34 | test_reset_vm_on_reboot.py
    test_createRegion | Success | 0.04 | test_regions.py
    test_create_pvlan_network | Success | 5.15 | test_pvlan.py
    test_dedicatePublicIpRange | Success | 0.31 | test_public_ip_range.py
    test_04_rvpc_privategw_static_routes | Success | 1133.00 | test_privategw_acl.py
    test_03_vpc_privategw_restart_vpc_cleanup | Success | 1017.75 | test_privategw_acl.py
    test_02_vpc_privategw_static_routes | Success | 777.51 | test_privategw_acl.py
    test_01_vpc_privategw_acl | Success | 192.93 | test_privategw_acl.py
    test_01_primary_storage_nfs | Success | 37.18 | test_primary_storage.py
    test_createPortablePublicIPRange | Success | 15.18 | test_portable_publicip.py
    test_createPortablePublicIPAcquire | Success | 15.35 | test_portable_publicip.py
    test_isolate_network_password_server | Success | 91.97 | test_password_server.py
    test_UpdateStorageOverProvisioningFactor | Success | 0.13 | test_over_provisioning.py
    test_oobm_zchange_password | Success | 20.51 | test_outofbandmanagement.py
    test_oobm_multiple_mgmt_server_ownership | Success | 11.51 | test_outofbandmanagement.py
    test_oobm_issue_power_status | Success | 10.51 | test_outofbandmanagement.py
    test_oobm_issue_power_soft | Success | 10.52 | test_outofbandmanagement.py
    test_oobm_issue_power_reset | Success | 16.01 | test_outofbandmanagement.py
    test_oobm_issue_power_on | Success | 15.49 | test_outofbandmanagement.py
    test_oobm_issue_power_off | Success | 10.53 | test_outofbandmanagement.py
    test_oobm_issue_power_cycle | Success | 15.52 | test_outofbandmanagement.py
    test_oobm_enable_feature_valid | Success | 5.14 | test_outofbandmanagement.py
    test_oobm_enable_feature_invalid | Success | 0.10 | test_outofbandmanagement.py
    test_oobm_disable_feature_valid | Success | 0.15 | test_outofbandmanagement.py
    test_oobm_disable_feature_invalid | Success | 0.10 | test_outofbandmanagement.py
    test_oobm_configure_invalid_driver | Success | 0.11 | test_outofbandmanagement.py
    test_oobm_configure_default_driver | Success | 0.10 | test_outofbandmanagement.py
    test_oobm_background_powerstate_sync | Success | 29.45 | test_outofbandmanagement.py
    test_extendPhysicalNetworkVlan | Success | 15.28 | test_non_contigiousvlan.py
    test_01_nic | Success | 620.76 | test_nic.py
    test_releaseIP | Success | 343.24 | test_network.py
    test_reboot_router | Success | 624.65 | test_network.py
    test_public_ip_user_account | Success | 10.33 | test_network.py
    test_public_ip_admin_account | Success | 40.21 | test_network.py
    test_network_rules_acquired_public_ip_3_Load_Balancer_Rule | Success | 71.45 | test_network.py
    test_network_rules_acquired_public_ip_2_nat_rule | Success | 61.32 | test_network.py
    test_network_rules_acquired_public_ip_1_static_nat_rule | Success | 124.76 | test_network.py
    test_delete_account | Success | 327.88 | test_network.py
    test_02_port_fwd_on_non_src_nat | Success | 55.51 | test_network.py
    test_01_port_fwd_on_src_nat | Success | 111.64 | test_network.py
    test_nic_secondaryip_add_remove | Success | 222.69 | test_multipleips_per_nic.py
    login_test_saml_user | Success | 21.36 | test_login.py
    test_assign_and_removal_lb | Success | 148.39 | test_loadbalance.py
    test_02_create_lb_rule_non_nat | Success | 207.48 | test_loadbalance.py
    test_01_create_lb_rule_src_nat | Success | 209.04 | test_loadbalance.py
    test_03_list_snapshots | Success | 0.06 | test_list_ids_parameter.py
    test_02_list_templates | Success | 0.03 | test_list_ids_parameter.py
    test_01_list_volumes | Success | 0.02 | test_list_ids_parameter.py
    test_07_list_default_iso | Success | 0.05 | test_iso.py
    test_05_iso_permissions | Success | 0.07 | test_iso.py
    test_04_extract_Iso | Success | 5.27 | test_iso.py
    test_03_delete_iso | Success | 95.21 | test_iso.py
    test_02_edit_iso | Success | 0.04 | test_iso.py
    test_01_create_iso | Success | 21.27 | test_iso.py
    test_04_rvpc_internallb_haproxy_stats_on_all_interfaces | Success | 639.80 | test_internal_lb.py
    test_03_vpc_internallb_haproxy_stats_on_all_interfaces | Success | 398.73 | test_internal_lb.py
    test_02_internallb_roundrobin_1RVPC_3VM_HTTP_port80 | Success | 1056.63 | test_internal_lb.py
    test_01_internallb_roundrobin_1VPC_3VM_HTTP_port80 | Success | 897.62 | test_internal_lb.py
    test_dedicateGuestVlanRange | Success | 10.36 | test_guest_vlan_range.py
    test_UpdateConfigParamWithScope | Success | 0.12 | test_global_settings.py
    test_rolepermission_lifecycle_update | Success | 6.60 | test_dynamicroles.py
    test_rolepermission_lifecycle_list | Success | 6.32 | test_dynamicroles.py
    test_rolepermission_lifecycle_delete | Success | 6.19 | test_dynamicroles.py
    test_rolepermission_lifecycle_create | Success | 6.22 | test_dynamicroles.py
    test_rolepermission_lifecycle_concurrent_updates | Success | 6.30 | test_dynamicroles.py
    test_role_lifecycle_update_role_inuse | Success | 6.24 | test_dynamicroles.py
    test_role_lifecycle_update | Success | 11.44 | test_dynamicroles.py
    test_role_lifecycle_list | Success | 6.35 | test_dynamicroles.py
    test_role_lifecycle_delete | Success | 11.26 | test_dynamicroles.py
    test_role_lifecycle_create | Success | 6.20 | test_dynamicroles.py
    test_role_inuse_deletion | Success | 6.18 | test_dynamicroles.py
    test_role_account_acls_multiple_mgmt_servers | Success | 7.60 | test_dynamicroles.py
    test_role_account_acls | Success | 7.83 | test_dynamicroles.py
    test_default_role_deletion | Success | 6.25 | test_dynamicroles.py
    test_04_create_fat_type_disk_offering | Success | 0.05 | test_disk_offerings.py
    test_03_delete_disk_offering | Success | 0.04 | test_disk_offerings.py
    test_02_edit_disk_offering | Success | 0.05 | test_disk_offerings.py
    test_02_create_sparse_type_disk_offering | Success | 0.06 | test_disk_offerings.py
    test_01_create_disk_offering | Success | 0.07 | test_disk_offerings.py
    test_deployvm_userdispersing | Success | 50.65 | test_deploy_vms_with_varied_deploymentplanners.py
    test_deployvm_userconcentrated | Success | 91.07 | test_deploy_vms_with_varied_deploymentplanners.py
    test_deployvm_firstfit | Success | 191.21 | test_deploy_vms_with_varied_deploymentplanners.py
    test_deployvm_userdata_post | Success | 70.90 | test_deploy_vm_with_userdata.py
    test_deployvm_userdata | Success | 206.81 | test_deploy_vm_with_userdata.py
    test_02_deploy_vm_root_resize | Success | 6.19 | test_deploy_vm_root_resize.py
    test_01_deploy_vm_root_resize | Success | 6.17 | test_deploy_vm_root_resize.py
    test_00_deploy_vm_root_resize | Success | 6.43 | test_deploy_vm_root_resize.py
    test_deploy_vm_from_iso | Success | 187.81 | test_deploy_vm_iso.py
    test_DeployVmAntiAffinityGroup | Success | 247.06 | test_affinity_groups.py
    test_08_resize_volume | Skipped | 5.08 | test_volumes.py
    test_07_resize_fail | Skipped | 10.21 | test_volumes.py
    test_06_copy_template | Skipped | 0.00 | test_templates.py
    test_static_role_account_acls | Skipped | 0.02 | test_staticroles.py
    test_01_scale_vm | Skipped | 63.33 | test_scale_vm.py
    test_01_primary_storage_iscsi | Skipped | 0.03 | test_primary_storage.py
    test_06_copy_iso | Skipped | 0.00 | test_iso.py
    test_deploy_vgpu_enabled_vm | Skipped | 0.01 | test_deploy_vgpu_enabled_vm.py



> [VMware] VM snapshot fails for 12 min after instance creation
> -------------------------------------------------------------
>
>                 Key: CLOUDSTACK-8830
>                 URL: https://issues.apache.org/jira/browse/CLOUDSTACK-8830
>             Project: CloudStack
>          Issue Type: Bug
>      Security Level: Public(Anyone can view this level - this is the default.) 
>            Reporter: Maneesha
>            Assignee: Maneesha
>
> ISSUE
> ============
> [VMware] VM snapshot fails for 12 min after instance creation
> Environment
> ==================
> Product Name: Cloudstack
> Hypervisor: VMWare VSphere 6
> VM DETAILS
> ==================
> i-84987-16119-VM
> TROUBLESHOOTING
> ==================
> I see that the following failure and immediate success result for the CreateVMSnapshot
call
> {noformat}
> 2015-07-24 08:20:55,363 DEBUG [c.c.a.t.Request] (Work-Job-Executor-61:ctx-03fad7f2 job-64835/job-64836
ctx-746f3965) (logid:8b87ab8a) Seq 80-6161487240196259878: Sending  { Cmd , MgmtId: 345051581208,
via: 80(ussfoldcsesx112.adslab.local), Ver: v1, Flags: 100011, [{"com.cloud.agent.api.CreateVMSnapshotCommand":{"volumeTOs":[{"uuid":"a89b4ad5-f23f-4df6-84a8-89c4f40b2edb","volumeType":"ROOT","volumeState":"Ready","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"346b381a-8543-3f7b-9eff-fa909ad243c7","id":205,"poolType":"NetworkFilesystem","host":"10.144.35.110","path":"/tintri/ECS-SR-CLD200","port":2049,"url":"NetworkFilesystem://10.144.35.110/tintri/ECS-SR-CLD200/?ROLE=Primary&STOREUUID=346b381a-8543-3f7b-9eff-fa909ad243c7"}},"name":"ROOT-16119","size":1073741824,"path":"ROOT-16119","volumeId":19311,"vmName":"i-84987-16119-VM","vmState":"Running","accountId":84987,"chainInfo":"{\"diskDeviceBusName\":\"ide0:1\",\"diskChain\":[\"[346b381a85433f7b9efffa909ad243c7]
i-84987-16119-VM/ROOT-16119.vmdk\",\"[346b381a85433f7b9efffa909ad243c7] 49f59e1a4ce23fec8890c8b9e5891d56/49f59e1a4ce23fec8890c8b9e5891d56.vmdk\"]}","format":"OVA","provisioningType":"THIN","id":19311,"deviceId":0,"cacheMode":"NONE","hypervisorType":"VMware"}],"target":{"id":962,"snapshotName":"i-84987-16119-VM_VS_20150724152053","type":"Disk","current":false,"description":"unit-test-instance-snapshot","quiescevm":false},"vmName":"i-84987-16119-VM","guestOSType":"None","wait":1800}}]
}
> 2015-07-24 08:20:55,373 DEBUG [c.c.a.t.Request] (Work-Job-Executor-61:ctx-03fad7f2 job-64835/job-64836
ctx-746f3965) (logid:8b87ab8a) Seq 80-6161487240196259878: Executing:  { Cmd , MgmtId: 345051581208,
via: 80(ussfoldcsesx112.adslab.local), Ver: v1, Flags: 100011, [{"com.cloud.agent.api.CreateVMSnapshotCommand":{"volumeTOs":[{"uuid":"a89b4ad5-f23f-4df6-84a8-89c4f40b2edb","volumeType":"ROOT","volumeState":"Ready","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"346b381a-8543-3f7b-9eff-fa909ad243c7","id":205,"poolType":"NetworkFilesystem","host":"10.144.35.110","path":"/tintri/ECS-SR-CLD200","port":2049,"url":"NetworkFilesystem://10.144.35.110/tintri/ECS-SR-CLD200/?ROLE=Primary&STOREUUID=346b381a-8543-3f7b-9eff-fa909ad243c7"}},"name":"ROOT-16119","size":1073741824,"path":"ROOT-16119","volumeId":19311,"vmName":"i-84987-16119-VM","vmState":"Running","accountId":84987,"chainInfo":"{\"diskDeviceBusName\":\"ide0:1\",\"diskChain\":[\"[346b381a85433f7b9efffa909ad243c7]
i-84987-16119-VM/ROOT-16119.vmdk\",\"[346b381a85433f7b9efffa909ad243c7] 49f59e1a4ce23fec8890c8b9e5891d56/49f59e1a4ce23fec8890c8b9e5891d56.vmdk\"]}","format":"OVA","provisioningType":"THIN","id":19311,"deviceId":0,"cacheMode":"NONE","hypervisorType":"VMware"}],"target":{"id":962,"snapshotName":"i-84987-16119-VM_VS_20150724152053","type":"Disk","current":false,"description":"unit-test-instance-snapshot","quiescevm":false},"vmName":"i-84987-16119-VM","guestOSType":"None","wait":1800}}]
}
> 2015-07-24 08:20:55,374 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-66:ctx-5fbdccd8)
(logid:710814a5) Seq 80-6161487240196259878: Executing request
> 2015-07-24 08:20:55,523 ERROR [c.c.h.v.m.VmwareStorageManagerImpl] (DirectAgent-66:ctx-5fbdccd8
ussfoldcsesx112.adslab.local, job-64835/job-64836, cmd: CreateVMSnapshotCommand) (logid:8b87ab8a)
failed to create snapshot for vm:i-84987-16119-VM due to null
> 2015-07-24 08:20:55,524 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-66:ctx-5fbdccd8)
(logid:8b87ab8a) Seq 80-6161487240196259878: Response Received: 
> 2015-07-24 08:20:55,525 DEBUG [c.c.a.t.Request] (DirectAgent-66:ctx-5fbdccd8) (logid:8b87ab8a)
Seq 80-6161487240196259878: Processing:  { Ans: , MgmtId: 345051581208, via: 80, Ver: v1,
Flags: 10, [{"com.cloud.agent.api.CreateVMSnapshotAnswer":{"result":false,"wait":0}}] }
> 2015-07-24 08:20:55,525 DEBUG [c.c.a.t.Request] (Work-Job-Executor-61:ctx-03fad7f2 job-64835/job-64836
ctx-746f3965) (logid:8b87ab8a) Seq 80-6161487240196259878: Received:  { Ans: , MgmtId: 345051581208,
via: 80, Ver: v1, Flags: 10, { CreateVMSnapshotAnswer } }
> 2015-07-24 08:20:55,525 ERROR [o.a.c.s.v.DefaultVMSnapshotStrategy] (Work-Job-Executor-61:ctx-03fad7f2
job-64835/job-64836 ctx-746f3965) (logid:8b87ab8a) Creating VM snapshot: i-84987-16119-VM_VS_20150724152053
failed
> 2015-07-24 08:20:55,531 DEBUG [c.c.v.s.VMSnapshotManagerImpl] (Work-Job-Executor-61:ctx-03fad7f2
job-64835/job-64836 ctx-746f3965) (logid:8b87ab8a) Failed to create vm snapshot: 962
> com.cloud.utils.exception.CloudRuntimeException: Creating VM snapshot: i-84987-16119-VM_VS_20150724152053
failed
> 	at org.apache.cloudstack.storage.vmsnapshot.DefaultVMSnapshotStrategy.takeVMSnapshot(DefaultVMSnapshotStrategy.java:163)
> 	at com.cloud.vm.snapshot.VMSnapshotManagerImpl.orchestrateCreateVMSnapshot(VMSnapshotManagerImpl.java:438)
> 	at com.cloud.vm.snapshot.VMSnapshotManagerImpl.orchestrateCreateVMSnapshot(VMSnapshotManagerImpl.java:994)
> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> 	at java.lang.reflect.Method.invoke(Method.java:606)
> 	at com.cloud.vm.VmWorkJobHandlerProxy.handleVmWorkJob(VmWorkJobHandlerProxy.java:107)
> 	at com.cloud.vm.snapshot.VMSnapshotManagerImpl.handleVmWorkJob(VMSnapshotManagerImpl.java:1021)
> 	at sun.reflect.GeneratedMethodAccessor797.invoke(Unknown Source)
> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> 	at java.lang.reflect.Method.invoke(Method.java:606)
> 	at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
> 	at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
> 	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
> 	at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
> 	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
> 	at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
> 	at com.sun.proxy.$Proxy198.handleVmWorkJob(Unknown Source)
> 	at com.cloud.vm.VmWorkJobDispatcher.runJob(VmWorkJobDispatcher.java:103)
> 	at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:549)
> 	at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
> 	at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
> 	at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
> 	at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
> 	at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
> 	at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:500)
> 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
> 	at java.util.concurrent.FutureTask.run(FutureTask.java:262)
> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
> 	at java.lang.Thread.run(Thread.java:745)
> {noformat}
> {noformat}
> 2015-07-24 08:23:01,359 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-137:ctx-bcdafb76)
(logid:a20017dd) Seq 80-6161487240196259885: Response Received: 
> 2015-07-24 08:23:01,370 DEBUG [c.c.a.t.Request] (DirectAgent-137:ctx-bcdafb76) (logid:a20017dd)
Seq 80-6161487240196259885: Processing:  { Ans: , MgmtId: 345051581208, via: 80, Ver: v1,
Flags: 10, [{"com.cloud.agent.api.CreateVMSnapshotAnswer":{"volumeTOs":[{"uuid":"a89b4ad5-f23f-4df6-84a8-89c4f40b2edb","volumeType":"ROOT","volumeState":"Ready","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"346b381a-8543-3f7b-9eff-fa909ad243c7","id":205,"poolType":"NetworkFilesystem","host":"10.144.35.110","path":"/tintri/ECS-SR-CLD200","port":2049,"url":"NetworkFilesystem://10.144.35.110/tintri/ECS-SR-CLD200/?ROLE=Primary&STOREUUID=346b381a-8543-3f7b-9eff-fa909ad243c7"}},"name":"ROOT-16119","size":33386,"path":"ROOT-16119-000001","volumeId":19311,"vmName":"i-84987-16119-VM","vmState":"Running","accountId":84987,"chainInfo":"{\"diskDeviceBusName\":\"ide0:1\",\"diskChain\":[\"[346b381a85433f7b9efffa909ad243c7]
i-84987-16119-VM/ROOT-16119.vmdk\",\"[346b381a85433f7b9efffa909ad243c7] 49f59e1a4ce23fec8890c8b9e5891d56/49f59e1a4ce23fec8890c8b9e5891d56.vmdk\"]}","format":"OVA","provisioningType":"THIN","id":19311,"deviceId":0,"cacheMode":"NONE","hypervisorType":"VMware"}],"vmSnapshotTo":{"id":964,"snapshotName":"i-84987-16119-VM_VS_20150724152253","type":"Disk","current":false,"description":"unit-test-instance-snapshot","quiescevm":false},"result":true,"details":"","wait":0}}]
}
> 2015-07-24 08:23:01,370 DEBUG [c.c.a.t.Request] (Work-Job-Executor-63:ctx-f077facb job-64839/job-64840
ctx-85ac1d8c) (logid:a20017dd) Seq 80-6161487240196259885: Received:  { Ans: , MgmtId: 345051581208,
via: 80, Ver: v1, Flags: 10, { CreateVMSnapshotAnswer } }
> 2015-07-24 08:23:01,382 DEBUG [o.a.c.s.v.DefaultVMSnapshotStrategy] (Work-Job-Executor-63:ctx-f077facb
job-64839/job-64840 ctx-85ac1d8c) (logid:a20017dd) Create vm snapshot i-84987-16119-VM_VS_20150724152253
succeeded for vm: i-84987-16119-VM
> 2015-07-24 08:23:01,396 DEBUG [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Executor-63:ctx-f077facb
job-64839/job-64840 ctx-85ac1d8c) (logid:a20017dd) Done executing VM work job: com.cloud.vm.snapshot.VmWorkCreateVMSnapshot{"vmSnapshotId":964,"quiesceVm":false,"userId":1,"accountId":1,"vmId":16119,"handlerName":"VMSnapshotManagerImpl"}
> {noformat}
> It is not clear from this log what is causing this error "failed to create snapshot for
vm:i-84987-16119-VM due to null"
> Interestingly, this VM has guest OS type set to None (138).
> REPRO STEPS
> ==================
> Try taking snapshot for 12 min after VM creation. VM snapshot fails for 12 mins after
VM creation.
> EXPECTED BEHAVIOR
> ==================
> VM snapshot should be successful from the moment the VM is ready.
> ACTUAL BEHAVIOR
> ==================
> VM snapshot is not successful for ~12 minutes



--
This message was sent by Atlassian JIRA
(v6.3.4#6332)

Mime
View raw message