[ https://issues.apache.org/jira/browse/CLOUDSTACK-9356?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15879078#comment-15879078 ]
ASF GitHub Bot commented on CLOUDSTACK-9356: -------------------------------------------- Github user blueorangutan commented on the issue: https://github.com/apache/cloudstack/pull/1903 <b>Trillian test result (tid-883)</b> Environment: kvm-centos7 (x2), Advanced Networking with Mgmt server 7 Total time taken: 33531 seconds Marvin logs: https://github.com/blueorangutan/acs-prs/releases/download/trillian/pr1903-t883-kvm-centos7.zip Intermitten failure detected: /marvin/tests/smoke/test_privategw_acl.py Intermitten failure detected: /marvin/tests/smoke/test_vpc_redundant.py Test completed. 46 look ok, 2 have error(s) Test | Result | Time (s) | Test File --- | --- | --- | --- test_02_redundant_VPC_default_routes | `Failure` | 864.37 | test_vpc_redundant.py test_04_rvpc_privategw_static_routes | `Failure` | 370.91 | test_privategw_acl.py test_01_vpc_site2site_vpn | Success | 150.49 | test_vpc_vpn.py test_01_vpc_remote_access_vpn | Success | 61.30 | test_vpc_vpn.py test_01_redundant_vpc_site2site_vpn | Success | 235.86 | test_vpc_vpn.py test_02_VPC_default_routes | Success | 282.35 | test_vpc_router_nics.py test_01_VPC_nics_after_destroy | Success | 459.32 | test_vpc_router_nics.py test_05_rvpc_multi_tiers | Success | 517.05 | test_vpc_redundant.py test_04_rvpc_network_garbage_collector_nics | Success | 1408.57 | test_vpc_redundant.py test_03_create_redundant_VPC_1tier_2VMs_2IPs_2PF_ACL_reboot_routers | Success | 537.91 | test_vpc_redundant.py test_01_create_redundant_VPC_2tiers_4VMs_4IPs_4PF_ACL | Success | 1264.82 | test_vpc_redundant.py test_09_delete_detached_volume | Success | 156.60 | test_volumes.py test_08_resize_volume | Success | 156.50 | test_volumes.py test_07_resize_fail | Success | 161.59 | test_volumes.py test_06_download_detached_volume | Success | 156.34 | test_volumes.py test_05_detach_volume | Success | 155.92 | test_volumes.py test_04_delete_attached_volume | Success | 151.24 | test_volumes.py test_03_download_attached_volume | Success | 156.42 | test_volumes.py test_02_attach_volume | Success | 126.00 | test_volumes.py test_01_create_volume | Success | 622.27 | test_volumes.py test_deploy_vm_multiple | Success | 273.56 | test_vm_life_cycle.py test_deploy_vm | Success | 0.03 | test_vm_life_cycle.py test_advZoneVirtualRouter | Success | 0.03 | test_vm_life_cycle.py test_10_attachAndDetach_iso | Success | 26.62 | test_vm_life_cycle.py test_09_expunge_vm | Success | 125.21 | test_vm_life_cycle.py test_08_migrate_vm | Success | 35.94 | test_vm_life_cycle.py test_07_restore_vm | Success | 0.13 | test_vm_life_cycle.py test_06_destroy_vm | Success | 125.89 | test_vm_life_cycle.py test_03_reboot_vm | Success | 125.97 | test_vm_life_cycle.py test_02_start_vm | Success | 5.15 | test_vm_life_cycle.py test_01_stop_vm | Success | 40.36 | test_vm_life_cycle.py test_CreateTemplateWithDuplicateName | Success | 75.75 | test_templates.py test_08_list_system_templates | Success | 0.04 | test_templates.py test_07_list_public_templates | Success | 0.05 | test_templates.py test_05_template_permissions | Success | 0.07 | test_templates.py test_04_extract_template | Success | 5.25 | test_templates.py test_03_delete_template | Success | 5.11 | test_templates.py test_02_edit_template | Success | 90.16 | test_templates.py test_01_create_template | Success | 35.42 | test_templates.py test_10_destroy_cpvm | Success | 131.73 | test_ssvm.py test_09_destroy_ssvm | Success | 163.61 | test_ssvm.py test_08_reboot_cpvm | Success | 101.35 | test_ssvm.py test_07_reboot_ssvm | Success | 133.83 | test_ssvm.py test_06_stop_cpvm | Success | 131.53 | test_ssvm.py test_05_stop_ssvm | Success | 133.69 | test_ssvm.py test_04_cpvm_internals | Success | 0.98 | test_ssvm.py test_03_ssvm_internals | Success | 3.34 | test_ssvm.py test_02_list_cpvm_vm | Success | 0.15 | test_ssvm.py test_01_list_sec_storage_vm | Success | 0.13 | test_ssvm.py test_01_snapshot_root_disk | Success | 11.25 | test_snapshots.py test_04_change_offering_small | Success | 242.71 | test_service_offerings.py test_03_delete_service_offering | Success | 0.04 | test_service_offerings.py test_02_edit_service_offering | Success | 0.06 | test_service_offerings.py test_01_create_service_offering | Success | 0.12 | test_service_offerings.py test_02_sys_template_ready | Success | 0.14 | test_secondary_storage.py test_01_sys_vm_start | Success | 0.20 | test_secondary_storage.py test_09_reboot_router | Success | 40.39 | test_routers.py test_08_start_router | Success | 30.30 | test_routers.py test_07_stop_router | Success | 10.16 | test_routers.py test_06_router_advanced | Success | 0.06 | test_routers.py test_05_router_basic | Success | 0.04 | test_routers.py test_04_restart_network_wo_cleanup | Success | 5.72 | test_routers.py test_03_restart_network_cleanup | Success | 60.57 | test_routers.py test_02_router_internal_adv | Success | 1.05 | test_routers.py test_01_router_internal_basic | Success | 0.58 | test_routers.py test_router_dns_guestipquery | Success | 76.75 | test_router_dns.py test_router_dns_externalipquery | Success | 0.08 | test_router_dns.py test_router_dhcphosts | Success | 278.69 | test_router_dhcphosts.py test_router_dhcp_opts | Success | 21.70 | test_router_dhcphosts.py test_01_updatevolumedetail | Success | 0.10 | test_resource_detail.py test_01_reset_vm_on_reboot | Success | 166.17 | test_reset_vm_on_reboot.py test_createRegion | Success | 0.04 | test_regions.py test_create_pvlan_network | Success | 5.23 | test_pvlan.py test_dedicatePublicIpRange | Success | 0.42 | test_public_ip_range.py test_03_vpc_privategw_restart_vpc_cleanup | Success | 495.87 | test_privategw_acl.py test_02_vpc_privategw_static_routes | Success | 360.94 | test_privategw_acl.py test_01_vpc_privategw_acl | Success | 82.91 | test_privategw_acl.py test_01_primary_storage_nfs | Success | 35.81 | test_primary_storage.py test_createPortablePublicIPRange | Success | 15.19 | test_portable_publicip.py test_createPortablePublicIPAcquire | Success | 15.51 | test_portable_publicip.py test_isolate_network_password_server | Success | 89.56 | test_password_server.py test_UpdateStorageOverProvisioningFactor | Success | 0.15 | test_over_provisioning.py test_oobm_zchange_password | Success | 30.75 | test_outofbandmanagement.py test_oobm_multiple_mgmt_server_ownership | Success | 16.33 | test_outofbandmanagement.py test_oobm_issue_power_status | Success | 5.27 | test_outofbandmanagement.py test_oobm_issue_power_soft | Success | 15.35 | test_outofbandmanagement.py test_oobm_issue_power_reset | Success | 15.37 | test_outofbandmanagement.py test_oobm_issue_power_on | Success | 15.34 | test_outofbandmanagement.py test_oobm_issue_power_off | Success | 15.33 | test_outofbandmanagement.py test_oobm_issue_power_cycle | Success | 15.32 | test_outofbandmanagement.py test_oobm_enabledisable_across_clusterzones | Success | 82.65 | test_outofbandmanagement.py test_oobm_enable_feature_valid | Success | 5.17 | test_outofbandmanagement.py test_oobm_enable_feature_invalid | Success | 0.10 | test_outofbandmanagement.py test_oobm_disable_feature_valid | Success | 5.18 | test_outofbandmanagement.py test_oobm_disable_feature_invalid | Success | 0.10 | test_outofbandmanagement.py test_oobm_configure_invalid_driver | Success | 0.08 | test_outofbandmanagement.py test_oobm_configure_default_driver | Success | 0.08 | test_outofbandmanagement.py test_oobm_background_powerstate_sync | Success | 23.48 | test_outofbandmanagement.py test_extendPhysicalNetworkVlan | Success | 15.33 | test_non_contigiousvlan.py test_01_nic | Success | 404.13 | test_nic.py test_releaseIP | Success | 268.23 | test_network.py test_reboot_router | Success | 404.26 | test_network.py test_public_ip_user_account | Success | 10.25 | test_network.py test_public_ip_admin_account | Success | 40.30 | test_network.py test_network_rules_acquired_public_ip_3_Load_Balancer_Rule | Success | 66.82 | test_network.py test_network_rules_acquired_public_ip_2_nat_rule | Success | 61.52 | test_network.py test_network_rules_acquired_public_ip_1_static_nat_rule | Success | 120.72 | test_network.py test_delete_account | Success | 298.32 | test_network.py test_02_port_fwd_on_non_src_nat | Success | 55.68 | test_network.py test_01_port_fwd_on_src_nat | Success | 111.79 | test_network.py test_nic_secondaryip_add_remove | Success | 202.59 | test_multipleips_per_nic.py login_test_saml_user | Success | 19.26 | test_login.py test_assign_and_removal_lb | Success | 133.16 | test_loadbalance.py test_02_create_lb_rule_non_nat | Success | 187.46 | test_loadbalance.py test_01_create_lb_rule_src_nat | Success | 187.48 | test_loadbalance.py test_03_list_snapshots | Success | 0.06 | test_list_ids_parameter.py test_02_list_templates | Success | 0.04 | test_list_ids_parameter.py test_01_list_volumes | Success | 0.03 | test_list_ids_parameter.py test_07_list_default_iso | Success | 0.06 | test_iso.py test_05_iso_permissions | Success | 0.07 | test_iso.py test_04_extract_Iso | Success | 5.20 | test_iso.py test_03_delete_iso | Success | 95.12 | test_iso.py test_02_edit_iso | Success | 0.06 | test_iso.py test_01_create_iso | Success | 21.13 | test_iso.py test_04_rvpc_internallb_haproxy_stats_on_all_interfaces | Success | 198.11 | test_internal_lb.py test_03_vpc_internallb_haproxy_stats_on_all_interfaces | Success | 142.90 | test_internal_lb.py test_02_internallb_roundrobin_1RVPC_3VM_HTTP_port80 | Success | 504.47 | test_internal_lb.py test_01_internallb_roundrobin_1VPC_3VM_HTTP_port80 | Success | 409.72 | test_internal_lb.py test_dedicateGuestVlanRange | Success | 10.27 | test_guest_vlan_range.py test_UpdateConfigParamWithScope | Success | 0.14 | test_global_settings.py test_rolepermission_lifecycle_update | Success | 6.20 | test_dynamicroles.py test_rolepermission_lifecycle_list | Success | 6.00 | test_dynamicroles.py test_rolepermission_lifecycle_delete | Success | 5.86 | test_dynamicroles.py test_rolepermission_lifecycle_create | Success | 5.91 | test_dynamicroles.py test_rolepermission_lifecycle_concurrent_updates | Success | 6.01 | test_dynamicroles.py test_role_lifecycle_update_role_inuse | Success | 6.07 | test_dynamicroles.py test_role_lifecycle_update | Success | 10.99 | test_dynamicroles.py test_role_lifecycle_list | Success | 5.93 | test_dynamicroles.py test_role_lifecycle_delete | Success | 10.97 | test_dynamicroles.py test_role_lifecycle_create | Success | 6.04 | test_dynamicroles.py test_role_inuse_deletion | Success | 6.19 | test_dynamicroles.py test_role_account_acls_multiple_mgmt_servers | Success | 8.19 | test_dynamicroles.py test_role_account_acls | Success | 8.28 | test_dynamicroles.py test_default_role_deletion | Success | 6.03 | test_dynamicroles.py test_04_create_fat_type_disk_offering | Success | 0.08 | 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.07 | test_disk_offerings.py test_01_create_disk_offering | Success | 0.12 | test_disk_offerings.py test_deployvm_userdispersing | Success | 40.97 | test_deploy_vms_with_varied_deploymentplanners.py test_deployvm_userconcentrated | Success | 20.58 | test_deploy_vms_with_varied_deploymentplanners.py test_deployvm_firstfit | Success | 55.67 | test_deploy_vms_with_varied_deploymentplanners.py test_deployvm_userdata_post | Success | 10.38 | test_deploy_vm_with_userdata.py test_deployvm_userdata | Success | 71.39 | test_deploy_vm_with_userdata.py test_02_deploy_vm_root_resize | Success | 6.00 | test_deploy_vm_root_resize.py test_01_deploy_vm_root_resize | Success | 6.03 | test_deploy_vm_root_resize.py test_00_deploy_vm_root_resize | Success | 207.56 | test_deploy_vm_root_resize.py test_deploy_vm_from_iso | Success | 207.39 | test_deploy_vm_iso.py test_DeployVmAntiAffinityGroup | Success | 65.99 | test_affinity_groups.py test_03_delete_vm_snapshots | Skipped | 0.00 | test_vm_snapshots.py test_02_revert_vm_snapshots | Skipped | 0.00 | test_vm_snapshots.py test_01_test_vm_volume_snapshot | Skipped | 0.00 | test_vm_snapshots.py test_01_create_vm_snapshots | Skipped | 0.00 | test_vm_snapshots.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 | 0.00 | test_scale_vm.py test_01_primary_storage_iscsi | Skipped | 0.04 | 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 > VPC add VPN User fails same error as CLOUDSTACK-8927 > ---------------------------------------------------- > > Key: CLOUDSTACK-9356 > URL: https://issues.apache.org/jira/browse/CLOUDSTACK-9356 > Project: CloudStack > Issue Type: Bug > Security Level: Public(Anyone can view this level - this is the > default.) > Components: Management Server, VPC, XenServer > Affects Versions: 4.8.0, 4.9.0 > Environment: Two CentOS7 MGMT Servers, Two XenServerClusters, > Advanced Networking, VLAN isolated > Reporter: Thomas > Priority: Critical > > When we try to add an VPN User on a VPC following error occurs: > Management Server: > --- > Apr 20 09:24:43 WARN [resource.virtualnetwork.VirtualRoutingResource] > (DirectAgent-68:ctx-de5cbf45) (logid:180e35ed) Expected 1 answers while > executing VpnUsersCfgCommand but received 2 > Apr 20 09:24:43 admin02 server: WARN [c.c.a.r.v.VirtualRoutingResource] > (DirectAgent-68:ctx-de5cbf45) (logid:180e35ed) Expected 1 answers while > executing VpnUsersCfgCommand but received 2 > Apr 20 09:24:47 WARN [resource.virtualnetwork.VirtualRoutingResource] > (DirectAgent-268:ctx-873174f6) (logid:180e35ed) Expected 1 answers while > executing VpnUsersCfgCommand but received 2 > Apr 20 09:24:47 admin02 server: WARN [c.c.a.r.v.VirtualRoutingResource] > (DirectAgent-268:ctx-873174f6) (logid:180e35ed) Expected 1 answers while > executing VpnUsersCfgCommand but received 2 > Apr 20 09:24:47 WARN [network.vpn.RemoteAccessVpnManagerImpl] > (API-Job-Executor-58:ctx-7f86f610 job-1169 ctx-1073feac) (logid:180e35ed) > Unable to apply vpn users > Apr 20 09:24:47 localhost java.lang.IndexOutOfBoundsException: Index: 1, > Size: 1 > Apr 20 09:24:47 localhost at > java.util.ArrayList.rangeCheck(ArrayList.java:653) > Apr 20 09:24:47 localhost at java.util.ArrayList.get(ArrayList.java:429) > Apr 20 09:24:47 localhost at > com.cloud.network.vpn.RemoteAccessVpnManagerImpl.applyVpnUsers(RemoteAccessVpnManagerImpl.java:532) > Apr 20 09:24:47 localhost at > sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > Apr 20 09:24:47 localhost at > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) > Apr 20 09:24:47 localhost at > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > Apr 20 09:24:47 localhost at > java.lang.reflect.Method.invoke(Method.java:498) > Apr 20 09:24:47 localhost at > org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) > Apr 20 09:24:47 localhost at > org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183) > Apr 20 09:24:47 localhost at > org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150) > Apr 20 09:24:47 localhost at > org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91) > Apr 20 09:24:47 localhost at > org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) > Apr 20 09:24:47 localhost at > org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) > Apr 20 09:24:47 localhost at > com.sun.proxy.$Proxy234.applyVpnUsers(Unknown Source) > Apr 20 09:24:47 localhost at > org.apache.cloudstack.api.command.user.vpn.AddVpnUserCmd.execute(AddVpnUserCmd.java:122) > Apr 20 09:24:47 localhost at > com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:150) > Apr 20 09:24:47 localhost at > com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.java:108) > Apr 20 09:24:47 localhost at > org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:554) > Apr 20 09:24:47 localhost at > org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49) > Apr 20 09:24:47 localhost at > org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56) > Apr 20 09:24:47 localhost at > org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103) > Apr 20 09:24:47 localhost at > org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53) > Apr 20 09:24:47 localhost at > org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46) > Apr 20 09:24:47 localhost at > org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:502) > Apr 20 09:24:47 localhost at > java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) > Apr 20 09:24:47 localhost at > java.util.concurrent.FutureTask.run(FutureTask.java:266) > Apr 20 09:24:47 localhost at > java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) > Apr 20 09:24:47 localhost at > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) > Apr 20 09:24:47 localhost at java.lang.Thread.run(Thread.java:745) > Apr 20 09:24:47 admin02 server: WARN [c.c.n.v.RemoteAccessVpnManagerImpl] > (API-Job-Executor-58:ctx-7f86f610 job-1169 ctx-1073feac) (logid:180e35ed) > Unable to apply vpn users > Apr 20 09:24:47 admin02 server: java.lang.IndexOutOfBoundsException: Index: > 1, Size: 1 > Apr 20 09:24:47 admin02 server: at > java.util.ArrayList.rangeCheck(ArrayList.java:653) > Apr 20 09:24:47 admin02 server: at java.util.ArrayList.get(ArrayList.java:429) > Apr 20 09:24:47 admin02 server: at > com.cloud.network.vpn.RemoteAccessVpnManagerImpl.applyVpnUsers(RemoteAccessVpnManagerImpl.java:532) > Apr 20 09:24:47 admin02 server: at > sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > Apr 20 09:24:47 admin02 server: at > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) > Apr 20 09:24:47 admin02 server: at > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > Apr 20 09:24:47 admin02 server: at > java.lang.reflect.Method.invoke(Method.java:498) > Apr 20 09:24:47 admin02 server: at > org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) > Apr 20 09:24:47 admin02 server: at > org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183) > Apr 20 09:24:47 admin02 server: at > org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150) > Apr 20 09:24:47 admin02 server: at > org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91) > Apr 20 09:24:47 admin02 server: at > org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) > Apr 20 09:24:47 admin02 server: at > org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) > Apr 20 09:24:47 admin02 server: at > com.sun.proxy.$Proxy234.applyVpnUsers(Unknown Source) > Apr 20 09:24:47 admin02 server: at > org.apache.cloudstack.api.command.user.vpn.AddVpnUserCmd.execute(AddVpnUserCmd.java:122) > Apr 20 09:24:47 admin02 server: at > com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:150) > Apr 20 09:24:47 admin02 server: at > com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.java:108) > Apr 20 09:24:47 admin02 server: at > org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:554) > Apr 20 09:24:47 admin02 server: at > org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49) > Apr 20 09:24:47 admin02 server: at > org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56) > Apr 20 09:24:47 admin02 server: at > org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103) > Apr 20 09:24:47 admin02 server: at > org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53) > Apr 20 09:24:47 admin02 server: at > org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46) > Apr 20 09:24:47 admin02 server: at > org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:502) > Apr 20 09:24:47 admin02 server: at > java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) > Apr 20 09:24:47 admin02 server: at > java.util.concurrent.FutureTask.run(FutureTask.java:266) > Apr 20 09:24:47 admin02 server: at > java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) > Apr 20 09:24:47 admin02 server: at > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) > Apr 20 09:24:47 admin02 server: at java.lang.Thread.run(Thread.java:745) > Apr 20 09:24:48 WARN [resource.virtualnetwork.VirtualRoutingResource] > (DirectAgent-273:ctx-e224fd43) (logid:180e35ed) Expected 1 answers while > executing VpnUsersCfgCommand but received 2 > Apr 20 09:24:48 admin02 server: WARN [c.c.a.r.v.VirtualRoutingResource] > (DirectAgent-273:ctx-e224fd43) (logid:180e35ed) Expected 1 answers while > executing VpnUsersCfgCommand but received 2 > Apr 20 09:24:49 admin02 server: INFO [o.a.c.f.j.i.AsyncJobManagerImpl] > (AsyncJobMgr-Heartbeat-1:ctx-1a18ac7a) (logid:51e6f749) Begin cleanup expired > async-jobs > Apr 20 09:24:49 admin02 server: INFO [o.a.c.f.j.i.AsyncJobManagerImpl] > (AsyncJobMgr-Heartbeat-1:ctx-1a18ac7a) (logid:51e6f749) End cleanup expired > async-jobs > Apr 20 09:24:51 WARN [resource.virtualnetwork.VirtualRoutingResource] > (DirectAgent-137:ctx-f25bab62) (logid:180e35ed) Expected 1 answers while > executing VpnUsersCfgCommand but received 2 > Apr 20 09:24:51 admin02 server: WARN [c.c.a.r.v.VirtualRoutingResource] > (DirectAgent-137:ctx-f25bab62) (logid:180e35ed) Expected 1 answers while > executing VpnUsersCfgCommand but received 2 > Apr 20 09:24:51 admin02 server: INFO [o.a.c.f.j.i.AsyncJobMonitor] > (API-Job-Executor-58:ctx-7f86f610 job-1169) (logid:180e35ed) Remove job-1169 > from job monitoring > RouterVM: > --- > 2016-04-20 07:34:49,139 merge.py load:60 Loading data bag type vpnuserlist > 2016-04-20 07:34:49,140 merge.py process:100 Command of type vpnuserlist > received > 2016-04-20 07:34:49,141 merge.py save:71 Writing data bag type vpnuserlist > 2016-04-20 07:34:49,141 merge.py save:72 {u'test': {u'add': True, > u'password': u'test', u'user': u'test'}, u'id': u'vpnuserlist'} > 2016-04-20 07:34:49,142 merge.py load:60 Loading data bag type ips > 2016-04-20 07:34:49,142 merge.py load:60 Loading data bag type cmdline > 2016-04-20 07:34:49,143 configure.py main:925 Configuring ip addresses > 2016-04-20 07:34:49,143 CsHelper.py execute:160 Executing: ip addr show dev > eth1 > 2016-04-20 07:34:49,155 CsHelper.py execute:160 Executing: ip addr show dev > eth0 > 2016-04-20 07:34:49,166 CsHelper.py execute:160 Executing: ip addr show dev > eth1 > 2016-04-20 07:34:49,177 CsAddress.py process:110 Address found in DataBag > ==> {u'public_ip': u'192.168.8.149', u'one_to_one_nat': False, u'nic_dev_id': > 1, u'network': u'192.168.8.144/28', u'first_i_p': True, u'netmask': > u'255.255.255.240', u'source_nat': True, u'broadcast': u'192.168.8.159', > u'add': True, u'nw_type': u'public', u'device': u'eth1', u'vif_mac_address': > u'06:39:da:00:02:02', u'cidr': u'192.168.8.149/28', u'gateway': > u'192.168.8.145', u'new_nic': False, u'size': u'28'} > 2016-04-20 07:34:49,178 CsAddress.py process:119 Address 192.168.8.149/28 on > device eth1 not configured > 2016-04-20 07:34:49,179 CsAddress.py configure:277 Configuring address > 192.168.8.149/28 on device eth1 > 2016-04-20 07:34:49,189 CsRoute.py add_table:38 Adding route table: 1 > Table_eth1 to /etc/iproute2/rt_tables if not present > 2016-04-20 07:34:49,190 CsHelper.py execute:160 Executing: ip rule show > 2016-04-20 07:34:49,201 CsHelper.py execute:160 Executing: ip link show eth1 > | grep 'state DOWN' > 2016-04-20 07:34:49,216 CsHelper.py execute:160 Executing: arping -c 1 -I > eth1 -A -U -s 192.168.8.149 192.168.8.145 > 2016-04-20 07:34:49,227 CsAddress.py inKernel:689 rpsfr is present in the > kernel > 2016-04-20 07:34:49,227 CsAddress.py cpus:699 Single CPU machine > 2016-04-20 07:34:49,227 CsRoute.py add_table:38 Adding route table: 1 > Table_eth1 to /etc/iproute2/rt_tables if not present > 2016-04-20 07:34:49,228 CsRoute.py add_route:50 Adding route: dev eth1 > table: Table_eth1 network: 192.168.8.144/28 if not present > 2016-04-20 07:34:49,228 CsHelper.py execute:160 Executing: ip route show dev > eth1 table Table_eth1 192.168.8.144/28 > 2016-04-20 07:34:49,239 CsRoute.py set_route:60 Add dev eth1 table > Table_eth1 192.168.8.144/28 > 2016-04-20 07:34:49,239 CsHelper.py execute:160 Executing: ip route add dev > eth1 table Table_eth1 192.168.8.144/28 > 2016-04-20 07:34:49,251 CsRoute.py defaultroute_exists:89 Checking if > default ipv4 route is present > 2016-04-20 07:34:49,251 CsHelper.py execute:160 Executing: ip -4 route list > 0/0 > 2016-04-20 07:34:49,262 CsRoute.py defaultroute_exists:96 No default route > found! > 2016-04-20 07:34:49,262 CsRoute.py add_defaultroute:81 Adding default route > 2016-04-20 07:34:49,262 CsHelper.py execute:160 Executing: ip route show > default via 192.168.8.145 > 2016-04-20 07:34:49,273 CsRoute.py set_route:60 Add default via 192.168.8.145 > 2016-04-20 07:34:49,273 CsHelper.py execute:160 Executing: ip route add > default via 192.168.8.145 > 2016-04-20 07:34:49,284 CsHelper.py execute:160 Executing: ip addr show dev > eth0 > 2016-04-20 07:34:49,296 CsAddress.py process:110 Address found in DataBag > ==> {u'public_ip': u'169.254.1.192', u'one_to_one_nat': False, u'nic_dev_id': > u'0', u'network': u'169.254.0.0/16', u'netmask': u'255.255.0.0', > u'source_nat': False, u'broadcast': u'169.254.255.255', u'add': True, > u'nw_type': u'control', u'device': u'eth0', u'cidr': u'169.254.1.192/16', > u'gateway': u'None', u'size': u'16'} > 2016-04-20 07:34:49,296 CsAddress.py process:119 Address 169.254.1.192/16 on > device eth0 not configured > 2016-04-20 07:34:49,297 CsAddress.py configure:277 Configuring address > 169.254.1.192/16 on device eth0 > 2016-04-20 07:34:49,308 merge.py load:57 Creating data bag type networkacl > 2016-04-20 07:34:49,308 merge.py load:57 Creating data bag type firewallrules > 2016-04-20 07:34:49,309 merge.py load:57 Creating data bag type > forwardingrules > 2016-04-20 07:34:49,309 merge.py load:57 Creating data bag type site2sitevpn > 2016-04-20 07:34:49,310 merge.py load:60 Loading data bag type > remoteaccessvpn > 2016-04-20 07:34:49,311 configure.py process:624 {u'192.168.8.149': > {u'local_ip': u'10.1.2.1', u'preshared_key': u'', u'create': True, > u'vpn_server_ip': u'192.168.8.149', u'local_cidr': u'10.0.0.0/16', > u'ip_range': u'10.1.2.2-10.1.2.8', u'public_interface': u'eth1', u'type': > u'remoteaccessvpn'}, u'id': u'remoteaccessvpn'} > 2016-04-20 07:34:49,311 configure.py process:632 Enabling remote access vpn > on 192.168.8.149 > 2016-04-20 07:34:49,311 CsHelper.py execute2:181 Executing: service ipsec > status > 2016-04-20 07:34:49,388 CsHelper.py execute2:181 Executing: service ipsec > start > 2016-04-20 07:34:49,779 CsFile.py load:39 Reading file /etc/ipsec.d/l2tp.conf > 2016-04-20 07:34:49,780 CsFile.py search:123 Searching for left= and > replacing with left=192.168.8.149 > 2016-04-20 07:34:49,780 CsFile.py commit:60 Nothing to commit. The > /etc/ipsec.d/l2tp.conf file did not change > 2016-04-20 07:34:49,780 CsFile.py load:39 Reading file > /etc/ipsec.d/ipsec.any.secrets > 2016-04-20 07:34:49,781 CsFile.py search:123 Searching for : PSK ""= and > replacing with : PSK "****" > 2016-04-20 07:34:49,781 CsFile.py commit:60 Nothing to commit. The > /etc/ipsec.d/ipsec.any.secrets file did not change > 2016-04-20 07:34:49,781 CsFile.py load:39 Reading file > /etc/xl2tpd/xl2tpd.conf > 2016-04-20 07:34:49,781 CsFile.py search:123 Searching for ip range = and > replacing with ip range = 10.1.2.2-10.1.2.8 > 2016-04-20 07:34:49,781 CsFile.py search:123 Searching for local ip = and > replacing with local ip = 10.1.2.1 > 2016-04-20 07:34:49,782 CsFile.py commit:60 Nothing to commit. The > /etc/xl2tpd/xl2tpd.conf file did not change > 2016-04-20 07:34:49,782 CsFile.py load:39 Reading file > /etc/ppp/options.xl2tpd > 2016-04-20 07:34:49,782 CsFile.py search:123 Searching for ms-dns and > replacing with ms-dns 10.1.2.1 > 2016-04-20 07:34:49,782 CsFile.py commit:60 Nothing to commit. The > /etc/ppp/options.xl2tpd file did not change > 2016-04-20 07:34:49,783 configure.py process:635 Remote accessvpn data bag > {u'192.168.8.149': {u'local_ip': u'10.1.2.1', u'preshared_key': u'', > u'create': True, u'vpn_server_ip': u'192.168.8.149', u'local_cidr': > u'10.0.0.0/16', u'ip_range': u'10.1.2.2-10.1.2.8', u'public_interface': > u'eth1', u'type': u'remoteaccessvpn'}, u'id': u'remoteaccessvpn'} > 2016-04-20 07:34:49,784 CsHelper.py execute:160 Executing: ipsec auto > --rereadall > 2016-04-20 07:34:49,851 CsHelper.py execute:160 Executing: service xl2tpd > stop > 2016-04-20 07:34:49,879 CsHelper.py execute:160 Executing: service xl2tpd > start > 2016-04-20 07:34:49,896 CsHelper.py execute:160 Executing: ipsec auto > --rereadsecrets > 2016-04-20 07:34:49,916 CsHelper.py execute:160 Executing: ipsec auto > --replace L2TP-PSK > 2016-04-20 07:34:49,936 merge.py load:57 Creating data bag type loadbalancer > 2016-04-20 07:34:49,937 configure.py main:984 Configuring vpn users list > 2016-04-20 07:34:49,937 merge.py load:60 Loading data bag type vpnuserlist > 2016-04-20 07:34:49,937 configure.py add_l2tp_ipsec_user:575 Adding vpn user > test \* test \* > 2016-04-20 07:34:49,937 CsFile.py load:39 Reading file /etc/ppp/chap-secrets > 2016-04-20 07:34:49,937 CsFile.py searchString:139 Searching for test \* > test \* string > 2016-04-20 07:34:49,938 configure.py add_l2tp_ipsec_user:580 User is not > there already, so adding user > 2016-04-20 07:34:49,938 configure.py del_l2tp_ipsec_user:591 Deleting the > user test > 2016-04-20 07:34:49,938 CsFile.py load:39 Reading file /etc/ppp/chap-secrets > 2016-04-20 07:34:49,938 CsFile.py deleteLine:154 Searching for test \* test > \* to remove the line > 2016-04-20 07:34:49,938 CsFile.py commit:60 Nothing to commit. The > /etc/ppp/chap-secrets file did not change > 2016-04-20 07:34:49,939 CsFile.py commit:66 Wrote edited file > /etc/ppp/chap-secrets > 2016-04-20 07:34:49,939 CsFile.py commit:68 Updated file in-cache > configuration > 2016-04-20 07:34:49,939 CsRedundant.py set:68 Router redundancy status is > True > 2016-04-20 07:34:49,939 CsRedundant.py set_backup:257 Setting router to > backup > 2016-04-20 07:34:49,939 CsRedundant.py set_backup:264 Bringing public > interface eth1 down > 2016-04-20 07:34:49,939 CsHelper.py execute:160 Executing: ip link set eth1 > down > 2016-04-20 07:34:49,946 CsHelper.py execute:160 Executing: > /usr/sbin/conntrackd -C /etc/conntrackd/conntrackd.conf -d > 2016-04-20 07:34:49,953 CsHelper.py execute:160 Executing: service ipsec stop > 2016-04-20 07:34:51,449 CsHelper.py service:189 Service ipsec stop > 2016-04-20 07:34:51,449 CsHelper.py execute:160 Executing: service xl2tpd > stop > 2016-04-20 07:34:51,472 CsHelper.py service:189 Service xl2tpd stop > 2016-04-20 07:34:51,473 CsHelper.py execute:160 Executing: service dnsmasq > stop > 2016-04-20 07:34:51,506 CsHelper.py service:189 Service dnsmasq stop > 2016-04-20 07:34:51,507 merge.py save:71 Writing data bag type cmdline > 2016-04-20 07:34:51,507 merge.py save:72 {u'config': {u'domain': > u'cs2cloud.internal', u'disable_rp_filter': u'true', u'redundant_master': > False, u'port': u'8080', u'eth0ip': u'169.254.1.192', > u'baremetalnotificationapikey': > u'kWvqCTc8zyZ1FuA3wwlp8YX-U_WDrBQbgI97hKvDtg9iwVfhkKokRwTxZbGYazT-k8UDdS-4YycAzTrPR2sflg', > u'console': u'hvc0', u'debian-installer': u'en_US', u'template': u'domP', > u'ro': u'ro', u'type': u'vpcrouter', u'router_id': u'9', > u'baremetalnotificationsecuritykey': > u'lxZ4MlDFgA06DtVVF5cMdCfU4n-4FwVl3Qg11LCcWS0GXD8b_1zQz_ofAn2UgkXsbHFhX2dPGj2XECSJS0ZZSA', > u'eth0mask': u'255.255.0.0', u'vpccidr': u'10.0.0.0/16', u'host': > u'192.168.0.81', u'redundant_router': u'true', u'router_password': > u'12531972995023938399938864803700219656655913733374186216522671209788813769832463691507875752762600304946287225516798905049028588013552604921641695040609944', > u'redundant_state': 'BACKUP', u'name': u'r-76-VM', u'--': u'--', u'dns2': > u'212.123.96.110', u'dns1': u'192.168.0.10', u'quiet': u'quiet', u'root': > u'UUID'}, u'id': u'cmdline'} > 2016-04-20 07:34:51,508 CsRedundant.py set_backup:282 Router switched to > backup mode > 2016-04-20 07:34:51,508 CsHelper.py execute:160 Executing: service > conntrackd stop > 2016-04-20 07:34:51,540 CsHelper.py service:189 Service conntrackd stop > 2016-04-20 07:34:51,541 CsHelper.py execute:160 Executing: service > keepalived stop > 2016-04-20 07:34:51,563 CsHelper.py service:189 Service keepalived stop > 2016-04-20 07:34:51,564 CsHelper.py execute:160 Executing: mount -- This message was sent by Atlassian JIRA (v6.3.15#6346)