[ 
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)

Reply via email to