2025-10-30 04:10:01,947 - INFO - Domain Default found not creating 2025-10-30 04:10:02,125 - INFO - Project ctest-TestMd5testsOnControl-05837923 not found, creating it 2025-10-30 04:10:02,633 - INFO - Created Project:ctest-TestMd5testsOnControl-05837923, ID : 34604885-da03-4301-b0bb-fe9e593dcb41 2025-10-30 04:10:04,910 - DEBUG - Response for create_network : {'network': {'id': '06b327da-70a9-46dc-909a-59f9c1d854d6', 'name': 'test_vnv6sr', 'tenant_id': '34604885da034301b0bbfe9e593dcb41', 'project_id': '34604885da034301b0bbfe9e593dcb41', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-05837923', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-10-30 04:10:05,146 - DEBUG - Response for create_subnet : {'subnet': {'id': '2295f476-78c6-4c64-b96c-cff02fba03da', 'name': '', 'tenant_id': '34604885da034301b0bbfe9e593dcb41', 'network_id': '06b327da-70a9-46dc-909a-59f9c1d854d6', 'ip_version': 6, 'cidr': '2001::101:0/120', 'allocation_pools': [{'start': '2001::101:2', 'end': '2001::101:fe'}], 'gateway_ip': '2001::101:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2001::101:2', 'tags': [], 'project_id': '34604885da034301b0bbfe9e593dcb41'}} 2025-10-30 04:10:05,344 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b7ed9de7-a80f-47a0-9b8f-57363457f0c8', 'name': '', 'tenant_id': '34604885da034301b0bbfe9e593dcb41', 'network_id': '06b327da-70a9-46dc-909a-59f9c1d854d6', 'ip_version': 4, 'cidr': '166.216.50.0/26', 'allocation_pools': [{'start': '166.216.50.2', 'end': '166.216.50.62'}], 'gateway_ip': '166.216.50.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '166.216.50.2', 'tags': [], 'project_id': '34604885da034301b0bbfe9e593dcb41'}} 2025-10-30 04:10:05,368 - INFO - Created VN test_vnv6sr 2025-10-30 04:10:05,380 - DEBUG - VN test_vnv6sr UUID is 06b327da-70a9-46dc-909a-59f9c1d854d6 2025-10-30 04:10:05,870 - DEBUG - Response for create_network : {'network': {'id': '2d6d7bb9-4007-497c-b0be-7f719070561c', 'name': 'test_vnv6dn', 'tenant_id': '34604885da034301b0bbfe9e593dcb41', 'project_id': '34604885da034301b0bbfe9e593dcb41', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-05837923', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-10-30 04:10:06,112 - DEBUG - Response for create_subnet : {'subnet': {'id': 'f560b8eb-d5d0-4038-8692-43ad333a91b9', 'name': '', 'tenant_id': '34604885da034301b0bbfe9e593dcb41', 'network_id': '2d6d7bb9-4007-497c-b0be-7f719070561c', 'ip_version': 6, 'cidr': '2001::201:0/120', 'allocation_pools': [{'start': '2001::201:2', 'end': '2001::201:fe'}], 'gateway_ip': '2001::201:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2001::201:2', 'tags': [], 'project_id': '34604885da034301b0bbfe9e593dcb41'}} 2025-10-30 04:10:06,315 - DEBUG - Response for create_subnet : {'subnet': {'id': '5b11d66e-9b8f-49a5-b54d-b9cba63f8ff0', 'name': '', 'tenant_id': '34604885da034301b0bbfe9e593dcb41', 'network_id': '2d6d7bb9-4007-497c-b0be-7f719070561c', 'ip_version': 4, 'cidr': '144.176.199.128/26', 'allocation_pools': [{'start': '144.176.199.130', 'end': '144.176.199.190'}], 'gateway_ip': '144.176.199.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '144.176.199.130', 'tags': [], 'project_id': '34604885da034301b0bbfe9e593dcb41'}} 2025-10-30 04:10:06,349 - INFO - Created VN test_vnv6dn 2025-10-30 04:10:06,364 - DEBUG - VN test_vnv6dn UUID is 2d6d7bb9-4007-497c-b0be-7f719070561c 2025-10-30 04:10:06,584 - DEBUG - Services list from nova: [, , , ] 2025-10-30 04:10:07,816 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4535-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4535-2) 2025-10-30 04:10:09,289 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4535-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4535-1) 2025-10-30 04:10:09,289 - INFO - Waiting for VM source_vm to be up.. 2025-10-30 04:10:09,396 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-30 04:10:14,529 - DEBUG - VM is in ACTIVE state now 2025-10-30 04:10:14,530 - INFO - VM name : source_vm 2025-10-30 04:10:14,625 - DEBUG - VM source_vm ID is 209e1069-b7fd-4520-b1fe-cad0822cdf21 2025-10-30 04:10:14,651 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4535-2 2025-10-30 04:10:14,739 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/209e1069-b7fd-4520-b1fe-cad0822cdf21 2025-10-30 04:10:15,040 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/209e1069-b7fd-4520-b1fe-cad0822cdf21 2025-10-30 04:10:15,077 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/2b35696b-e2f0-4319-8864-eabbee100a51 2025-10-30 04:10:18,279 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1027ms') 2025-10-30 04:10:18,280 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-10-30 04:10:18,294 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-05837923:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-10-30 04:10:18,294 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-05837923:test_vnv6sr is 166.216.50.1 and allocation pool is NOT set 2025-10-30 04:10:20,362 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=5.95 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.26 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 2.264/4.106/5.948/1.842 ms') 2025-10-30 04:10:20,362 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-10-30 04:10:20,513 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-30 04:10:20,513 - DEBUG - Waiting to SSH to VM source_vm, IP 166.216.50.3, Port 22 2025-10-30 04:10:20,670 - DEBUG - VM source_vm is ready for SSH connections 2025-10-30 04:10:20,670 - INFO - Waiting for VM dest_vm to be up.. 2025-10-30 04:10:20,810 - DEBUG - VM is in ACTIVE state now 2025-10-30 04:10:20,810 - INFO - VM name : dest_vm 2025-10-30 04:10:21,027 - DEBUG - VM dest_vm ID is 64292fa3-3092-447e-9195-2128bf4d9574 2025-10-30 04:10:21,027 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4535-1 2025-10-30 04:10:21,179 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/64292fa3-3092-447e-9195-2128bf4d9574 2025-10-30 04:10:21,190 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/69752e14-4d3b-49ae-a170-0cac3de145fd 2025-10-30 04:10:22,367 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=3.93 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.674 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1001ms\r\nrtt min/avg/max/mdev = 0.674/2.302/3.931/1.628 ms') 2025-10-30 04:10:22,367 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-10-30 04:10:22,443 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-30 04:10:22,443 - DEBUG - Waiting to SSH to VM dest_vm, IP 144.176.199.131, Port 22 2025-10-30 04:10:22,510 - DEBUG - Error on ssh to cirros@169.254.0.3:22, result: /bin/bash: connect: Connection refused /bin/bash: line 1: /dev/tcp/169.254.0.3/22: Connection refused {'failed': True, 'command': '(echo > /dev/tcp/169.254.0.3/22)', 'real_command': '/bin/bash -l -c "(echo > /dev/tcp/169.254.0.3/22)"', 'return_code': 1, 'succeeded': False, 'stderr': ''} 2025-10-30 04:10:22,596 - DEBUG - VM dest_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-10-30 04:10:27,597 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-30 04:10:27,597 - DEBUG - Waiting to SSH to VM dest_vm, IP 144.176.199.131, Port 22 2025-10-30 04:10:27,767 - DEBUG - VM dest_vm is ready for SSH connections 2025-10-30 04:10:27,775 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-05837923:test_vnv6sr, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-05837923:test_vnv6dn, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-30 04:10:28,109 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-10-30 04:10:28,467 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-10-30 04:10:28,737 - INFO - Configure the policy with allow any 2025-10-30 04:10:29,048 - DEBUG - Response for create_network : {'network': {'id': '2c21af58-6cbd-4263-ba56-55bd64da321e', 'name': 'vn1', 'tenant_id': '34604885da034301b0bbfe9e593dcb41', 'project_id': '34604885da034301b0bbfe9e593dcb41', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-05837923', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-10-30 04:10:29,315 - DEBUG - Response for create_subnet : {'subnet': {'id': 'bfb91fad-9223-4aab-bd01-ef43ec0537ca', 'name': '', 'tenant_id': '34604885da034301b0bbfe9e593dcb41', 'network_id': '2c21af58-6cbd-4263-ba56-55bd64da321e', 'ip_version': 4, 'cidr': '10.1.1.0/24', 'allocation_pools': [{'start': '10.1.1.2', 'end': '10.1.1.254'}], 'gateway_ip': '10.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '10.1.1.2', 'tags': [], 'project_id': '34604885da034301b0bbfe9e593dcb41'}} 2025-10-30 04:10:29,338 - INFO - Created VN vn1 2025-10-30 04:10:29,352 - DEBUG - VN vn1 UUID is 2c21af58-6cbd-4263-ba56-55bd64da321e 2025-10-30 04:10:29,708 - DEBUG - Response for create_network : {'network': {'id': 'd33165a4-93ab-4911-81bb-be1d5cba5665', 'name': 'vn2', 'tenant_id': '34604885da034301b0bbfe9e593dcb41', 'project_id': '34604885da034301b0bbfe9e593dcb41', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-05837923', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-10-30 04:10:29,983 - DEBUG - Response for create_subnet : {'subnet': {'id': '21d7ca2a-0561-41e9-8fa7-eeb56cd5e9fa', 'name': '', 'tenant_id': '34604885da034301b0bbfe9e593dcb41', 'network_id': 'd33165a4-93ab-4911-81bb-be1d5cba5665', 'ip_version': 4, 'cidr': '20.1.1.0/24', 'allocation_pools': [{'start': '20.1.1.2', 'end': '20.1.1.254'}], 'gateway_ip': '20.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '20.1.1.2', 'tags': [], 'project_id': '34604885da034301b0bbfe9e593dcb41'}} 2025-10-30 04:10:30,010 - INFO - Created VN vn2 2025-10-30 04:10:30,023 - DEBUG - VN vn2 UUID is d33165a4-93ab-4911-81bb-be1d5cba5665 2025-10-30 04:10:30,126 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-05837923:vn1, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-05837923:vn2, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-30 04:10:30,488 - INFO - Attaching policy sec_grp_policy_ctest-random-62534291 to vn vn1 2025-10-30 04:10:30,851 - INFO - Attaching policy sec_grp_policy_ctest-random-62534291 to vn vn2 2025-10-30 04:10:32,611 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4535-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4535-2) 2025-10-30 04:10:33,967 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4535-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4535-1) 2025-10-30 04:10:34,033 - INFO - ================================================================================ 2025-10-30 04:10:34,033 - INFO - STARTING TEST : test_check_per_peer_on_control 2025-10-30 04:10:34,034 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2025-10-30 04:10:35,322 - DEBUG - Skipping xmpp flap check 2025-10-30 04:10:35,322 - INFO - Initial checks done. Running the testcase now 2025-10-30 04:10:35,322 - INFO - 2025-10-30 04:10:35,322 - INFO - config per peer with auth_data None 2025-10-30 04:10:35,924 - INFO - configure MD5 on host 6cd92ca5-08fe-409c-9411-9ebed65075f2 2025-10-30 04:10:36,028 - INFO - config per peer with auth_data None 2025-10-30 04:10:36,673 - INFO - configure MD5 on host 96c7bde1-a2da-4b39-a85f-c47551f01f83 2025-10-30 04:10:36,776 - INFO - config per peer with auth_data None 2025-10-30 04:10:37,464 - INFO - configure MD5 on host 3d826ddb-5595-4fb8-a5b3-9785c55f1ace 2025-10-30 04:10:37,576 - INFO - check BGP peering is up before setting md5 config 2025-10-30 04:10:37,576 - INFO - Check BGP staus between peers 2025-10-30 04:10:37,589 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2025-10-30 04:10:38,257 - INFO - Check BGP staus between peers 2025-10-30 04:10:38,268 - DEBUG - Check the BGP connection on 10.0.0.242 2025-10-30 04:10:38,268 - DEBUG - Check the BGP connection on 10.0.0.242 2025-10-30 04:10:48,268 - INFO - Check BGP staus between peers 2025-10-30 04:10:48,281 - INFO - remove md5 configured on host 6cd92ca5-08fe-409c-9411-9ebed65075f2 2025-10-30 04:10:48,281 - INFO - config per peer with auth_data None 2025-10-30 04:10:48,844 - INFO - configure MD5 on host 6cd92ca5-08fe-409c-9411-9ebed65075f2 2025-10-30 04:10:48,932 - INFO - remove md5 configured on host 96c7bde1-a2da-4b39-a85f-c47551f01f83 2025-10-30 04:10:48,933 - INFO - config per peer with auth_data None 2025-10-30 04:10:49,528 - INFO - configure MD5 on host 96c7bde1-a2da-4b39-a85f-c47551f01f83 2025-10-30 04:10:49,608 - INFO - remove md5 configured on host 3d826ddb-5595-4fb8-a5b3-9785c55f1ace 2025-10-30 04:10:49,609 - INFO - config per peer with auth_data None 2025-10-30 04:10:50,204 - INFO - configure MD5 on host 3d826ddb-5595-4fb8-a5b3-9785c55f1ace 2025-10-30 04:10:50,308 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-10-30 04:10:50,309 - INFO - Deleting VM vn2_vm_0 2025-10-30 04:10:50,309 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-30 04:10:50,309 - ERROR - Nova failed to get host of the VM 2025-10-30 04:10:50,309 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-30 04:10:50,309 - ERROR - Nova failed to get host of the VM 2025-10-30 04:10:50,391 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-10-30 04:10:50,391 - INFO - Deleting VM vn1_vm_0 2025-10-30 04:10:50,391 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-30 04:10:50,392 - ERROR - Nova failed to get host of the VM 2025-10-30 04:10:50,392 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-30 04:10:50,392 - ERROR - Nova failed to get host of the VM 2025-10-30 04:10:50,473 - INFO - Dettaching policy sec_grp_policy_ctest-random-62534291 from vn vn2 2025-10-30 04:10:50,647 - INFO - Dettaching policy sec_grp_policy_ctest-random-62534291 from vn vn1 2025-10-30 04:10:50,903 - INFO - Deleted policy sec_grp_policy_ctest-random-62534291 2025-10-30 04:10:50,903 - INFO - Deleting VN vn2 2025-10-30 04:10:50,973 - DEBUG - VN d33165a4-93ab-4911-81bb-be1d5cba5665 still in use: Unable to complete operation on network d33165a4-93ab-4911-81bb-be1d5cba5665. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-b3282a13-9d99-418e-98db-dadddee90b81'] 2025-10-30 04:10:50,973 - WARNING - Deleting VN vn2 failed..Will retry 2025-10-30 04:10:53,140 - DEBUG - Response for deleting network () 2025-10-30 04:10:53,140 - INFO - Deleting VN vn1 2025-10-30 04:10:53,314 - DEBUG - Response for deleting network () 2025-10-30 04:10:53,315 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-10-30 04:10:53,488 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-10-30 04:10:53,726 - INFO - Deleted policy allow_all 2025-10-30 04:10:53,726 - INFO - Deleting VM dest_vm 2025-10-30 04:10:53,817 - INFO - Deleting VM source_vm 2025-10-30 04:10:53,888 - INFO - Deleting VN test_vnv6dn 2025-10-30 04:10:53,929 - DEBUG - VN 2d6d7bb9-4007-497c-b0be-7f719070561c still in use: Unable to complete operation on network 2d6d7bb9-4007-497c-b0be-7f719070561c. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-6a1ab8d1-516e-4977-b5e9-0dc0af734e69'] 2025-10-30 04:10:53,929 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-10-30 04:10:56,139 - DEBUG - Response for deleting network () 2025-10-30 04:10:56,139 - INFO - Deleting VN test_vnv6sr 2025-10-30 04:10:56,297 - DEBUG - Response for deleting network () 2025-10-30 04:10:57,549 - DEBUG - Skipping xmpp flap check
2025-10-30 04:10:57,549 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:23]
2025-10-30 04:10:57,549 - INFO - -------------------------------------------------------------------------------- 2025-10-30 04:10:57,812 - DEBUG - Response for create_network : {'network': {'id': '222ec4fe-df77-43fb-9825-a251c1a16e49', 'name': 'test_vnv6sr', 'tenant_id': '34604885da034301b0bbfe9e593dcb41', 'project_id': '34604885da034301b0bbfe9e593dcb41', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-05837923', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-10-30 04:10:58,017 - DEBUG - Response for create_subnet : {'subnet': {'id': 'af5d855f-1b7a-4161-bc79-533f33b3f3f3', 'name': '', 'tenant_id': '34604885da034301b0bbfe9e593dcb41', 'network_id': '222ec4fe-df77-43fb-9825-a251c1a16e49', 'ip_version': 6, 'cidr': '2001::101:0/120', 'allocation_pools': [{'start': '2001::101:2', 'end': '2001::101:fe'}], 'gateway_ip': '2001::101:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2001::101:2', 'tags': [], 'project_id': '34604885da034301b0bbfe9e593dcb41'}} 2025-10-30 04:10:58,241 - DEBUG - Response for create_subnet : {'subnet': {'id': '669acd82-d52e-4653-b81f-efe3d6ab0899', 'name': '', 'tenant_id': '34604885da034301b0bbfe9e593dcb41', 'network_id': '222ec4fe-df77-43fb-9825-a251c1a16e49', 'ip_version': 4, 'cidr': '161.222.98.0/26', 'allocation_pools': [{'start': '161.222.98.2', 'end': '161.222.98.62'}], 'gateway_ip': '161.222.98.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '161.222.98.2', 'tags': [], 'project_id': '34604885da034301b0bbfe9e593dcb41'}} 2025-10-30 04:10:58,266 - INFO - Created VN test_vnv6sr 2025-10-30 04:10:58,278 - DEBUG - VN test_vnv6sr UUID is 222ec4fe-df77-43fb-9825-a251c1a16e49 2025-10-30 04:10:58,660 - DEBUG - Response for create_network : {'network': {'id': '825463a3-aa78-420d-aaff-b97a125f155c', 'name': 'test_vnv6dn', 'tenant_id': '34604885da034301b0bbfe9e593dcb41', 'project_id': '34604885da034301b0bbfe9e593dcb41', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-05837923', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-10-30 04:10:58,911 - DEBUG - Response for create_subnet : {'subnet': {'id': '793008dd-358b-4bdf-9ab6-008f6e883899', 'name': '', 'tenant_id': '34604885da034301b0bbfe9e593dcb41', 'network_id': '825463a3-aa78-420d-aaff-b97a125f155c', 'ip_version': 6, 'cidr': '2001::201:0/120', 'allocation_pools': [{'start': '2001::201:2', 'end': '2001::201:fe'}], 'gateway_ip': '2001::201:1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '2001::201:2', 'tags': [], 'project_id': '34604885da034301b0bbfe9e593dcb41'}} 2025-10-30 04:10:59,114 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c725b775-00f8-41d7-af0c-23c0817de596', 'name': '', 'tenant_id': '34604885da034301b0bbfe9e593dcb41', 'network_id': '825463a3-aa78-420d-aaff-b97a125f155c', 'ip_version': 4, 'cidr': '56.123.158.128/26', 'allocation_pools': [{'start': '56.123.158.130', 'end': '56.123.158.190'}], 'gateway_ip': '56.123.158.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '56.123.158.130', 'tags': [], 'project_id': '34604885da034301b0bbfe9e593dcb41'}} 2025-10-30 04:10:59,142 - INFO - Created VN test_vnv6dn 2025-10-30 04:10:59,155 - DEBUG - VN test_vnv6dn UUID is 825463a3-aa78-420d-aaff-b97a125f155c 2025-10-30 04:11:00,357 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4535-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4535-2) 2025-10-30 04:11:01,736 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4535-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4535-1) 2025-10-30 04:11:01,736 - INFO - Waiting for VM source_vm to be up.. 2025-10-30 04:11:01,853 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-10-30 04:11:06,939 - DEBUG - VM is in ACTIVE state now 2025-10-30 04:11:06,939 - INFO - VM name : source_vm 2025-10-30 04:11:07,035 - DEBUG - VM source_vm ID is c1dae34b-84c3-49e4-9c54-4804b497c735 2025-10-30 04:11:07,035 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4535-2 2025-10-30 04:11:07,117 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/c1dae34b-84c3-49e4-9c54-4804b497c735 2025-10-30 04:11:07,128 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/43e37985-eb59-447b-ae2d-12b412e05a67 2025-10-30 04:11:10,311 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 0 received, 100% packet loss, time 1019ms') 2025-10-30 04:11:10,311 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-10-30 04:11:10,326 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-05837923:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-10-30 04:11:10,326 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-05837923:test_vnv6sr is 161.222.98.1 and allocation pool is NOT set 2025-10-30 04:11:12,398 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=2.60 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.554 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 0.554/1.575/2.597/1.021 ms') 2025-10-30 04:11:12,399 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-10-30 04:11:12,472 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-30 04:11:12,472 - DEBUG - Waiting to SSH to VM source_vm, IP 161.222.98.3, Port 22 2025-10-30 04:11:12,630 - DEBUG - VM source_vm is ready for SSH connections 2025-10-30 04:11:12,630 - INFO - Waiting for VM dest_vm to be up.. 2025-10-30 04:11:12,719 - DEBUG - VM is in ACTIVE state now 2025-10-30 04:11:12,719 - INFO - VM name : dest_vm 2025-10-30 04:11:12,805 - DEBUG - VM dest_vm ID is 25aeddea-cfbe-4950-a347-75836144df57 2025-10-30 04:11:12,805 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4535-1 2025-10-30 04:11:12,886 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine/25aeddea-cfbe-4950-a347-75836144df57 2025-10-30 04:11:12,898 - DEBUG - Requesting: http://10.0.0.240:8082/virtual-machine-interface/f143bac8-3e3f-4712-b312-752977d4e6eb 2025-10-30 04:11:14,064 - DEBUG - (True, 'PING 169.254.0.3 (169.254.0.3) 56(84) bytes of data.\r\n64 bytes from 169.254.0.3: icmp_seq=1 ttl=63 time=6.41 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.37 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1002ms\r\nrtt min/avg/max/mdev = 2.370/4.391/6.412/2.021 ms') 2025-10-30 04:11:14,064 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-10-30 04:11:14,134 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-10-30 04:11:14,134 - DEBUG - Waiting to SSH to VM dest_vm, IP 56.123.158.131, Port 22 2025-10-30 04:11:14,297 - DEBUG - VM dest_vm is ready for SSH connections 2025-10-30 04:11:14,305 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-05837923:test_vnv6sr, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-05837923:test_vnv6dn, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-30 04:11:14,668 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-10-30 04:11:15,064 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-10-30 04:11:15,452 - INFO - Configure the policy with allow any 2025-10-30 04:11:15,776 - DEBUG - Response for create_network : {'network': {'id': '2f044e52-0886-4b42-8e4b-528940001c61', 'name': 'vn1', 'tenant_id': '34604885da034301b0bbfe9e593dcb41', 'project_id': '34604885da034301b0bbfe9e593dcb41', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-05837923', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-10-30 04:11:16,013 - DEBUG - Response for create_subnet : {'subnet': {'id': '8b1ee567-7a26-4e3f-91f1-29a2d9a909a8', 'name': '', 'tenant_id': '34604885da034301b0bbfe9e593dcb41', 'network_id': '2f044e52-0886-4b42-8e4b-528940001c61', 'ip_version': 4, 'cidr': '10.1.1.0/24', 'allocation_pools': [{'start': '10.1.1.2', 'end': '10.1.1.254'}], 'gateway_ip': '10.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '10.1.1.2', 'tags': [], 'project_id': '34604885da034301b0bbfe9e593dcb41'}} 2025-10-30 04:11:16,046 - INFO - Created VN vn1 2025-10-30 04:11:16,058 - DEBUG - VN vn1 UUID is 2f044e52-0886-4b42-8e4b-528940001c61 2025-10-30 04:11:16,487 - DEBUG - Response for create_network : {'network': {'id': '48ee8197-6cf7-409c-9604-9166a556c11a', 'name': 'vn2', 'tenant_id': '34604885da034301b0bbfe9e593dcb41', 'project_id': '34604885da034301b0bbfe9e593dcb41', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-05837923', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-10-30 04:11:16,766 - DEBUG - Response for create_subnet : {'subnet': {'id': '8d167f5e-6579-4f72-831a-8efd901cab45', 'name': '', 'tenant_id': '34604885da034301b0bbfe9e593dcb41', 'network_id': '48ee8197-6cf7-409c-9604-9166a556c11a', 'ip_version': 4, 'cidr': '20.1.1.0/24', 'allocation_pools': [{'start': '20.1.1.2', 'end': '20.1.1.254'}], 'gateway_ip': '20.1.1.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '20.1.1.2', 'tags': [], 'project_id': '34604885da034301b0bbfe9e593dcb41'}} 2025-10-30 04:11:16,790 - INFO - Created VN vn2 2025-10-30 04:11:16,804 - DEBUG - VN vn2 UUID is 48ee8197-6cf7-409c-9604-9166a556c11a 2025-10-30 04:11:16,912 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-05837923:vn1, security_group = None, network_policy = None, subnet_list = []], src_ports = [start_port = 0, end_port = -1], application = [], dst_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-05837923:vn2, security_group = None, network_policy = None, subnet_list = []], dst_ports = [start_port = 0, end_port = -1], action_list = simple_action = pass, gateway_name = None, apply_service = [], service_properties = None, mirror_to = None, assign_routing_instance = None, log = False, alert = False, qos_action = None, host_based_service = False, ethertype = None, created = None, last_modified = None, description = None] 2025-10-30 04:11:17,267 - INFO - Attaching policy sec_grp_policy_ctest-random-40078858 to vn vn1 2025-10-30 04:11:17,660 - INFO - Attaching policy sec_grp_policy_ctest-random-40078858 to vn vn2 2025-10-30 04:11:19,352 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4535-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4535-2) 2025-10-30 04:11:20,979 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4535-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4535-1) 2025-10-30 04:11:21,048 - INFO - ================================================================================ 2025-10-30 04:11:21,048 - INFO - STARTING TEST : test_create_md5_on_control 2025-10-30 04:11:21,049 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2025-10-30 04:11:22,325 - DEBUG - Skipping xmpp flap check 2025-10-30 04:11:22,325 - INFO - Initial checks done. Running the testcase now 2025-10-30 04:11:22,325 - INFO - 2025-10-30 04:11:22,325 - INFO - config md5 on each host with auth_data None 2025-10-30 04:11:22,325 - INFO - config per peer with auth_data None 2025-10-30 04:11:22,908 - INFO - configure MD5 on host 6cd92ca5-08fe-409c-9411-9ebed65075f2 2025-10-30 04:11:23,004 - INFO - config per peer with auth_data None 2025-10-30 04:11:23,608 - INFO - configure MD5 on host 96c7bde1-a2da-4b39-a85f-c47551f01f83 2025-10-30 04:11:23,716 - INFO - config per peer with auth_data None 2025-10-30 04:11:24,308 - INFO - configure MD5 on host 3d826ddb-5595-4fb8-a5b3-9785c55f1ace 2025-10-30 04:11:24,404 - INFO - check if BGP between peers are up before setting md5 2025-10-30 04:11:24,405 - INFO - Check BGP staus between peers 2025-10-30 04:11:24,424 - INFO - setting MD5 config on host 6cd92ca5-08fe-409c-9411-9ebed65075f2 2025-10-30 04:11:24,424 - INFO - configure MD5 on host 6cd92ca5-08fe-409c-9411-9ebed65075f2 2025-10-30 04:11:24,512 - INFO - setting MD5 config on host 96c7bde1-a2da-4b39-a85f-c47551f01f83 2025-10-30 04:11:24,513 - INFO - configure MD5 on host 96c7bde1-a2da-4b39-a85f-c47551f01f83 2025-10-30 04:11:24,589 - INFO - setting MD5 config on host 3d826ddb-5595-4fb8-a5b3-9785c55f1ace 2025-10-30 04:11:24,589 - INFO - configure MD5 on host 3d826ddb-5595-4fb8-a5b3-9785c55f1ace 2025-10-30 04:11:24,672 - INFO - check if BGP peering is up after md5 config 2025-10-30 04:11:24,673 - INFO - Check BGP staus between peers 2025-10-30 04:11:24,684 - DEBUG - Check the BGP connection on 10.0.0.242 2025-10-30 04:11:24,684 - DEBUG - Check the BGP connection on 10.0.0.242 2025-10-30 04:11:34,684 - INFO - Check BGP staus between peers 2025-10-30 04:11:34,697 - INFO - remove md5 configured on host 6cd92ca5-08fe-409c-9411-9ebed65075f2 2025-10-30 04:11:34,698 - INFO - config per peer with auth_data None 2025-10-30 04:11:35,274 - INFO - configure MD5 on host 6cd92ca5-08fe-409c-9411-9ebed65075f2 2025-10-30 04:11:35,368 - INFO - remove md5 configured on host 96c7bde1-a2da-4b39-a85f-c47551f01f83 2025-10-30 04:11:35,369 - INFO - config per peer with auth_data None 2025-10-30 04:11:36,049 - INFO - configure MD5 on host 96c7bde1-a2da-4b39-a85f-c47551f01f83 2025-10-30 04:11:36,149 - INFO - remove md5 configured on host 3d826ddb-5595-4fb8-a5b3-9785c55f1ace 2025-10-30 04:11:36,152 - INFO - config per peer with auth_data None 2025-10-30 04:11:36,885 - INFO - configure MD5 on host 3d826ddb-5595-4fb8-a5b3-9785c55f1ace 2025-10-30 04:11:36,989 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-10-30 04:11:36,989 - INFO - Deleting VM vn2_vm_0 2025-10-30 04:11:36,990 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-30 04:11:36,990 - ERROR - Nova failed to get host of the VM 2025-10-30 04:11:36,990 - ERROR - VM vn2_vm_0 has failed to come up 2025-10-30 04:11:36,990 - ERROR - Nova failed to get host of the VM 2025-10-30 04:11:37,085 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-10-30 04:11:37,085 - INFO - Deleting VM vn1_vm_0 2025-10-30 04:11:37,085 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-30 04:11:37,086 - ERROR - Nova failed to get host of the VM 2025-10-30 04:11:37,086 - ERROR - VM vn1_vm_0 has failed to come up 2025-10-30 04:11:37,086 - ERROR - Nova failed to get host of the VM 2025-10-30 04:11:37,183 - INFO - Dettaching policy sec_grp_policy_ctest-random-40078858 from vn vn2 2025-10-30 04:11:37,408 - INFO - Dettaching policy sec_grp_policy_ctest-random-40078858 from vn vn1 2025-10-30 04:11:37,735 - INFO - Deleted policy sec_grp_policy_ctest-random-40078858 2025-10-30 04:11:37,735 - INFO - Deleting VN vn2 2025-10-30 04:11:37,824 - DEBUG - VN 48ee8197-6cf7-409c-9604-9166a556c11a still in use: Unable to complete operation on network 48ee8197-6cf7-409c-9604-9166a556c11a. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-ea5226ac-588f-49f2-a50f-676a98367dce'] 2025-10-30 04:11:37,824 - WARNING - Deleting VN vn2 failed..Will retry 2025-10-30 04:11:39,958 - DEBUG - Response for deleting network () 2025-10-30 04:11:39,958 - INFO - Deleting VN vn1 2025-10-30 04:11:40,164 - DEBUG - Response for deleting network () 2025-10-30 04:11:40,164 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-10-30 04:11:40,364 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-10-30 04:11:40,664 - INFO - Deleted policy allow_all 2025-10-30 04:11:40,664 - INFO - Deleting VM dest_vm 2025-10-30 04:11:40,743 - INFO - Deleting VM source_vm 2025-10-30 04:11:40,822 - INFO - Deleting VN test_vnv6dn 2025-10-30 04:11:40,866 - DEBUG - VN 825463a3-aa78-420d-aaff-b97a125f155c still in use: Unable to complete operation on network 825463a3-aa78-420d-aaff-b97a125f155c. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-430bd3e9-696b-4cf7-b7f7-706a75e3f05d'] 2025-10-30 04:11:40,866 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-10-30 04:11:43,050 - DEBUG - Response for deleting network () 2025-10-30 04:11:43,051 - INFO - Deleting VN test_vnv6sr 2025-10-30 04:11:43,250 - DEBUG - Response for deleting network () 2025-10-30 04:11:44,518 - DEBUG - Skipping xmpp flap check
2025-10-30 04:11:44,518 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:23]
2025-10-30 04:11:44,518 - INFO - -------------------------------------------------------------------------------- 2025-10-30 04:11:45,284 - INFO - Deleted project: ctest-TestMd5testsOnControl-05837923, ID : 34604885-da03-4301-b0bb-fe9e593dcb41