2025-08-07 04:24:34,294 - INFO - Domain Default found not creating 2025-08-07 04:24:34,475 - INFO - Project ctest-TestMd5testsOnControl-21700972 not found, creating it 2025-08-07 04:24:35,120 - INFO - Created Project:ctest-TestMd5testsOnControl-21700972, ID : 3ff5128a-9b6c-4982-b84d-4b3639b504c3 2025-08-07 04:24:37,564 - DEBUG - Response for create_network : {'network': {'id': 'dae26cd5-0324-4979-bde7-79bb9ed6e6e8', 'name': 'test_vnv6sr', 'tenant_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'project_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-21700972', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-08-07 04:24:37,920 - DEBUG - Response for create_subnet : {'subnet': {'id': 'e8dc3c64-8897-47c3-91af-f07201e30f1f', 'name': '', 'tenant_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'network_id': 'dae26cd5-0324-4979-bde7-79bb9ed6e6e8', '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': '3ff5128a9b6c4982b84d4b3639b504c3'}} 2025-08-07 04:24:38,142 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b1f5b5f5-8a19-41bb-9109-62ce03fa7b8b', 'name': '', 'tenant_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'network_id': 'dae26cd5-0324-4979-bde7-79bb9ed6e6e8', 'ip_version': 4, 'cidr': '213.191.146.64/26', 'allocation_pools': [{'start': '213.191.146.66', 'end': '213.191.146.126'}], 'gateway_ip': '213.191.146.65', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '213.191.146.66', 'tags': [], 'project_id': '3ff5128a9b6c4982b84d4b3639b504c3'}} 2025-08-07 04:24:38,184 - INFO - Created VN test_vnv6sr 2025-08-07 04:24:38,205 - DEBUG - VN test_vnv6sr UUID is dae26cd5-0324-4979-bde7-79bb9ed6e6e8 2025-08-07 04:24:38,699 - DEBUG - Response for create_network : {'network': {'id': 'a0c669a3-b43d-4c97-a866-29f1cbf851c0', 'name': 'test_vnv6dn', 'tenant_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'project_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-21700972', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-08-07 04:24:39,013 - DEBUG - Response for create_subnet : {'subnet': {'id': 'be71915e-5664-42b0-8ab9-ca2c24805413', 'name': '', 'tenant_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'network_id': 'a0c669a3-b43d-4c97-a866-29f1cbf851c0', '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': '3ff5128a9b6c4982b84d4b3639b504c3'}} 2025-08-07 04:24:39,283 - DEBUG - Response for create_subnet : {'subnet': {'id': 'b03ec3d1-cf70-4aa0-bbad-9c953b20a125', 'name': '', 'tenant_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'network_id': 'a0c669a3-b43d-4c97-a866-29f1cbf851c0', 'ip_version': 4, 'cidr': '208.73.187.192/26', 'allocation_pools': [{'start': '208.73.187.194', 'end': '208.73.187.254'}], 'gateway_ip': '208.73.187.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '208.73.187.194', 'tags': [], 'project_id': '3ff5128a9b6c4982b84d4b3639b504c3'}} 2025-08-07 04:24:39,318 - INFO - Created VN test_vnv6dn 2025-08-07 04:24:39,337 - DEBUG - VN test_vnv6dn UUID is a0c669a3-b43d-4c97-a866-29f1cbf851c0 2025-08-07 04:24:39,598 - DEBUG - Services list from nova: [, , , ] 2025-08-07 04:24:40,813 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3767-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3767-2) 2025-08-07 04:24:42,661 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3767-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3767-1) 2025-08-07 04:24:42,661 - INFO - Waiting for VM source_vm to be up.. 2025-08-07 04:24:42,771 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-07 04:24:47,876 - DEBUG - VM is in ACTIVE state now 2025-08-07 04:24:47,876 - INFO - VM name : source_vm 2025-08-07 04:24:47,976 - DEBUG - VM source_vm ID is 330f4e3a-d50f-4077-8c93-87bf445944d5 2025-08-07 04:24:48,006 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-3767-2 2025-08-07 04:24:48,110 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/330f4e3a-d50f-4077-8c93-87bf445944d5 2025-08-07 04:24:48,433 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/330f4e3a-d50f-4077-8c93-87bf445944d5 2025-08-07 04:24:48,473 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/09cc7a08-b922-49c3-9e15-d7e5486f7dea 2025-08-07 04:24:51,680 - 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-08-07 04:24:51,680 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-08-07 04:24:51,699 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-21700972:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-08-07 04:24:51,700 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-21700972:test_vnv6sr is 213.191.146.65 and allocation pool is NOT set 2025-08-07 04:24:53,764 - 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=4.32 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.16 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.161/2.741/4.321/1.580 ms') 2025-08-07 04:24:53,764 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-08-07 04:24:53,851 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-07 04:24:53,851 - DEBUG - Waiting to SSH to VM source_vm, IP 213.191.146.67, Port 22 2025-08-07 04:24:54,024 - DEBUG - VM source_vm is ready for SSH connections 2025-08-07 04:24:54,025 - INFO - Waiting for VM dest_vm to be up.. 2025-08-07 04:24:54,141 - DEBUG - VM is in ACTIVE state now 2025-08-07 04:24:54,142 - INFO - VM name : dest_vm 2025-08-07 04:24:54,261 - DEBUG - VM dest_vm ID is 905b9a7d-6d06-4196-a6cb-1ff074658e35 2025-08-07 04:24:54,261 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-3767-1 2025-08-07 04:24:54,364 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/905b9a7d-6d06-4196-a6cb-1ff074658e35 2025-08-07 04:24:54,378 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/535b1667-a2fb-468f-873f-d70e58b3f0d4 2025-08-07 04:24:55,578 - 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.18 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=1.87 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.866/4.025/6.184/2.159 ms') 2025-08-07 04:24:55,579 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-08-07 04:24:55,659 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-07 04:24:55,659 - DEBUG - Waiting to SSH to VM dest_vm, IP 208.73.187.195, Port 22 2025-08-07 04:24:55,830 - DEBUG - VM dest_vm is ready for SSH connections 2025-08-07 04:24:55,838 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-21700972: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-21700972: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-07 04:24:56,217 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-08-07 04:24:56,631 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-08-07 04:24:56,955 - INFO - Configure the policy with allow any 2025-08-07 04:24:57,371 - DEBUG - Response for create_network : {'network': {'id': '20bd2573-e622-461b-afde-ade65f21fd89', 'name': 'vn1', 'tenant_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'project_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-21700972', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-08-07 04:24:57,621 - DEBUG - Response for create_subnet : {'subnet': {'id': '277fff1e-2f2d-41f9-a50e-582a856a9674', 'name': '', 'tenant_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'network_id': '20bd2573-e622-461b-afde-ade65f21fd89', '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': '3ff5128a9b6c4982b84d4b3639b504c3'}} 2025-08-07 04:24:57,653 - INFO - Created VN vn1 2025-08-07 04:24:57,673 - DEBUG - VN vn1 UUID is 20bd2573-e622-461b-afde-ade65f21fd89 2025-08-07 04:24:58,106 - DEBUG - Response for create_network : {'network': {'id': 'f5da1281-974b-4fb8-b39e-a1b4fba33c95', 'name': 'vn2', 'tenant_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'project_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-21700972', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-08-07 04:24:58,416 - DEBUG - Response for create_subnet : {'subnet': {'id': '75b39b49-cf9f-4cca-90fa-ea20a30f8c6b', 'name': '', 'tenant_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'network_id': 'f5da1281-974b-4fb8-b39e-a1b4fba33c95', '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': '3ff5128a9b6c4982b84d4b3639b504c3'}} 2025-08-07 04:24:58,443 - INFO - Created VN vn2 2025-08-07 04:24:58,462 - DEBUG - VN vn2 UUID is f5da1281-974b-4fb8-b39e-a1b4fba33c95 2025-08-07 04:24:58,552 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-21700972: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-21700972: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-07 04:24:58,902 - INFO - Attaching policy sec_grp_policy_ctest-random-98048772 to vn vn1 2025-08-07 04:24:59,307 - INFO - Attaching policy sec_grp_policy_ctest-random-98048772 to vn vn2 2025-08-07 04:25:01,245 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3767-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3767-2) 2025-08-07 04:25:02,968 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3767-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3767-1) 2025-08-07 04:25:03,041 - INFO - ================================================================================ 2025-08-07 04:25:03,041 - INFO - STARTING TEST : test_check_per_peer_on_control 2025-08-07 04:25:03,041 - INFO - TEST DESCRIPTION : Description: Verify per peer md5 and specific protocol on all ports and policy with allow all between VN's 2025-08-07 04:25:04,412 - DEBUG - Skipping xmpp flap check 2025-08-07 04:25:04,412 - INFO - Initial checks done. Running the testcase now 2025-08-07 04:25:04,413 - INFO - 2025-08-07 04:25:04,413 - INFO - config per peer with auth_data None 2025-08-07 04:25:05,215 - INFO - configure MD5 on host e3eeb952-37e1-414d-94e1-c813d29e5ab4 2025-08-07 04:25:05,460 - INFO - config per peer with auth_data None 2025-08-07 04:25:06,550 - INFO - configure MD5 on host e0ec2349-d465-4757-8276-e92ef8212c46 2025-08-07 04:25:06,662 - INFO - config per peer with auth_data None 2025-08-07 04:25:07,454 - INFO - configure MD5 on host 6c4497f7-b708-4923-836b-298dc355827b 2025-08-07 04:25:07,574 - INFO - check BGP peering is up before setting md5 config 2025-08-07 04:25:07,574 - INFO - Check BGP staus between peers 2025-08-07 04:25:07,606 - INFO - config per peer with auth_data {'key_items': [{'key': 'juniper', 'key_id': 0}], 'key_type': 'md5'} 2025-08-07 04:25:08,423 - INFO - Check BGP staus between peers 2025-08-07 04:25:08,450 - DEBUG - Check the BGP connection on 10.0.0.56 2025-08-07 04:25:08,450 - DEBUG - Check the BGP connection on 10.0.0.56 2025-08-07 04:25:18,452 - INFO - Check BGP staus between peers 2025-08-07 04:25:18,468 - INFO - remove md5 configured on host e3eeb952-37e1-414d-94e1-c813d29e5ab4 2025-08-07 04:25:18,468 - INFO - config per peer with auth_data None 2025-08-07 04:25:19,166 - INFO - configure MD5 on host e3eeb952-37e1-414d-94e1-c813d29e5ab4 2025-08-07 04:25:19,270 - INFO - remove md5 configured on host e0ec2349-d465-4757-8276-e92ef8212c46 2025-08-07 04:25:19,270 - INFO - config per peer with auth_data None 2025-08-07 04:25:19,992 - INFO - configure MD5 on host e0ec2349-d465-4757-8276-e92ef8212c46 2025-08-07 04:25:20,110 - INFO - remove md5 configured on host 6c4497f7-b708-4923-836b-298dc355827b 2025-08-07 04:25:20,110 - INFO - config per peer with auth_data None 2025-08-07 04:25:20,862 - INFO - configure MD5 on host 6c4497f7-b708-4923-836b-298dc355827b 2025-08-07 04:25:20,974 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-08-07 04:25:20,974 - INFO - Deleting VM vn2_vm_0 2025-08-07 04:25:20,974 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-07 04:25:20,974 - ERROR - Nova failed to get host of the VM 2025-08-07 04:25:20,974 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-07 04:25:20,974 - ERROR - Nova failed to get host of the VM 2025-08-07 04:25:21,063 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-08-07 04:25:21,063 - INFO - Deleting VM vn1_vm_0 2025-08-07 04:25:21,063 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-07 04:25:21,063 - ERROR - Nova failed to get host of the VM 2025-08-07 04:25:21,063 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-07 04:25:21,064 - ERROR - Nova failed to get host of the VM 2025-08-07 04:25:21,144 - INFO - Dettaching policy sec_grp_policy_ctest-random-98048772 from vn vn2 2025-08-07 04:25:21,350 - INFO - Dettaching policy sec_grp_policy_ctest-random-98048772 from vn vn1 2025-08-07 04:25:21,665 - INFO - Deleted policy sec_grp_policy_ctest-random-98048772 2025-08-07 04:25:21,665 - INFO - Deleting VN vn2 2025-08-07 04:25:21,722 - DEBUG - VN f5da1281-974b-4fb8-b39e-a1b4fba33c95 still in use: Unable to complete operation on network f5da1281-974b-4fb8-b39e-a1b4fba33c95. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-27d38621-70c4-475b-b05f-3d8e242832a9'] 2025-08-07 04:25:21,722 - WARNING - Deleting VN vn2 failed..Will retry 2025-08-07 04:25:24,031 - DEBUG - Response for deleting network () 2025-08-07 04:25:24,031 - INFO - Deleting VN vn1 2025-08-07 04:25:24,268 - DEBUG - Response for deleting network () 2025-08-07 04:25:24,268 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-08-07 04:25:24,622 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-08-07 04:25:25,032 - INFO - Deleted policy allow_all 2025-08-07 04:25:25,032 - INFO - Deleting VM dest_vm 2025-08-07 04:25:25,163 - INFO - Deleting VM source_vm 2025-08-07 04:25:25,270 - INFO - Deleting VN test_vnv6dn 2025-08-07 04:25:25,357 - DEBUG - VN a0c669a3-b43d-4c97-a866-29f1cbf851c0 still in use: Unable to complete operation on network a0c669a3-b43d-4c97-a866-29f1cbf851c0. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-93f56e21-8653-4cc7-a524-2762c9aebe0b'] 2025-08-07 04:25:25,357 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-08-07 04:25:27,671 - DEBUG - Response for deleting network () 2025-08-07 04:25:27,671 - INFO - Deleting VN test_vnv6sr 2025-08-07 04:25:28,018 - DEBUG - Response for deleting network () 2025-08-07 04:25:29,330 - DEBUG - Skipping xmpp flap check
2025-08-07 04:25:29,330 - INFO - END TEST : test_check_per_peer_on_control : PASSED[0:00:26]
2025-08-07 04:25:29,330 - INFO - -------------------------------------------------------------------------------- 2025-08-07 04:25:29,687 - DEBUG - Response for create_network : {'network': {'id': 'ade03eb4-6d2e-4344-bf59-c6330c9f491a', 'name': 'test_vnv6sr', 'tenant_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'project_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-21700972', 'test_vnv6sr'], 'port_security_enabled': True, 'description': ''}} 2025-08-07 04:25:30,016 - DEBUG - Response for create_subnet : {'subnet': {'id': '75489a5d-2e5a-493c-b2f2-7962041e8cae', 'name': '', 'tenant_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'network_id': 'ade03eb4-6d2e-4344-bf59-c6330c9f491a', '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': '3ff5128a9b6c4982b84d4b3639b504c3'}} 2025-08-07 04:25:30,285 - DEBUG - Response for create_subnet : {'subnet': {'id': 'c1763e79-d1cf-476d-a07e-9b474981c0a2', 'name': '', 'tenant_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'network_id': 'ade03eb4-6d2e-4344-bf59-c6330c9f491a', 'ip_version': 4, 'cidr': '94.198.247.192/26', 'allocation_pools': [{'start': '94.198.247.194', 'end': '94.198.247.254'}], 'gateway_ip': '94.198.247.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '94.198.247.194', 'tags': [], 'project_id': '3ff5128a9b6c4982b84d4b3639b504c3'}} 2025-08-07 04:25:30,324 - INFO - Created VN test_vnv6sr 2025-08-07 04:25:30,343 - DEBUG - VN test_vnv6sr UUID is ade03eb4-6d2e-4344-bf59-c6330c9f491a 2025-08-07 04:25:30,808 - DEBUG - Response for create_network : {'network': {'id': 'ff2d92de-86f8-4de9-8881-624c23500890', 'name': 'test_vnv6dn', 'tenant_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'project_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-21700972', 'test_vnv6dn'], 'port_security_enabled': True, 'description': ''}} 2025-08-07 04:25:31,098 - DEBUG - Response for create_subnet : {'subnet': {'id': '18103215-0d51-4cda-8282-52754b476638', 'name': '', 'tenant_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'network_id': 'ff2d92de-86f8-4de9-8881-624c23500890', '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': '3ff5128a9b6c4982b84d4b3639b504c3'}} 2025-08-07 04:25:31,319 - DEBUG - Response for create_subnet : {'subnet': {'id': '42e58598-58f7-43f5-9e6b-f657b6454e6f', 'name': '', 'tenant_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'network_id': 'ff2d92de-86f8-4de9-8881-624c23500890', 'ip_version': 4, 'cidr': '85.248.108.192/26', 'allocation_pools': [{'start': '85.248.108.194', 'end': '85.248.108.254'}], 'gateway_ip': '85.248.108.193', 'enable_dhcp': True, 'ipv6_ra_mode': None, 'ipv6_address_mode': None, 'dns_nameservers': [], 'host_routes': [], 'dns_server_address': '85.248.108.194', 'tags': [], 'project_id': '3ff5128a9b6c4982b84d4b3639b504c3'}} 2025-08-07 04:25:31,348 - INFO - Created VN test_vnv6dn 2025-08-07 04:25:31,362 - DEBUG - VN test_vnv6dn UUID is ff2d92de-86f8-4de9-8881-624c23500890 2025-08-07 04:25:32,780 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3767-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3767-2) 2025-08-07 04:25:34,513 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3767-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3767-1) 2025-08-07 04:25:34,513 - INFO - Waiting for VM source_vm to be up.. 2025-08-07 04:25:34,637 - DEBUG - VM is still in BUILD state, Expected: ACTIVE 2025-08-07 04:25:39,738 - DEBUG - VM is in ACTIVE state now 2025-08-07 04:25:39,738 - INFO - VM name : source_vm 2025-08-07 04:25:39,840 - DEBUG - VM source_vm ID is d15bf4e2-0298-4bf4-b010-cefe1f8a747e 2025-08-07 04:25:39,840 - DEBUG - VM source_vm launched on Node an-jenkins-deploy-platform-ansible-os-3767-2 2025-08-07 04:25:39,946 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/d15bf4e2-0298-4bf4-b010-cefe1f8a747e 2025-08-07 04:25:39,964 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/b1df83b9-471b-47fa-958a-5bb04e98b1b8 2025-08-07 04:25:43,171 - 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 1011ms') 2025-08-07 04:25:43,171 - DEBUG - Ping to Metadata IP 169.254.0.3 of VM source_vm failed! 2025-08-07 04:25:43,190 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-21700972:test_vnv6sr is 2001::101:1 and allocation pool is NOT set 2025-08-07 04:25:43,190 - DEBUG - Gateway for vn default-domain:ctest-TestMd5testsOnControl-21700972:test_vnv6sr is 94.198.247.193 and allocation pool is NOT set 2025-08-07 04:25:45,261 - 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.74 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.840 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.840/4.791/8.742/3.951 ms') 2025-08-07 04:25:45,261 - INFO - Ping to Metadata IP 169.254.0.3 of VM source_vm passed 2025-08-07 04:25:45,341 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-07 04:25:45,341 - DEBUG - Waiting to SSH to VM source_vm, IP 94.198.247.195, Port 22 2025-08-07 04:25:45,408 - 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-08-07 04:25:45,521 - DEBUG - VM source_vm is NOT ready for SSH connections, VM status: ACTIVE 2025-08-07 04:25:50,523 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-07 04:25:50,523 - DEBUG - Waiting to SSH to VM source_vm, IP 94.198.247.195, Port 22 2025-08-07 04:25:50,681 - DEBUG - VM source_vm is ready for SSH connections 2025-08-07 04:25:50,681 - INFO - Waiting for VM dest_vm to be up.. 2025-08-07 04:25:50,791 - DEBUG - VM is in ACTIVE state now 2025-08-07 04:25:50,791 - INFO - VM name : dest_vm 2025-08-07 04:25:50,885 - DEBUG - VM dest_vm ID is 8c1fec20-4d0d-49da-81a4-7bff83626aa8 2025-08-07 04:25:50,885 - DEBUG - VM dest_vm launched on Node an-jenkins-deploy-platform-ansible-os-3767-1 2025-08-07 04:25:50,975 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine/8c1fec20-4d0d-49da-81a4-7bff83626aa8 2025-08-07 04:25:50,989 - DEBUG - Requesting: http://10.0.0.249:8082/virtual-machine-interface/dde7a025-c844-4fc3-bef0-d0ece933fb74 2025-08-07 04:25:52,182 - 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.60 ms\r\n64 bytes from 169.254.0.3: icmp_seq=2 ttl=63 time=0.461 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.461/2.028/3.596/1.567 ms') 2025-08-07 04:25:52,183 - INFO - Ping to Metadata IP 169.254.0.3 of VM dest_vm passed 2025-08-07 04:25:52,262 - DEBUG - verify_vm_launched is skipped since self.vm_launch_flag is set 2025-08-07 04:25:52,262 - DEBUG - Waiting to SSH to VM dest_vm, IP 85.248.108.195, Port 22 2025-08-07 04:25:52,437 - DEBUG - VM dest_vm is ready for SSH connections 2025-08-07 04:25:52,445 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-21700972: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-21700972: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-07 04:25:52,818 - INFO - Attaching policy allow_all to vn test_vnv6sr 2025-08-07 04:25:53,248 - INFO - Attaching policy allow_all to vn test_vnv6dn 2025-08-07 04:25:53,560 - INFO - Configure the policy with allow any 2025-08-07 04:25:53,937 - DEBUG - Response for create_network : {'network': {'id': 'db28c422-b6c1-4763-9444-1187a60e8055', 'name': 'vn1', 'tenant_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'project_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-21700972', 'vn1'], 'port_security_enabled': True, 'description': ''}} 2025-08-07 04:25:54,239 - DEBUG - Response for create_subnet : {'subnet': {'id': '6e127eee-9f4b-4624-8e98-dc20f0df7973', 'name': '', 'tenant_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'network_id': 'db28c422-b6c1-4763-9444-1187a60e8055', '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': '3ff5128a9b6c4982b84d4b3639b504c3'}} 2025-08-07 04:25:54,265 - INFO - Created VN vn1 2025-08-07 04:25:54,298 - DEBUG - VN vn1 UUID is db28c422-b6c1-4763-9444-1187a60e8055 2025-08-07 04:25:54,769 - DEBUG - Response for create_network : {'network': {'id': '14c9cb56-05ad-4d4b-80e3-36921ad2cc66', 'name': 'vn2', 'tenant_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'project_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'admin_state_up': True, 'shared': False, 'status': 'ACTIVE', 'router:external': False, 'mtu': 0, 'subnets': [], 'fq_name': ['default-domain', 'ctest-TestMd5testsOnControl-21700972', 'vn2'], 'port_security_enabled': True, 'description': ''}} 2025-08-07 04:25:55,033 - DEBUG - Response for create_subnet : {'subnet': {'id': '5f78db58-48cf-4cc0-b785-ac7b67f9019b', 'name': '', 'tenant_id': '3ff5128a9b6c4982b84d4b3639b504c3', 'network_id': '14c9cb56-05ad-4d4b-80e3-36921ad2cc66', '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': '3ff5128a9b6c4982b84d4b3639b504c3'}} 2025-08-07 04:25:55,063 - INFO - Created VN vn2 2025-08-07 04:25:55,080 - DEBUG - VN vn2 UUID is 14c9cb56-05ad-4d4b-80e3-36921ad2cc66 2025-08-07 04:25:55,189 - DEBUG - Policy np_rules : [rule_sequence = None, rule_uuid = None, direction = <>, protocol = any, src_addresses = [subnet = None, virtual_network = default-domain:ctest-TestMd5testsOnControl-21700972: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-21700972: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-07 04:25:55,555 - INFO - Attaching policy sec_grp_policy_ctest-random-89902658 to vn vn1 2025-08-07 04:25:55,988 - INFO - Attaching policy sec_grp_policy_ctest-random-89902658 to vn vn2 2025-08-07 04:25:57,709 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3767-2), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3767-2) 2025-08-07 04:25:59,455 - INFO - VM ([]) created on node: (an-jenkins-deploy-platform-ansible-os-3767-1), Zone: (nova:an-jenkins-deploy-platform-ansible-os-3767-1) 2025-08-07 04:25:59,528 - INFO - ================================================================================ 2025-08-07 04:25:59,528 - INFO - STARTING TEST : test_create_md5_on_control 2025-08-07 04:25:59,528 - INFO - TEST DESCRIPTION : Description: Verify md5 with allow specific protocol on all ports and policy with allow all between VN's 2025-08-07 04:26:00,858 - DEBUG - Skipping xmpp flap check 2025-08-07 04:26:00,858 - INFO - Initial checks done. Running the testcase now 2025-08-07 04:26:00,858 - INFO - 2025-08-07 04:26:00,859 - INFO - config md5 on each host with auth_data None 2025-08-07 04:26:00,859 - INFO - config per peer with auth_data None 2025-08-07 04:26:01,638 - INFO - configure MD5 on host e3eeb952-37e1-414d-94e1-c813d29e5ab4 2025-08-07 04:26:01,780 - INFO - config per peer with auth_data None 2025-08-07 04:26:02,546 - INFO - configure MD5 on host e0ec2349-d465-4757-8276-e92ef8212c46 2025-08-07 04:26:02,654 - INFO - config per peer with auth_data None 2025-08-07 04:26:03,406 - INFO - configure MD5 on host 6c4497f7-b708-4923-836b-298dc355827b 2025-08-07 04:26:03,527 - INFO - check if BGP between peers are up before setting md5 2025-08-07 04:26:03,527 - INFO - Check BGP staus between peers 2025-08-07 04:26:03,551 - INFO - setting MD5 config on host e3eeb952-37e1-414d-94e1-c813d29e5ab4 2025-08-07 04:26:03,551 - INFO - configure MD5 on host e3eeb952-37e1-414d-94e1-c813d29e5ab4 2025-08-07 04:26:03,670 - INFO - setting MD5 config on host e0ec2349-d465-4757-8276-e92ef8212c46 2025-08-07 04:26:03,670 - INFO - configure MD5 on host e0ec2349-d465-4757-8276-e92ef8212c46 2025-08-07 04:26:03,758 - INFO - setting MD5 config on host 6c4497f7-b708-4923-836b-298dc355827b 2025-08-07 04:26:03,758 - INFO - configure MD5 on host 6c4497f7-b708-4923-836b-298dc355827b 2025-08-07 04:26:03,846 - INFO - check if BGP peering is up after md5 config 2025-08-07 04:26:03,846 - INFO - Check BGP staus between peers 2025-08-07 04:26:03,863 - DEBUG - Check the BGP connection on 10.0.0.56 2025-08-07 04:26:03,863 - DEBUG - Check the BGP connection on 10.0.0.56 2025-08-07 04:26:13,864 - INFO - Check BGP staus between peers 2025-08-07 04:26:13,882 - INFO - remove md5 configured on host e3eeb952-37e1-414d-94e1-c813d29e5ab4 2025-08-07 04:26:13,883 - INFO - config per peer with auth_data None 2025-08-07 04:26:14,558 - INFO - configure MD5 on host e3eeb952-37e1-414d-94e1-c813d29e5ab4 2025-08-07 04:26:14,666 - INFO - remove md5 configured on host e0ec2349-d465-4757-8276-e92ef8212c46 2025-08-07 04:26:14,668 - INFO - config per peer with auth_data None 2025-08-07 04:26:15,534 - INFO - configure MD5 on host e0ec2349-d465-4757-8276-e92ef8212c46 2025-08-07 04:26:15,639 - INFO - remove md5 configured on host 6c4497f7-b708-4923-836b-298dc355827b 2025-08-07 04:26:15,639 - INFO - config per peer with auth_data None 2025-08-07 04:26:16,362 - INFO - configure MD5 on host 6c4497f7-b708-4923-836b-298dc355827b 2025-08-07 04:26:16,458 - INFO - Skip interface_detach for VM vn2_vm_0 in state BUILD 2025-08-07 04:26:16,458 - INFO - Deleting VM vn2_vm_0 2025-08-07 04:26:16,458 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-07 04:26:16,459 - ERROR - Nova failed to get host of the VM 2025-08-07 04:26:16,459 - ERROR - VM vn2_vm_0 has failed to come up 2025-08-07 04:26:16,459 - ERROR - Nova failed to get host of the VM 2025-08-07 04:26:16,555 - INFO - Skip interface_detach for VM vn1_vm_0 in state BUILD 2025-08-07 04:26:16,555 - INFO - Deleting VM vn1_vm_0 2025-08-07 04:26:16,556 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-07 04:26:16,556 - ERROR - Nova failed to get host of the VM 2025-08-07 04:26:16,556 - ERROR - VM vn1_vm_0 has failed to come up 2025-08-07 04:26:16,556 - ERROR - Nova failed to get host of the VM 2025-08-07 04:26:16,638 - INFO - Dettaching policy sec_grp_policy_ctest-random-89902658 from vn vn2 2025-08-07 04:26:16,834 - INFO - Dettaching policy sec_grp_policy_ctest-random-89902658 from vn vn1 2025-08-07 04:26:17,137 - INFO - Deleted policy sec_grp_policy_ctest-random-89902658 2025-08-07 04:26:17,137 - INFO - Deleting VN vn2 2025-08-07 04:26:17,223 - DEBUG - VN 14c9cb56-05ad-4d4b-80e3-36921ad2cc66 still in use: Unable to complete operation on network 14c9cb56-05ad-4d4b-80e3-36921ad2cc66. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-72aa2b2c-d48d-40f9-9e8f-c6af0971e2c9'] 2025-08-07 04:26:17,223 - WARNING - Deleting VN vn2 failed..Will retry 2025-08-07 04:26:19,417 - DEBUG - Response for deleting network () 2025-08-07 04:26:19,417 - INFO - Deleting VN vn1 2025-08-07 04:26:19,627 - DEBUG - Response for deleting network () 2025-08-07 04:26:19,627 - INFO - Dettaching policy allow_all from vn test_vnv6dn 2025-08-07 04:26:19,875 - INFO - Dettaching policy allow_all from vn test_vnv6sr 2025-08-07 04:26:20,185 - INFO - Deleted policy allow_all 2025-08-07 04:26:20,186 - INFO - Deleting VM dest_vm 2025-08-07 04:26:20,279 - INFO - Deleting VM source_vm 2025-08-07 04:26:20,400 - INFO - Deleting VN test_vnv6dn 2025-08-07 04:26:20,456 - DEBUG - VN ff2d92de-86f8-4de9-8881-624c23500890 still in use: Unable to complete operation on network ff2d92de-86f8-4de9-8881-624c23500890. There are one or more ports still in use on the network. Neutron server returns request_ids: ['req-567d680f-d4f7-4d43-b3bc-93a5a3ed3e3a'] 2025-08-07 04:26:20,456 - WARNING - Deleting VN test_vnv6dn failed..Will retry 2025-08-07 04:26:22,714 - DEBUG - Response for deleting network () 2025-08-07 04:26:22,714 - INFO - Deleting VN test_vnv6sr 2025-08-07 04:26:22,959 - DEBUG - Response for deleting network () 2025-08-07 04:26:24,263 - DEBUG - Skipping xmpp flap check
2025-08-07 04:26:24,263 - INFO - END TEST : test_create_md5_on_control : PASSED[0:00:25]
2025-08-07 04:26:24,263 - INFO - -------------------------------------------------------------------------------- 2025-08-07 04:26:25,258 - INFO - Deleted project: ctest-TestMd5testsOnControl-21700972, ID : 3ff5128a-9b6c-4982-b84d-4b3639b504c3