2025-09-04 04:04:00,604 - INFO - Domain Default found not creating 2025-09-04 04:04:00,775 - INFO - Project ctest-TestMd5testsOnControl-04012076 not found, creating it 2025-09-04 04:04:01,300 - INFO - Created Project:ctest-TestMd5testsOnControl-04012076, ID : fdd5872a-87dc-4549-abcf-e4d2710001f7 2025-09-04 04:04:03,377 - DEBUG - Response for create_network : {'network': {'id': 'a6a0f625-7c13-44c4-b2e9-e13d6271e14f', 'name': 'test_vnv6sr', 'tenant_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'project_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-04012076', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-09-04 04:04:03,625 - DEBUG - Response for create_subnet : {'subnet': {'id': 'd259549a-565c-40c6-b205-f25be8605ce2', 'name': '', 'tenant_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'network_id': 'a6a0f625-7c13-44c4-b2e9-e13d6271e14f', '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': 'fdd5872a87dc4549abcfe4d2710001f7'}} 2025-09-04 04:04:03,879 - DEBUG - Response for create_subnet : {'subnet': {'id': '56ed229b-2957-4f23-8044-224cb65dcaa0', 'name': '', 'tenant_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'network_id': 'a6a0f625-7c13-44c4-b2e9-e13d6271e14f', 'ip_version': 4, 'cidr': '6.106.43.0/26', 'allocation_pools': [{'start': '6.106.43.2', 'end': '6.106.43.62'}], 'gateway_ip': '6.106.43.1', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '6.106.43.2', 'tags': [], 'project_id': 'fdd5872a87dc4549abcfe4d2710001f7'}} 2025-09-04 04:04:03,902 - INFO - Created VN test_vnv6sr 2025-09-04 04:04:03,913 - DEBUG - VN test_vnv6sr UUID is a6a0f625-7c13-44c4-b2e9-e13d6271e14f 2025-09-04 04:04:04,287 - DEBUG - Response for create_network : {'network': {'id': 'c093a389-10f1-49f8-b5c9-4d170a4ba323', 'name': 'test_vnv6dn', 'tenant_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'project_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-04012076', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-09-04 04:04:04,551 - DEBUG - Response for create_subnet : {'subnet': {'id': 'dda81e09-84b0-4951-8a0a-1a417ee82d74', 'name': '', 'tenant_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'network_id': 'c093a389-10f1-49f8-b5c9-4d170a4ba323', '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': 'fdd5872a87dc4549abcfe4d2710001f7'}} 2025-09-04 04:04:04,791 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c8203f18-bfb0-42bc-a929-e7914bfb6592', 'name': '', 'tenant_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'network_id': 'c093a389-10f1-49f8-b5c9-4d170a4ba323', 'ip_version': 4, 'cidr': '42.107.85.128/26', 'allocation_pools': [{'start': '42.107.85.130', 'end': '42.107.85.190'}], 'gateway_ip': '42.107.85.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '42.107.85.130', 'tags': [], 'project_id': 'fdd5872a87dc4549abcfe4d2710001f7'}} 2025-09-04 04:04:04,817 - INFO - Created VN test_vnv6dn 2025-09-04 04:04:04,828 - DEBUG - VN test_vnv6dn UUID is c093a389-10f1-49f8-b5c9-4d170a4ba323 2025-09-04 04:04:04,999 - DEBUG - Services list from nova: [, , , ] 2025-09-04 04:04:06,149 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-1) 2025-09-04 04:04:07,781 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-2) 2025-09-04 04:04:07,781 - INFO - Waiting for VM source_vm to be up.. 2025-09-04 04:04:07,882 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-09-04 04:04:13,020 - DEBUG - VM is in ACTIVE state now 2025-09-04 04:04:13,020 - INFO - VM name : source_vm 2025-09-04 04:04:13,124 - DEBUG - VM source_vm ID is 1cb44528-36f2-493c-8444-7a4775a3c5af 2025-09-04 04:04:13,149 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4012-1 2025-09-04 04:04:13,234 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/1cb44528-36f2-493c-8444-7a4775a3c5af 2025-09-04 04:04:13,535 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/1cb44528-36f2-493c-8444-7a4775a3c5af 2025-09-04 04:04:13,566 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/3dfd2b8f-9bfd-43b2-bd58-d911ccf4858d 2025-09-04 04:04:16,741 - 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 1014ms') 2025-09-04 04:04:16,741 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-09-04 04:04:16,755 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-04012076:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-09-04 04:04:16,756 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-04012076:test_vnv6sr is 6.106.43.1 and allocation pool is NOT set 2025-09-04 04:04:18,822 - 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.93 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.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 = 1.891/5.411/8.931/3.520 ms') 2025-09-04 04:04:18,822 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-09-04 04:04:18,889 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 04:04:18,890 - DEBUG - Waiting to SSH to VM source_vm, IP 6.106.43.3, Port 22 2025-09-04 04:04:18,958 - 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-09-04 04:04:19,046 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-09-04 04:04:24,047 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 04:04:24,048 - DEBUG - Waiting to SSH to VM source_vm, IP 6.106.43.3, Port 22 2025-09-04 04:04:24,216 - DEBUG - VM source_vm is ready for SSH connections 2025-09-04 04:04:24,217 - INFO - Waiting for VM dest_vm to be up.. 2025-09-04 04:04:24,306 - DEBUG - VM is in ACTIVE state now 2025-09-04 04:04:24,306 - INFO - VM name : dest_vm 2025-09-04 04:04:24,405 - DEBUG - VM dest_vm ID is 1e26034c-e31f-4a82-a494-bb8e33d1bfdd 2025-09-04 04:04:24,405 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4012-2 2025-09-04 04:04:24,498 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/1e26034c-e31f-4a82-a494-bb8e33d1bfdd 2025-09-04 04:04:24,508 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/bda70849-5287-4764-8300-63c8e8537486 2025-09-04 04:04:25,667 - 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.31 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.399 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.399/1.854/3.309/1.455 ms') 2025-09-04 04:04:25,667 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-09-04 04:04:25,747 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 04:04:25,747 - DEBUG - Waiting to SSH to VM dest_vm, IP 42.107.85.131, Port 22 2025-09-04 04:04:25,905 - DEBUG - VM dest_vm is ready for SSH connections 2025-09-04 04:04:25,913 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-04012076: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-04012076: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-09-04 04:04:26,272 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-09-04 04:04:26,651 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-09-04 04:04:26,915 - INFO - Configure the policy with allow any 2025-09-04 04:04:27,209 - DEBUG - Response for create_network : {'network': {'id': 'e13ec411-4b4a-4abb-a17e-426fb7d76442', 'name': 'vn1', 'tenant_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'project_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-04012076', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-09-04 04:04:27,498 - DEBUG - Response for create_subnet : {'subnet': {'id': 'eda2d07d-31fb-4f15-bbed-4c5348c0a0f0', 'name': '', 'tenant_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'network_id': 'e13ec411-4b4a-4abb-a17e-426fb7d76442', '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': 'fdd5872a87dc4549abcfe4d2710001f7'}} 2025-09-04 04:04:27,527 - INFO - Created VN vn1 2025-09-04 04:04:27,545 - DEBUG - VN vn1 UUID is e13ec411-4b4a-4abb-a17e-426fb7d76442 2025-09-04 04:04:27,883 - DEBUG - Response for create_network : {'network': {'id': '43a9431c-fe53-4ea0-90e0-02c03624513e', 'name': 'vn2', 'tenant_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'project_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-04012076', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-09-04 04:04:28,103 - DEBUG - Response for create_subnet : {'subnet': {'id': '609cf76f-8536-48d0-a99f-ef5be9ef4996', 'name': '', 'tenant_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'network_id': '43a9431c-fe53-4ea0-90e0-02c03624513e', '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': 'fdd5872a87dc4549abcfe4d2710001f7'}} 2025-09-04 04:04:28,127 - INFO - Created VN vn2 2025-09-04 04:04:28,141 - DEBUG - VN vn2 UUID is 43a9431c-fe53-4ea0-90e0-02c03624513e 2025-09-04 04:04:28,229 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-04012076: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-04012076: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-09-04 04:04:28,571 - INFO - Attaching policy sec_grp_policy_ctest-random-07334559 to vn vn1 2025-09-04 04:04:28,956 - INFO - Attaching policy sec_grp_policy_ctest-random-07334559 to vn vn2 2025-09-04 04:04:30,968 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-1) 2025-09-04 04:04:32,405 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-2) 2025-09-04 04:04:32,468 - INFO - ================================================================================ 2025-09-04 04:04:32,468 - INFO - STARTING TEST : test_check_per_peer_on_control 2025-09-04 04:04:32,468 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2025-09-04 04:04:33,738 - DEBUG - Skipping xmpp flap check 2025-09-04 04:04:33,738 - INFO - Initial checks done. Running the testcase now 2025-09-04 04:04:33,738 - INFO - 2025-09-04 04:04:33,738 - INFO - config per peer with auth_data None 2025-09-04 04:04:34,284 - INFO - configure MD5 on host 0add096f-8aef-4667-a57c-a434db0bb05e 2025-09-04 04:04:34,364 - INFO - config per peer with auth_data None 2025-09-04 04:04:34,948 - INFO - configure MD5 on host f599eb47-c607-4a9b-87ae-e9d1e54c21ec 2025-09-04 04:04:35,028 - INFO - config per peer with auth_data None 2025-09-04 04:04:35,568 - INFO - configure MD5 on host c8d8f8de-11e4-45fa-be20-61673add7ce8 2025-09-04 04:04:35,660 - INFO - check BGP peering is up before setting md5 config 2025-09-04 04:04:35,660 - INFO - Check BGP staus between peers 2025-09-04 04:04:35,671 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2025-09-04 04:04:36,236 - INFO - Check BGP staus between peers 2025-09-04 04:04:36,246 - DEBUG - Check the BGP connection on 10.0.0.57 2025-09-04 04:04:36,247 - DEBUG - Check the BGP connection on 10.0.0.57 2025-09-04 04:04:46,248 - INFO - Check BGP staus between peers 2025-09-04 04:04:46,265 - INFO - remove md5 configured on host 0add096f-8aef-4667-a57c-a434db0bb05e 2025-09-04 04:04:46,265 - INFO - config per peer with auth_data None 2025-09-04 04:04:46,824 - INFO - configure MD5 on host 0add096f-8aef-4667-a57c-a434db0bb05e 2025-09-04 04:04:46,912 - INFO - remove md5 configured on host f599eb47-c607-4a9b-87ae-e9d1e54c21ec 2025-09-04 04:04:46,913 - INFO - config per peer with auth_data None 2025-09-04 04:04:47,516 - INFO - configure MD5 on host f599eb47-c607-4a9b-87ae-e9d1e54c21ec 2025-09-04 04:04:47,608 - INFO - remove md5 configured on host c8d8f8de-11e4-45fa-be20-61673add7ce8 2025-09-04 04:04:47,608 - INFO - config per peer with auth_data None 2025-09-04 04:04:48,168 - INFO - configure MD5 on host c8d8f8de-11e4-45fa-be20-61673add7ce8 2025-09-04 04:04:48,248 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-09-04 04:04:48,249 - INFO - Deleting VM vn2_vm_0 2025-09-04 04:04:48,249 - ERROR - VM vn2_vm_0 has failed to come up 2025-09-04 04:04:48,249 - ERROR - Nova failed to get host of the VM 2025-09-04 04:04:48,249 - ERROR - VM vn2_vm_0 has failed to come up 2025-09-04 04:04:48,249 - ERROR - Nova failed to get host of the VM 2025-09-04 04:04:48,321 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-09-04 04:04:48,321 - INFO - Deleting VM vn1_vm_0 2025-09-04 04:04:48,321 - ERROR - VM vn1_vm_0 has failed to come up 2025-09-04 04:04:48,321 - ERROR - Nova failed to get host of the VM 2025-09-04 04:04:48,321 - ERROR - VM vn1_vm_0 has failed to come up 2025-09-04 04:04:48,321 - ERROR - Nova failed to get host of the VM 2025-09-04 04:04:48,394 - INFO - Dettaching policy sec_grp_policy_ctest-random-07334559 from vn vn2 2025-09-04 04:04:48,542 - INFO - Dettaching policy sec_grp_policy_ctest-random-07334559 from vn vn1 2025-09-04 04:04:48,767 - INFO - Deleted policy sec_grp_policy_ctest-random-07334559 2025-09-04 04:04:48,768 - INFO - Deleting VN vn2 2025-09-04 04:04:48,816 - DEBUG - VN 43a9431c-fe53-4ea0-90e0-02c03624513e still in use: Unable to complete operation on network 43a9431c-fe53-4ea0-90e0-02c03624513e. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-c8db36e4-5099-4dfd-860e-345a912519ec'] 2025-09-04 04:04:48,816 - WARNING - Deleting VN vn2 failed..Will retry 2025-09-04 04:04:50,986 - DEBUG - Response for deleting network () 2025-09-04 04:04:50,986 - INFO - Deleting VN vn1 2025-09-04 04:04:51,174 - DEBUG - Response for deleting network () 2025-09-04 04:04:51,174 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-09-04 04:04:51,372 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-09-04 04:04:51,643 - INFO - Deleted policy allow_all 2025-09-04 04:04:51,644 - INFO - Deleting VM dest_vm 2025-09-04 04:04:51,719 - INFO - Deleting VM source_vm 2025-09-04 04:04:51,799 - INFO - Deleting VN test_vnv6dn 2025-09-04 04:04:51,848 - DEBUG - VN c093a389-10f1-49f8-b5c9-4d170a4ba323 still in use: Unable to complete operation on network c093a389-10f1-49f8-b5c9-4d170a4ba323. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-ee0fe837-4b52-4d25-98af-6f4d2916e0ca'] 2025-09-04 04:04:51,848 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-09-04 04:04:54,083 - DEBUG - Response for deleting network () 2025-09-04 04:04:54,083 - INFO - Deleting VN test_vnv6sr 2025-09-04 04:04:54,324 - DEBUG - Response for deleting network () 2025-09-04 04:04:55,588 - DEBUG - Skipping xmpp flap check
2025-09-04 04:04:55,589 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:23]
2025-09-04 04:04:55,589 - INFO - -------------------------------------------------------------------------------- 2025-09-04 04:04:55,869 - DEBUG - Response for create_network : {'network': {'id': 'bd28fa23-a1cd-437b-b9b4-0b38ceb212b2', 'name': 'test_vnv6sr', 'tenant_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'project_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-04012076', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-09-04 04:04:56,108 - DEBUG - Response for create_subnet : {'subnet': {'id': '4dd2adab-c0dd-4a8f-a145-6411cfe73701', 'name': '', 'tenant_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'network_id': 'bd28fa23-a1cd-437b-b9b4-0b38ceb212b2', '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': 'fdd5872a87dc4549abcfe4d2710001f7'}} 2025-09-04 04:04:56,350 - DEBUG - Response for create_subnet : {'subnet': {'id': '7aa31cb1-37b5-4a3f-a0ee-b8f83e6dff6c', 'name': '', 'tenant_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'network_id': 'bd28fa23-a1cd-437b-b9b4-0b38ceb212b2', 'ip_version': 4, 'cidr': '114.100.127.128/26', 'allocation_pools': [{'start': '114.100.127.130', 'end': '114.100.127.190'}], 'gateway_ip': '114.100.127.129', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '114.100.127.130', 'tags': [], 'project_id': 'fdd5872a87dc4549abcfe4d2710001f7'}} 2025-09-04 04:04:56,381 - INFO - Created VN test_vnv6sr 2025-09-04 04:04:56,393 - DEBUG - VN test_vnv6sr UUID is bd28fa23-a1cd-437b-b9b4-0b38ceb212b2 2025-09-04 04:04:56,852 - DEBUG - Response for create_network : {'network': {'id': '385ae7ba-1cbb-4899-a6c6-9bf46ed139a0', 'name': 'test_vnv6dn', 'tenant_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'project_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-04012076', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-09-04 04:04:57,085 - DEBUG - Response for create_subnet : {'subnet': {'id': '2a0c0b7b-405a-4a4e-a41c-5feafd29300b', 'name': '', 'tenant_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'network_id': '385ae7ba-1cbb-4899-a6c6-9bf46ed139a0', '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': 'fdd5872a87dc4549abcfe4d2710001f7'}} 2025-09-04 04:04:57,340 - DEBUG - Response for create_subnet : {'subnet': {'id': '6c6a54b0-fbd3-48a2-a795-a27d5dc726cf', 'name': '', 'tenant_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'network_id': '385ae7ba-1cbb-4899-a6c6-9bf46ed139a0', 'ip_version': 4, 'cidr': '66.19.21.64/26', 'allocation_pools': [{'start': '66.19.21.66', 'end': '66.19.21.126'}], 'gateway_ip': '66.19.21.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '66.19.21.66', 'tags': [], 'project_id': 'fdd5872a87dc4549abcfe4d2710001f7'}} 2025-09-04 04:04:57,379 - INFO - Created VN test_vnv6dn 2025-09-04 04:04:57,391 - DEBUG - VN test_vnv6dn UUID is 385ae7ba-1cbb-4899-a6c6-9bf46ed139a0 2025-09-04 04:04:58,624 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-1) 2025-09-04 04:05:00,028 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-2) 2025-09-04 04:05:00,029 - INFO - Waiting for VM source_vm to be up.. 2025-09-04 04:05:00,133 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-09-04 04:05:05,227 - DEBUG - VM is in ACTIVE state now 2025-09-04 04:05:05,227 - INFO - VM name : source_vm 2025-09-04 04:05:05,308 - DEBUG - VM source_vm ID is 7396e4a7-5ec1-4f11-acab-9e5d1b292800 2025-09-04 04:05:05,308 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-4012-1 2025-09-04 04:05:05,395 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/7396e4a7-5ec1-4f11-acab-9e5d1b292800 2025-09-04 04:05:05,406 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/861ae8fb-d75a-41ed-ba1e-d8126b36f797 2025-09-04 04:05:08,580 - 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 1022ms') 2025-09-04 04:05:08,580 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-09-04 04:05:08,597 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-04012076:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-09-04 04:05:08,597 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-04012076:test_vnv6sr is 114.100.127.129 and allocation pool is NOT set 2025-09-04 04:05:10,658 - 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.96 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.476 ms\r\n\r\n--- 169.254.0.3 ping statistics ---\r\n2 packets transmitted, 2 received, 0% packet loss, time 1000ms\r\nrtt min/avg/max/mdev = 0.476/2.219/3.963/1.743 ms') 2025-09-04 04:05:10,658 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-09-04 04:05:10,729 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 04:05:10,729 - DEBUG - Waiting to SSH to VM source_vm, IP 114.100.127.131, Port 22 2025-09-04 04:05:10,891 - DEBUG - VM source_vm is ready for SSH connections 2025-09-04 04:05:10,891 - INFO - Waiting for VM dest_vm to be up.. 2025-09-04 04:05:10,976 - DEBUG - VM is in ACTIVE state now 2025-09-04 04:05:10,976 - INFO - VM name : dest_vm 2025-09-04 04:05:11,066 - DEBUG - VM dest_vm ID is 3e22cba1-3d40-41c7-b862-f20dd1e439e9 2025-09-04 04:05:11,066 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-4012-2 2025-09-04 04:05:11,149 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine/3e22cba1-3d40-41c7-b862-f20dd1e439e9 2025-09-04 04:05:11,160 - DEBUG - Requesting: http://10.0.0.131:8082/virtual-machine-interface/62c276b4-5ee1-414f-b4df-2860a17d1101 2025-09-04 04:05:12,328 - 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=11.0 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=3.01 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 = 3.007/6.982/10.957/3.975 ms') 2025-09-04 04:05:12,328 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-09-04 04:05:12,398 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 04:05:12,398 - DEBUG - Waiting to SSH to VM dest_vm, IP 66.19.21.67, Port 22 2025-09-04 04:05:12,463 - 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-09-04 04:05:12,572 - DEBUG - VM dest_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-09-04 04:05:17,573 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-09-04 04:05:17,573 - DEBUG - Waiting to SSH to VM dest_vm, IP 66.19.21.67, Port 22 2025-09-04 04:05:17,737 - DEBUG - VM dest_vm is ready for SSH connections 2025-09-04 04:05:17,744 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-04012076: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-04012076: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-09-04 04:05:18,075 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-09-04 04:05:18,442 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-09-04 04:05:18,759 - INFO - Configure the policy with allow any 2025-09-04 04:05:19,067 - DEBUG - Response for create_network : {'network': {'id': 'c459d7bd-01c6-4984-8085-b4fc444ef01a', 'name': 'vn1', 'tenant_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'project_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-04012076', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-09-04 04:05:19,282 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b84b8944-5618-4712-9e53-776277cae13f', 'name': '', 'tenant_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'network_id': 'c459d7bd-01c6-4984-8085-b4fc444ef01a', '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': 'fdd5872a87dc4549abcfe4d2710001f7'}} 2025-09-04 04:05:19,311 - INFO - Created VN vn1 2025-09-04 04:05:19,324 - DEBUG - VN vn1 UUID is c459d7bd-01c6-4984-8085-b4fc444ef01a 2025-09-04 04:05:19,675 - DEBUG - Response for create_network : {'network': {'id': '495f8e9d-a42b-4a97-818f-b5723842132f', 'name': 'vn2', 'tenant_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'project_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-04012076', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-09-04 04:05:19,904 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e034df22-2edd-4f44-a51a-56addc0a47ee', 'name': '', 'tenant_id': 'fdd5872a87dc4549abcfe4d2710001f7', 'network_id': '495f8e9d-a42b-4a97-818f-b5723842132f', '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': 'fdd5872a87dc4549abcfe4d2710001f7'}} 2025-09-04 04:05:19,924 - INFO - Created VN vn2 2025-09-04 04:05:19,934 - DEBUG - VN vn2 UUID is 495f8e9d-a42b-4a97-818f-b5723842132f 2025-09-04 04:05:20,021 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-04012076: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-04012076: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-09-04 04:05:20,360 - INFO - Attaching policy sec_grp_policy_ctest-random-90958097 to vn vn1 2025-09-04 04:05:20,732 - INFO - Attaching policy sec_grp_policy_ctest-random-90958097 to vn vn2 2025-09-04 04:05:22,222 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-1) 2025-09-04 04:05:23,608 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-4012-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-4012-2) 2025-09-04 04:05:23,672 - INFO - ================================================================================ 2025-09-04 04:05:23,672 - INFO - STARTING TEST : test_create_md5_on_control 2025-09-04 04:05:23,673 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2025-09-04 04:05:24,976 - DEBUG - Skipping xmpp flap check 2025-09-04 04:05:24,976 - INFO - Initial checks done. Running the testcase now 2025-09-04 04:05:24,976 - INFO - 2025-09-04 04:05:24,976 - INFO - config md5 on each host with auth_data None 2025-09-04 04:05:24,977 - INFO - config per peer with auth_data None 2025-09-04 04:05:25,568 - INFO - configure MD5 on host 0add096f-8aef-4667-a57c-a434db0bb05e 2025-09-04 04:05:25,656 - INFO - config per peer with auth_data None 2025-09-04 04:05:26,212 - INFO - configure MD5 on host f599eb47-c607-4a9b-87ae-e9d1e54c21ec 2025-09-04 04:05:26,300 - INFO - config per peer with auth_data None 2025-09-04 04:05:26,880 - INFO - configure MD5 on host c8d8f8de-11e4-45fa-be20-61673add7ce8 2025-09-04 04:05:26,960 - INFO - check if BGP between peers are up before setting md5 2025-09-04 04:05:26,960 - INFO - Check BGP staus between peers 2025-09-04 04:05:26,970 - INFO - setting MD5 config on host 0add096f-8aef-4667-a57c-a434db0bb05e 2025-09-04 04:05:26,970 - INFO - configure MD5 on host 0add096f-8aef-4667-a57c-a434db0bb05e 2025-09-04 04:05:27,044 - INFO - setting MD5 config on host f599eb47-c607-4a9b-87ae-e9d1e54c21ec 2025-09-04 04:05:27,045 - INFO - configure MD5 on host f599eb47-c607-4a9b-87ae-e9d1e54c21ec 2025-09-04 04:05:27,117 - INFO - setting MD5 config on host c8d8f8de-11e4-45fa-be20-61673add7ce8 2025-09-04 04:05:27,117 - INFO - configure MD5 on host c8d8f8de-11e4-45fa-be20-61673add7ce8 2025-09-04 04:05:27,192 - INFO - check if BGP peering is up after md5 config 2025-09-04 04:05:27,193 - INFO - Check BGP staus between peers 2025-09-04 04:05:27,202 - DEBUG - Check the BGP connection on 10.0.0.57 2025-09-04 04:05:27,202 - DEBUG - Check the BGP connection on 10.0.0.57 2025-09-04 04:05:37,202 - INFO - Check BGP staus between peers 2025-09-04 04:05:37,212 - INFO - remove md5 configured on host 0add096f-8aef-4667-a57c-a434db0bb05e 2025-09-04 04:05:37,212 - INFO - config per peer with auth_data None 2025-09-04 04:05:37,741 - INFO - configure MD5 on host 0add096f-8aef-4667-a57c-a434db0bb05e 2025-09-04 04:05:37,820 - INFO - remove md5 configured on host f599eb47-c607-4a9b-87ae-e9d1e54c21ec 2025-09-04 04:05:37,820 - INFO - config per peer with auth_data None 2025-09-04 04:05:38,389 - INFO - configure MD5 on host f599eb47-c607-4a9b-87ae-e9d1e54c21ec 2025-09-04 04:05:38,468 - INFO - remove md5 configured on host c8d8f8de-11e4-45fa-be20-61673add7ce8 2025-09-04 04:05:38,469 - INFO - config per peer with auth_data None 2025-09-04 04:05:39,129 - INFO - configure MD5 on host c8d8f8de-11e4-45fa-be20-61673add7ce8 2025-09-04 04:05:39,236 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-09-04 04:05:39,237 - INFO - Deleting VM vn2_vm_0 2025-09-04 04:05:39,237 - ERROR - VM vn2_vm_0 has failed to come up 2025-09-04 04:05:39,237 - ERROR - Nova failed to get host of the VM 2025-09-04 04:05:39,237 - ERROR - VM vn2_vm_0 has failed to come up 2025-09-04 04:05:39,237 - ERROR - Nova failed to get host of the VM 2025-09-04 04:05:39,334 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-09-04 04:05:39,334 - INFO - Deleting VM vn1_vm_0 2025-09-04 04:05:39,334 - ERROR - VM vn1_vm_0 has failed to come up 2025-09-04 04:05:39,334 - ERROR - Nova failed to get host of the VM 2025-09-04 04:05:39,334 - ERROR - VM vn1_vm_0 has failed to come up 2025-09-04 04:05:39,334 - ERROR - Nova failed to get host of the VM 2025-09-04 04:05:39,425 - INFO - Dettaching policy sec_grp_policy_ctest-random-90958097 from vn vn2 2025-09-04 04:05:39,612 - INFO - Dettaching policy sec_grp_policy_ctest-random-90958097 from vn vn1 2025-09-04 04:05:39,908 - INFO - Deleted policy sec_grp_policy_ctest-random-90958097 2025-09-04 04:05:39,908 - INFO - Deleting VN vn2 2025-09-04 04:05:39,975 - DEBUG - VN 495f8e9d-a42b-4a97-818f-b5723842132f still in use: Unable to complete operation on network 495f8e9d-a42b-4a97-818f-b5723842132f. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-bd172006-fb16-459d-bd69-395a13e077a9'] 2025-09-04 04:05:39,975 - WARNING - Deleting VN vn2 failed..Will retry 2025-09-04 04:05:42,145 - DEBUG - Response for deleting network () 2025-09-04 04:05:42,145 - INFO - Deleting VN vn1 2025-09-04 04:05:42,298 - DEBUG - Response for deleting network () 2025-09-04 04:05:42,298 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-09-04 04:05:42,497 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-09-04 04:05:42,735 - INFO - Deleted policy allow_all 2025-09-04 04:05:42,735 - INFO - Deleting VM dest_vm 2025-09-04 04:05:42,825 - INFO - Deleting VM source_vm 2025-09-04 04:05:42,913 - INFO - Deleting VN test_vnv6dn 2025-09-04 04:05:42,959 - DEBUG - VN 385ae7ba-1cbb-4899-a6c6-9bf46ed139a0 still in use: Unable to complete operation on network 385ae7ba-1cbb-4899-a6c6-9bf46ed139a0. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-96e9bf55-56af-4497-8ad1-51a5c9fda034'] 2025-09-04 04:05:42,959 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-09-04 04:05:45,141 - DEBUG - Response for deleting network () 2025-09-04 04:05:45,142 - INFO - Deleting VN test_vnv6sr 2025-09-04 04:05:45,368 - DEBUG - Response for deleting network () 2025-09-04 04:05:46,625 - DEBUG - Skipping xmpp flap check
2025-09-04 04:05:46,625 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:23]
2025-09-04 04:05:46,625 - INFO - -------------------------------------------------------------------------------- 2025-09-04 04:05:47,347 - INFO - Deleted project: ctest-TestMd5testsOnControl-04012076, ID : fdd5872a-87dc-4549-abcf-e4d2710001f7