2025-08-06 17:07:07,070 - INFO - Domain Default found not creating 2025-08-06 17:07:07,204 - INFO - Project ctest-TestMd5testsOnControl-96833828 not found, creating it 2025-08-06 17:07:07,751 - INFO - Created Project:ctest-TestMd5testsOnControl-96833828, ID : 989a88e5-0995-4ba8-9cd0-3bc9f260fbae 2025-08-06 17:07:09,852 - DEBUG - Response for create_network : {'network': {'id': 'eee87d06-04a2-486e-ac1c-f4eae95dc255', 'name': 'test_vnv6sr', 'tenant_id': '989a88e509954ba89cd03bc9f260fbae', 'project_id': '989a88e509954ba89cd03bc9f260fbae', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-96833828', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-08-06 17:07:10,171 - DEBUG - Response for create_subnet : {'subnet': {'id': '0982db90-638f-4b07-89ef-c0bf4d10db31', 'name': '', 'tenant_id': '989a88e509954ba89cd03bc9f260fbae', 'network_id': 'eee87d06-04a2-486e-ac1c-f4eae95dc255', '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': '989a88e509954ba89cd03bc9f260fbae'}} 2025-08-06 17:07:10,431 - DEBUG - Response for create_subnet : {'subnet': {'id': 'be970ad3-9b57-4b75-b1f2-3ccd574ddb4f', 'name': '', 'tenant_id': '989a88e509954ba89cd03bc9f260fbae', 'network_id': 'eee87d06-04a2-486e-ac1c-f4eae95dc255', 'ip_version': 4, 'cidr': '62.87.37.0/26', 'allocation_pools': [{'start': '62.87.37.2', 'end': '62.87.37.62'}], 'gateway_ip': '62.87.37.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '62.87.37.2', 'tags': [], 'project_id': '989a88e509954ba89cd03bc9f260fbae'}} 2025-08-06 17:07:10,459 - INFO - Created VN test_vnv6sr 2025-08-06 17:07:10,475 - DEBUG - VN test_vnv6sr UUID is eee87d06-04a2-486e-ac1c-f4eae95dc255 2025-08-06 17:07:10,851 - DEBUG - Response for create_network : {'network': {'id': '80ea6c0e-7793-46a5-8855-8af57d0c3cc2', 'name': 'test_vnv6dn', 'tenant_id': '989a88e509954ba89cd03bc9f260fbae', 'project_id': '989a88e509954ba89cd03bc9f260fbae', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-96833828', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-08-06 17:07:11,088 - DEBUG - Response for create_subnet : {'subnet': {'id': 'db5f4b11-131f-4dec-96f1-41fab0aca46d', 'name': '', 'tenant_id': '989a88e509954ba89cd03bc9f260fbae', 'network_id': '80ea6c0e-7793-46a5-8855-8af57d0c3cc2', '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': '989a88e509954ba89cd03bc9f260fbae'}} 2025-08-06 17:07:11,369 - DEBUG - Response for create_subnet : {'subnet': {'id': '9c1ff4a8-cb96-4522-b7a4-77442978d842', 'name': '', 'tenant_id': '989a88e509954ba89cd03bc9f260fbae', 'network_id': '80ea6c0e-7793-46a5-8855-8af57d0c3cc2', 'ip_version': 4, 'cidr': '193.85.104.0/26', 'allocation_pools': [{'start': '193.85.104.2', 'end': '193.85.104.62'}], 'gateway_ip': '193.85.104.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '193.85.104.2', 'tags': [], 'project_id': '989a88e509954ba89cd03bc9f260fbae'}} 2025-08-06 17:07:11,396 - INFO - Created VN test_vnv6dn 2025-08-06 17:07:11,411 - DEBUG - VN test_vnv6dn UUID is 80ea6c0e-7793-46a5-8855-8af57d0c3cc2 2025-08-06 17:07:11,680 - DEBUG - Services list from nova: [, , , ] 2025-08-06 17:07:12,753 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3763-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3763-1) 2025-08-06 17:07:14,228 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3763-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3763-2) 2025-08-06 17:07:14,228 - INFO - Waiting for VM source_vm to be up.. 2025-08-06 17:07:14,316 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-06 17:07:19,403 - DEBUG - VM is in ACTIVE state now 2025-08-06 17:07:19,404 - INFO - VM name : source_vm 2025-08-06 17:07:19,503 - DEBUG - VM source_vm ID is 49f578fe-5224-46ef-8f29-d5bcf008b955 2025-08-06 17:07:19,530 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-3763-1 2025-08-06 17:07:19,614 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/49f578fe-5224-46ef-8f29-d5bcf008b955 2025-08-06 17:07:19,921 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/49f578fe-5224-46ef-8f29-d5bcf008b955 2025-08-06 17:07:19,957 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/503c4cfb-8c28-4029-9b65-f664f28e4a06 2025-08-06 17:07:23,182 - 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 1007ms') 2025-08-06 17:07:23,182 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-08-06 17:07:23,199 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-96833828:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-08-06 17:07:23,199 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-96833828:test_vnv6sr is 62.87.37.1 and allocation pool is NOT set 2025-08-06 17:07:25,268 - 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=9.93 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.30 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.295/6.110/9.926/3.815 ms') 2025-08-06 17:07:25,269 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-08-06 17:07:25,384 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-06 17:07:25,384 - DEBUG - Waiting to SSH to VM source_vm, IP 62.87.37.3, Port 22 2025-08-06 17:07:25,557 - DEBUG - VM source_vm is ready for SSH connections 2025-08-06 17:07:25,557 - INFO - Waiting for VM dest_vm to be up.. 2025-08-06 17:07:25,644 - DEBUG - VM is in ACTIVE state now 2025-08-06 17:07:25,645 - INFO - VM name : dest_vm 2025-08-06 17:07:25,730 - DEBUG - VM dest_vm ID is 2dc66cf1-4f74-4b28-ad27-068b63c4588e 2025-08-06 17:07:25,730 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-3763-2 2025-08-06 17:07:25,809 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/2dc66cf1-4f74-4b28-ad27-068b63c4588e 2025-08-06 17:07:25,819 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/c94d4c7a-0b67-4c40-a1be-7d6a077ea416 2025-08-06 17:07:27,042 - 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.13 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.427 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.427/2.779/5.131/2.352 ms') 2025-08-06 17:07:27,042 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-08-06 17:07:27,155 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-06 17:07:27,156 - DEBUG - Waiting to SSH to VM dest_vm, IP 193.85.104.3, Port 22 2025-08-06 17:07:27,329 - DEBUG - VM dest_vm is ready for SSH connections 2025-08-06 17:07:27,338 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-96833828: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-96833828: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-08-06 17:07:27,686 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-08-06 17:07:28,073 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-08-06 17:07:28,365 - INFO - Configure the policy with allow any 2025-08-06 17:07:28,687 - DEBUG - Response for create_network : {'network': {'id': '99c61d7f-77d3-4710-ae6c-29a9c2c91203', 'name': 'vn1', 'tenant_id': '989a88e509954ba89cd03bc9f260fbae', 'project_id': '989a88e509954ba89cd03bc9f260fbae', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-96833828', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-08-06 17:07:28,929 - DEBUG - Response for create_subnet : {'subnet': {'id': '3e772917-2bc8-4a39-9578-f9d8b485f489', 'name': '', 'tenant_id': '989a88e509954ba89cd03bc9f260fbae', 'network_id': '99c61d7f-77d3-4710-ae6c-29a9c2c91203', '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': '989a88e509954ba89cd03bc9f260fbae'}} 2025-08-06 17:07:28,962 - INFO - Created VN vn1 2025-08-06 17:07:28,976 - DEBUG - VN vn1 UUID is 99c61d7f-77d3-4710-ae6c-29a9c2c91203 2025-08-06 17:07:29,351 - DEBUG - Response for create_network : {'network': {'id': 'cd2cbb60-0943-45af-aee9-c99b50a0e585', 'name': 'vn2', 'tenant_id': '989a88e509954ba89cd03bc9f260fbae', 'project_id': '989a88e509954ba89cd03bc9f260fbae', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-96833828', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-08-06 17:07:29,649 - DEBUG - Response for create_subnet : {'subnet': {'id': 'fdc1b3a1-79fb-4f45-9a4e-ffa2ce02572c', 'name': '', 'tenant_id': '989a88e509954ba89cd03bc9f260fbae', 'network_id': 'cd2cbb60-0943-45af-aee9-c99b50a0e585', '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': '989a88e509954ba89cd03bc9f260fbae'}} 2025-08-06 17:07:29,669 - INFO - Created VN vn2 2025-08-06 17:07:29,680 - DEBUG - VN vn2 UUID is cd2cbb60-0943-45af-aee9-c99b50a0e585 2025-08-06 17:07:29,755 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-96833828: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-96833828: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-08-06 17:07:30,098 - INFO - Attaching policy sec_grp_policy_ctest-random-15959416 to vn vn1 2025-08-06 17:07:30,470 - INFO - Attaching policy sec_grp_policy_ctest-random-15959416 to vn vn2 2025-08-06 17:07:32,049 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3763-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3763-1) 2025-08-06 17:07:33,634 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3763-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3763-2) 2025-08-06 17:07:33,702 - INFO - ================================================================================ 2025-08-06 17:07:33,703 - INFO - STARTING TEST : test_check_per_peer_on_control 2025-08-06 17:07:33,703 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2025-08-06 17:07:35,002 - DEBUG - Skipping xmpp flap check 2025-08-06 17:07:35,002 - INFO - Initial checks done. Running the testcase now 2025-08-06 17:07:35,002 - INFO - 2025-08-06 17:07:35,002 - INFO - config per peer with auth_data None 2025-08-06 17:07:35,562 - INFO - configure MD5 on host c9bc89d2-6e8b-422a-bf58-bb437ab43745 2025-08-06 17:07:35,654 - INFO - config per peer with auth_data None 2025-08-06 17:07:36,222 - INFO - configure MD5 on host 71021e13-649d-4f0b-85f3-286996457a96 2025-08-06 17:07:36,322 - INFO - config per peer with auth_data None 2025-08-06 17:07:36,900 - INFO - configure MD5 on host d1627fff-4994-4b7b-a45c-79eb153b143a 2025-08-06 17:07:36,986 - INFO - check BGP peering is up before setting md5 config 2025-08-06 17:07:36,986 - INFO - Check BGP staus between peers 2025-08-06 17:07:37,007 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2025-08-06 17:07:37,635 - INFO - Check BGP staus between peers 2025-08-06 17:07:37,655 - DEBUG - Check the BGP connection on 10.0.0.254 2025-08-06 17:07:37,655 - DEBUG - Check the BGP connection on 10.0.0.254 2025-08-06 17:07:47,656 - INFO - Check BGP staus between peers 2025-08-06 17:07:47,672 - INFO - remove md5 configured on host c9bc89d2-6e8b-422a-bf58-bb437ab43745 2025-08-06 17:07:47,673 - INFO - config per peer with auth_data None 2025-08-06 17:07:48,299 - INFO - configure MD5 on host c9bc89d2-6e8b-422a-bf58-bb437ab43745 2025-08-06 17:07:48,414 - INFO - remove md5 configured on host 71021e13-649d-4f0b-85f3-286996457a96 2025-08-06 17:07:48,414 - INFO - config per peer with auth_data None 2025-08-06 17:07:49,122 - INFO - configure MD5 on host 71021e13-649d-4f0b-85f3-286996457a96 2025-08-06 17:07:49,240 - INFO - remove md5 configured on host d1627fff-4994-4b7b-a45c-79eb153b143a 2025-08-06 17:07:49,240 - INFO - config per peer with auth_data None 2025-08-06 17:07:49,887 - INFO - configure MD5 on host d1627fff-4994-4b7b-a45c-79eb153b143a 2025-08-06 17:07:49,998 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-08-06 17:07:49,998 - INFO - Deleting VM vn2_vm_0 2025-08-06 17:07:49,999 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-06 17:07:49,999 - ERROR - Nova failed to get host of the VM 2025-08-06 17:07:49,999 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-06 17:07:49,999 - ERROR - Nova failed to get host of the VM 2025-08-06 17:07:50,081 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-08-06 17:07:50,084 - INFO - Deleting VM vn1_vm_0 2025-08-06 17:07:50,084 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-06 17:07:50,084 - ERROR - Nova failed to get host of the VM 2025-08-06 17:07:50,085 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-06 17:07:50,085 - ERROR - Nova failed to get host of the VM 2025-08-06 17:07:50,154 - INFO - Dettaching policy sec_grp_policy_ctest-random-15959416 from vn vn2 2025-08-06 17:07:50,330 - INFO - Dettaching policy sec_grp_policy_ctest-random-15959416 from vn vn1 2025-08-06 17:07:50,573 - INFO - Deleted policy sec_grp_policy_ctest-random-15959416 2025-08-06 17:07:50,573 - INFO - Deleting VN vn2 2025-08-06 17:07:50,626 - DEBUG - VN cd2cbb60-0943-45af-aee9-c99b50a0e585 still in use: Unable to complete operation on network cd2cbb60-0943-45af-aee9-c99b50a0e585. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-3424c209-afb0-4bb5-b85c-e5db207b688c'] 2025-08-06 17:07:50,626 - WARNING - Deleting VN vn2 failed..Will retry 2025-08-06 17:07:52,863 - DEBUG - Response for deleting network () 2025-08-06 17:07:52,863 - INFO - Deleting VN vn1 2025-08-06 17:07:53,036 - DEBUG - Response for deleting network () 2025-08-06 17:07:53,036 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-08-06 17:07:53,253 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-08-06 17:07:53,523 - INFO - Deleted policy allow_all 2025-08-06 17:07:53,523 - INFO - Deleting VM dest_vm 2025-08-06 17:07:53,723 - INFO - Deleting VM source_vm 2025-08-06 17:07:53,786 - INFO - Deleting VN test_vnv6dn 2025-08-06 17:07:53,839 - DEBUG - VN 80ea6c0e-7793-46a5-8855-8af57d0c3cc2 still in use: Unable to complete operation on network 80ea6c0e-7793-46a5-8855-8af57d0c3cc2. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-41c1aa48-d2bc-4b95-8df7-a98323f94e41'] 2025-08-06 17:07:53,839 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-08-06 17:07:56,004 - DEBUG - Response for deleting network () 2025-08-06 17:07:56,004 - INFO - Deleting VN test_vnv6sr 2025-08-06 17:07:56,175 - DEBUG - Response for deleting network () 2025-08-06 17:07:57,432 - DEBUG - Skipping xmpp flap check
2025-08-06 17:07:57,432 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:24]
2025-08-06 17:07:57,432 - INFO - -------------------------------------------------------------------------------- 2025-08-06 17:07:57,713 - DEBUG - Response for create_network : {'network': {'id': 'd8c0f5d6-909d-4553-817d-92f930ffbb56', 'name': 'test_vnv6sr', 'tenant_id': '989a88e509954ba89cd03bc9f260fbae', 'project_id': '989a88e509954ba89cd03bc9f260fbae', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-96833828', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-08-06 17:07:57,965 - DEBUG - Response for create_subnet : {'subnet': {'id': '2c1a380e-3618-43e5-8ab2-6da63e194940', 'name': '', 'tenant_id': '989a88e509954ba89cd03bc9f260fbae', 'network_id': 'd8c0f5d6-909d-4553-817d-92f930ffbb56', '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': '989a88e509954ba89cd03bc9f260fbae'}} 2025-08-06 17:07:58,191 - DEBUG - Response for create_subnet : {'subnet': {'id': '5d807cd0-33b0-4638-ba7e-07fa3ca114f4', 'name': '', 'tenant_id': '989a88e509954ba89cd03bc9f260fbae', 'network_id': 'd8c0f5d6-909d-4553-817d-92f930ffbb56', 'ip_version': 4, 'cidr': '222.26.63.128/26', 'allocation_pools': [{'start': '222.26.63.130', 'end': '222.26.63.190'}], 'gateway_ip': '222.26.63.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '222.26.63.130', 'tags': [], 'project_id': '989a88e509954ba89cd03bc9f260fbae'}} 2025-08-06 17:07:58,215 - INFO - Created VN test_vnv6sr 2025-08-06 17:07:58,246 - DEBUG - VN test_vnv6sr UUID is d8c0f5d6-909d-4553-817d-92f930ffbb56 2025-08-06 17:07:58,636 - DEBUG - Response for create_network : {'network': {'id': 'e1f49060-a1eb-45d8-8602-20e377d81ee0', 'name': 'test_vnv6dn', 'tenant_id': '989a88e509954ba89cd03bc9f260fbae', 'project_id': '989a88e509954ba89cd03bc9f260fbae', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-96833828', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-08-06 17:07:58,893 - DEBUG - Response for create_subnet : {'subnet': {'id': '9e3d621b-44cc-4b42-a2b2-c7033cbefc7d', 'name': '', 'tenant_id': '989a88e509954ba89cd03bc9f260fbae', 'network_id': 'e1f49060-a1eb-45d8-8602-20e377d81ee0', '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': '989a88e509954ba89cd03bc9f260fbae'}} 2025-08-06 17:07:59,150 - DEBUG - Response for create_subnet : {'subnet': {'id': 'a6d1faef-60cf-4190-bcb8-fea6a0cb9f23', 'name': '', 'tenant_id': '989a88e509954ba89cd03bc9f260fbae', 'network_id': 'e1f49060-a1eb-45d8-8602-20e377d81ee0', 'ip_version': 4, 'cidr': '142.56.85.0/26', 'allocation_pools': [{'start': '142.56.85.2', 'end': '142.56.85.62'}], 'gateway_ip': '142.56.85.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '142.56.85.2', 'tags': [], 'project_id': '989a88e509954ba89cd03bc9f260fbae'}} 2025-08-06 17:07:59,173 - INFO - Created VN test_vnv6dn 2025-08-06 17:07:59,185 - DEBUG - VN test_vnv6dn UUID is e1f49060-a1eb-45d8-8602-20e377d81ee0 2025-08-06 17:08:00,427 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3763-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3763-1) 2025-08-06 17:08:01,843 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3763-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3763-2) 2025-08-06 17:08:01,843 - INFO - Waiting for VM source_vm to be up.. 2025-08-06 17:08:01,933 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-06 17:08:07,012 - DEBUG - VM is in ACTIVE state now 2025-08-06 17:08:07,012 - INFO - VM name : source_vm 2025-08-06 17:08:07,102 - DEBUG - VM source_vm ID is 46d99180-b182-43fe-9ee7-a7a9e2f156d7 2025-08-06 17:08:07,102 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-3763-1 2025-08-06 17:08:07,187 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/46d99180-b182-43fe-9ee7-a7a9e2f156d7 2025-08-06 17:08:07,198 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/94ca518a-df12-4e81-99bb-c32afbbde441 2025-08-06 17:08:10,410 - 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 1005ms') 2025-08-06 17:08:10,410 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-08-06 17:08:10,426 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-96833828:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-08-06 17:08:10,426 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-96833828:test_vnv6sr is 222.26.63.129 and allocation pool is NOT set 2025-08-06 17:08:12,489 - 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=10.1 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=2.89 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.892/6.519/10.146/3.627 ms') 2025-08-06 17:08:12,489 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-08-06 17:08:12,599 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-06 17:08:12,600 - DEBUG - Waiting to SSH to VM source_vm, IP 222.26.63.131, Port 22 2025-08-06 17:08:12,771 - DEBUG - VM source_vm is ready for SSH connections 2025-08-06 17:08:12,771 - INFO - Waiting for VM dest_vm to be up.. 2025-08-06 17:08:12,878 - DEBUG - VM is in ACTIVE state now 2025-08-06 17:08:12,878 - INFO - VM name : dest_vm 2025-08-06 17:08:12,958 - DEBUG - VM dest_vm ID is f9030ec5-dc41-44d0-9490-e9eae59662ee 2025-08-06 17:08:12,958 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-3763-2 2025-08-06 17:08:13,045 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine/f9030ec5-dc41-44d0-9490-e9eae59662ee 2025-08-06 17:08:13,057 - DEBUG - Requesting: http://10.0.0.48:8082/virtual-machine-interface/6a06604c-f39d-4927-966c-25085ca759a1 2025-08-06 17:08:14,264 - 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=8.94 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.536 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.536/4.738/8.941/4.202 ms') 2025-08-06 17:08:14,264 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-08-06 17:08:14,375 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-06 17:08:14,375 - DEBUG - Waiting to SSH to VM dest_vm, IP 142.56.85.3, Port 22 2025-08-06 17:08:14,546 - DEBUG - VM dest_vm is ready for SSH connections 2025-08-06 17:08:14,554 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-96833828: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-96833828: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-08-06 17:08:14,882 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-08-06 17:08:15,274 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-08-06 17:08:15,578 - INFO - Configure the policy with allow any 2025-08-06 17:08:15,891 - DEBUG - Response for create_network : {'network': {'id': 'e2763c26-8d1e-4c4b-b58b-38ac252f24c3', 'name': 'vn1', 'tenant_id': '989a88e509954ba89cd03bc9f260fbae', 'project_id': '989a88e509954ba89cd03bc9f260fbae', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-96833828', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-08-06 17:08:16,109 - DEBUG - Response for create_subnet : {'subnet': {'id': 'baf9b9a2-f1c9-4ce0-a3e0-d25a32f7ee32', 'name': '', 'tenant_id': '989a88e509954ba89cd03bc9f260fbae', 'network_id': 'e2763c26-8d1e-4c4b-b58b-38ac252f24c3', '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': '989a88e509954ba89cd03bc9f260fbae'}} 2025-08-06 17:08:16,135 - INFO - Created VN vn1 2025-08-06 17:08:16,150 - DEBUG - VN vn1 UUID is e2763c26-8d1e-4c4b-b58b-38ac252f24c3 2025-08-06 17:08:16,537 - DEBUG - Response for create_network : {'network': {'id': '1a327409-6c59-4918-925f-45806d0665e9', 'name': 'vn2', 'tenant_id': '989a88e509954ba89cd03bc9f260fbae', 'project_id': '989a88e509954ba89cd03bc9f260fbae', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-96833828', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-08-06 17:08:16,779 - DEBUG - Response for create_subnet : {'subnet': {'id': '9beaeb1b-28b4-48e9-a899-818bc03c2923', 'name': '', 'tenant_id': '989a88e509954ba89cd03bc9f260fbae', 'network_id': '1a327409-6c59-4918-925f-45806d0665e9', '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': '989a88e509954ba89cd03bc9f260fbae'}} 2025-08-06 17:08:16,809 - INFO - Created VN vn2 2025-08-06 17:08:16,828 - DEBUG - VN vn2 UUID is 1a327409-6c59-4918-925f-45806d0665e9 2025-08-06 17:08:16,919 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-96833828: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-96833828: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-08-06 17:08:17,254 - INFO - Attaching policy sec_grp_policy_ctest-random-26746826 to vn vn1 2025-08-06 17:08:17,635 - INFO - Attaching policy sec_grp_policy_ctest-random-26746826 to vn vn2 2025-08-06 17:08:19,225 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3763-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3763-1) 2025-08-06 17:08:20,890 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3763-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3763-2) 2025-08-06 17:08:20,956 - INFO - ================================================================================ 2025-08-06 17:08:20,956 - INFO - STARTING TEST : test_create_md5_on_control 2025-08-06 17:08:20,956 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2025-08-06 17:08:22,262 - DEBUG - Skipping xmpp flap check 2025-08-06 17:08:22,262 - INFO - Initial checks done. Running the testcase now 2025-08-06 17:08:22,262 - INFO - 2025-08-06 17:08:22,262 - INFO - config md5 on each host with auth_data None 2025-08-06 17:08:22,262 - INFO - config per peer with auth_data None 2025-08-06 17:08:22,814 - INFO - configure MD5 on host c9bc89d2-6e8b-422a-bf58-bb437ab43745 2025-08-06 17:08:22,902 - INFO - config per peer with auth_data None 2025-08-06 17:08:23,482 - INFO - configure MD5 on host 71021e13-649d-4f0b-85f3-286996457a96 2025-08-06 17:08:23,566 - INFO - config per peer with auth_data None 2025-08-06 17:08:24,166 - INFO - configure MD5 on host d1627fff-4994-4b7b-a45c-79eb153b143a 2025-08-06 17:08:24,274 - INFO - check if BGP between peers are up before setting md5 2025-08-06 17:08:24,274 - INFO - Check BGP staus between peers 2025-08-06 17:08:24,285 - INFO - setting MD5 config on host c9bc89d2-6e8b-422a-bf58-bb437ab43745 2025-08-06 17:08:24,285 - INFO - configure MD5 on host c9bc89d2-6e8b-422a-bf58-bb437ab43745 2025-08-06 17:08:24,362 - INFO - setting MD5 config on host 71021e13-649d-4f0b-85f3-286996457a96 2025-08-06 17:08:24,362 - INFO - configure MD5 on host 71021e13-649d-4f0b-85f3-286996457a96 2025-08-06 17:08:24,462 - INFO - setting MD5 config on host d1627fff-4994-4b7b-a45c-79eb153b143a 2025-08-06 17:08:24,463 - INFO - configure MD5 on host d1627fff-4994-4b7b-a45c-79eb153b143a 2025-08-06 17:08:24,546 - INFO - check if BGP peering is up after md5 config 2025-08-06 17:08:24,546 - INFO - Check BGP staus between peers 2025-08-06 17:08:24,559 - DEBUG - Check the BGP connection on 10.0.0.254 2025-08-06 17:08:24,559 - DEBUG - Check the BGP connection on 10.0.0.254 2025-08-06 17:08:34,559 - INFO - Check BGP staus between peers 2025-08-06 17:08:34,575 - INFO - remove md5 configured on host c9bc89d2-6e8b-422a-bf58-bb437ab43745 2025-08-06 17:08:34,575 - INFO - config per peer with auth_data None 2025-08-06 17:08:35,151 - INFO - configure MD5 on host c9bc89d2-6e8b-422a-bf58-bb437ab43745 2025-08-06 17:08:35,234 - INFO - remove md5 configured on host 71021e13-649d-4f0b-85f3-286996457a96 2025-08-06 17:08:35,234 - INFO - config per peer with auth_data None 2025-08-06 17:08:35,934 - INFO - configure MD5 on host 71021e13-649d-4f0b-85f3-286996457a96 2025-08-06 17:08:36,038 - INFO - remove md5 configured on host d1627fff-4994-4b7b-a45c-79eb153b143a 2025-08-06 17:08:36,038 - INFO - config per peer with auth_data None 2025-08-06 17:08:36,646 - INFO - configure MD5 on host d1627fff-4994-4b7b-a45c-79eb153b143a 2025-08-06 17:08:36,734 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-08-06 17:08:36,735 - INFO - Deleting VM vn2_vm_0 2025-08-06 17:08:36,735 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-06 17:08:36,735 - ERROR - Nova failed to get host of the VM 2025-08-06 17:08:36,735 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-06 17:08:36,735 - ERROR - Nova failed to get host of the VM 2025-08-06 17:08:36,816 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-08-06 17:08:36,817 - INFO - Deleting VM vn1_vm_0 2025-08-06 17:08:36,817 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-06 17:08:36,817 - ERROR - Nova failed to get host of the VM 2025-08-06 17:08:36,817 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-06 17:08:36,817 - ERROR - Nova failed to get host of the VM 2025-08-06 17:08:36,893 - INFO - Dettaching policy sec_grp_policy_ctest-random-26746826 from vn vn2 2025-08-06 17:08:37,100 - INFO - Dettaching policy sec_grp_policy_ctest-random-26746826 from vn vn1 2025-08-06 17:08:37,358 - INFO - Deleted policy sec_grp_policy_ctest-random-26746826 2025-08-06 17:08:37,359 - INFO - Deleting VN vn2 2025-08-06 17:08:37,431 - DEBUG - VN 1a327409-6c59-4918-925f-45806d0665e9 still in use: Unable to complete operation on network 1a327409-6c59-4918-925f-45806d0665e9. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-83247eae-479a-40de-ab3b-b34a6f130d4c'] 2025-08-06 17:08:37,431 - WARNING - Deleting VN vn2 failed..Will retry 2025-08-06 17:08:39,599 - DEBUG - Response for deleting network () 2025-08-06 17:08:39,599 - INFO - Deleting VN vn1 2025-08-06 17:08:39,782 - DEBUG - Response for deleting network () 2025-08-06 17:08:39,782 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-08-06 17:08:39,984 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-08-06 17:08:40,255 - INFO - Deleted policy allow_all 2025-08-06 17:08:40,255 - INFO - Deleting VM dest_vm 2025-08-06 17:08:40,336 - INFO - Deleting VM source_vm 2025-08-06 17:08:40,403 - INFO - Deleting VN test_vnv6dn 2025-08-06 17:08:40,447 - DEBUG - VN e1f49060-a1eb-45d8-8602-20e377d81ee0 still in use: Unable to complete operation on network e1f49060-a1eb-45d8-8602-20e377d81ee0. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-c07e0a96-c663-4fd3-be7f-246cdff6a938'] 2025-08-06 17:08:40,447 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-08-06 17:08:42,595 - DEBUG - Response for deleting network () 2025-08-06 17:08:42,595 - INFO - Deleting VN test_vnv6sr 2025-08-06 17:08:42,810 - DEBUG - Response for deleting network () 2025-08-06 17:08:44,069 - DEBUG - Skipping xmpp flap check
2025-08-06 17:08:44,069 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:24]
2025-08-06 17:08:44,069 - INFO - -------------------------------------------------------------------------------- 2025-08-06 17:08:44,790 - INFO - Deleted project: ctest-TestMd5testsOnControl-96833828, ID : 989a88e5-0995-4ba8-9cd0-3bc9f260fbae